builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0147 starttime: 1490802726.64 results: success (0) buildid: 20170329071901 builduid: 09c1e77f1ad74e2a87e3c5275690f682 revision: 6ea713ccc9abea93126423fefb855d0e051c95e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:06.645167) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:06.645643) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:06.645917) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:06.673765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:06.674205) ========= 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.iDas4Rev0J/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.Av09v9Kr6G/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-03-29 08:52:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 179M=0s 2017-03-29 08:52:06 (179 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091815 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:06.790849) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:06.791232) ========= 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.iDas4Rev0J/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.Av09v9Kr6G/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.026759 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:06.854640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 08:52:06.855034) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6ea713ccc9abea93126423fefb855d0e051c95e2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6ea713ccc9abea93126423fefb855d0e051c95e2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iDas4Rev0J/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.Av09v9Kr6G/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-03-29 08:52:06,953 truncating revision to first 12 chars 2017-03-29 08:52:06,953 Setting DEBUG logging. 2017-03-29 08:52:06,953 attempt 1/10 2017-03-29 08:52:06,953 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6ea713ccc9ab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-29 08:52:08,130 unpacking tar archive at: mozilla-central-6ea713ccc9ab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.441733 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 08:52:08.323011) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:08.323556) ========= script_repo_revision: 6ea713ccc9abea93126423fefb855d0e051c95e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:08.324116) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:08.324556) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 08:52:08.345968) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-29 08:52:08.346588) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iDas4Rev0J/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.Av09v9Kr6G/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:52:08 INFO - MultiFileLogger online at 20170329 08:52:08 in /builds/slave/test 08:52:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:52:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:52:08 INFO - {'append_to_log': False, 08:52:08 INFO - 'base_work_dir': '/builds/slave/test', 08:52:08 INFO - 'blob_upload_branch': 'Firefox', 08:52:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:52:08 INFO - 'branch': 'Firefox', 08:52:08 INFO - 'buildbot_json_path': 'buildprops.json', 08:52:08 INFO - 'config_files': ('talos/mac_config.py',), 08:52:08 INFO - 'default_actions': ('clobber', 08:52:08 INFO - 'read-buildbot-config', 08:52:08 INFO - 'download-and-extract', 08:52:08 INFO - 'populate-webroot', 08:52:08 INFO - 'create-virtualenv', 08:52:08 INFO - 'install', 08:52:08 INFO - 'run-tests'), 08:52:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:52:08 INFO - 'download_minidump_stackwalk': True, 08:52:08 INFO - 'download_symbols': 'ondemand', 08:52:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:52:08 INFO - 'tooltool.py': '/tools/tooltool.py', 08:52:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:52:08 INFO - '/tools/misc-python/virtualenv.py')}, 08:52:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:52:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:52:08 INFO - 'gecko_profile': False, 08:52:08 INFO - 'gecko_profile_interval': 0, 08:52:08 INFO - 'installer_path': 'installer.exe', 08:52:08 INFO - 'log_level': 'info', 08:52:08 INFO - 'log_name': 'talos', 08:52:08 INFO - 'log_to_console': True, 08:52:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:52:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:52:08 INFO - 'opt_config_files': (), 08:52:08 INFO - 'pip_index': False, 08:52:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:08 INFO - 'cmd': ('bash', 08:52:08 INFO - '-c', 08:52:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:08 INFO - 'enabled': True, 08:52:08 INFO - 'halt_on_failure': False, 08:52:08 INFO - 'name': 'check_screen_resolution'},), 08:52:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:52:08 INFO - 'cmd': ('bash', 08:52:08 INFO - '-c', 08:52:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:52:08 INFO - 'enabled': True, 08:52:08 INFO - 'halt_on_failure': False, 08:52:08 INFO - 'name': 'check_screen_resolution'},), 08:52:08 INFO - 'run_cmd_checks_enabled': True, 08:52:08 INFO - 'suite': 'dromaeojs', 08:52:08 INFO - 'system_bits': '32', 08:52:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:52:08 INFO - 'title': 't-yosemite-r7-0147', 08:52:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:52:08 INFO - 'use_talos_json': True, 08:52:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:52:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:52:08 INFO - 'work_dir': 'build'} 08:52:08 INFO - [mozharness: 2017-03-29 15:52:08.557791Z] Running clobber step. 08:52:08 INFO - Running pre-action listener: _resource_record_pre_action 08:52:08 INFO - Running main action method: clobber 08:52:08 INFO - rmtree: /builds/slave/test/build 08:52:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:52:11 INFO - Running post-action listener: _resource_record_post_action 08:52:11 INFO - [mozharness: 2017-03-29 15:52:11.113251Z] Finished clobber step (success) 08:52:11 INFO - [mozharness: 2017-03-29 15:52:11.113381Z] Running read-buildbot-config step. 08:52:11 INFO - Running pre-action listener: _resource_record_pre_action 08:52:11 INFO - Running main action method: read_buildbot_config 08:52:11 INFO - Using buildbot properties: 08:52:11 INFO - { 08:52:11 INFO - "project": "", 08:52:11 INFO - "product": "firefox", 08:52:11 INFO - "script_repo_revision": "production", 08:52:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:52:11 INFO - "repository": "", 08:52:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:52:11 INFO - "buildid": "20170329071901", 08:52:11 INFO - "stage_platform": "macosx64", 08:52:11 INFO - "basedir": "/builds/slave/test", 08:52:11 INFO - "nightly_build": "True", 08:52:11 INFO - "buildnumber": 3, 08:52:11 INFO - "slavename": "t-yosemite-r7-0147", 08:52:11 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:52:11 INFO - "platform": "yosemite_r7", 08:52:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:52:11 INFO - "branch": "mozilla-central", 08:52:11 INFO - "repo_path": "mozilla-central", 08:52:11 INFO - "revision": "6ea713ccc9abea93126423fefb855d0e051c95e2", 08:52:11 INFO - "pgo_build": "False", 08:52:11 INFO - "builduid": "09c1e77f1ad74e2a87e3c5275690f682", 08:52:11 INFO - "slavebuilddir": "test" 08:52:11 INFO - } 08:52:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 08:52:11 INFO - Running post-action listener: _resource_record_post_action 08:52:11 INFO - [mozharness: 2017-03-29 15:52:11.116859Z] Finished read-buildbot-config step (success) 08:52:11 INFO - [mozharness: 2017-03-29 15:52:11.116983Z] Running download-and-extract step. 08:52:11 INFO - Running pre-action listener: _resource_record_pre_action 08:52:11 INFO - Running main action method: download_and_extract 08:52:11 INFO - mkdir: /builds/slave/test/build/tests 08:52:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:11 INFO - trying https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:52:11 INFO - Downloading https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:52:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 08:52:12 INFO - Downloaded 1325 bytes. 08:52:12 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:52:12 INFO - Using the following test package requirements: 08:52:12 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:52:12 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:52:12 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:52:12 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:52:12 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:52:12 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:52:12 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:52:12 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:52:12 INFO - u'jsshell-mac.zip'], 08:52:12 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:52:12 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:52:12 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:52:12 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:52:12 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:52:12 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:52:12 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:52:12 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:52:12 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:52:12 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:52:12 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:52:12 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:52:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:52:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:52:12 INFO - Fetch https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:52:14 INFO - Expected file size: 52507316 08:52:14 INFO - Obtained file size: 52507316 08:52:30 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:52:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:52:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:52:30 INFO - Fetch https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:52:32 INFO - Expected file size: 52507316 08:52:32 INFO - Obtained file size: 52507316 08:52:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:52:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:52:47 INFO - Fetch https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:52:48 INFO - Expected file size: 14404171 08:52:48 INFO - Obtained file size: 14404171 08:52:49 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')]} 08:52:49 INFO - trying https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:52:49 INFO - Downloading https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:52:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 08:52:51 INFO - Downloaded 75653334 bytes. 08:52:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:52:51 INFO - mkdir: /builds/slave/test/properties 08:52:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:52:51 INFO - Writing to file /builds/slave/test/properties/build_url 08:52:51 INFO - Contents: 08:52:51 INFO - build_url:https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:52:51 INFO - Running post-action listener: _resource_record_post_action 08:52:51 INFO - Running post-action listener: set_extra_try_arguments 08:52:51 INFO - [mozharness: 2017-03-29 15:52:51.945342Z] Finished download-and-extract step (success) 08:52:51 INFO - [mozharness: 2017-03-29 15:52:51.945481Z] Running populate-webroot step. 08:52:51 INFO - Running pre-action listener: _resource_record_pre_action 08:52:51 INFO - Running main action method: populate_webroot 08:52:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:52:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:52:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:52:51 INFO - '--tppagecycles', 08:52:51 INFO - '7'], 08:52:51 INFO - 'tests': ['tsvgm']}}, 08:52:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:52:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:52:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:52:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:52:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:52:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:52:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:52:51 INFO - 'talos_options': ['--disable-e10s'], 08:52:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:52:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:52:51 INFO - 'talos_options': ['--disable-e10s'], 08:52:51 INFO - 'tests': ['damp', 'tps']}, 08:52:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:52:51 INFO - 'tests': ['damp', 'tps']}, 08:52:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:52:51 INFO - 'tests': ['dromaeo_dom']}, 08:52:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:52:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:52:51 INFO - 'tests': ['basic_compositor_video']}, 08:52:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:52:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:52:51 INFO - 'tests': ['a11yr', 08:52:51 INFO - 'ts_paint', 08:52:51 INFO - 'tpaint', 08:52:51 INFO - 'sessionrestore', 08:52:51 INFO - 'sessionrestore_no_auto_restore', 08:52:51 INFO - 'tabpaint']}, 08:52:51 INFO - 'other-e10s': {'tests': ['a11yr', 08:52:51 INFO - 'ts_paint', 08:52:51 INFO - 'tpaint', 08:52:51 INFO - 'sessionrestore', 08:52:51 INFO - 'sessionrestore_no_auto_restore', 08:52:51 INFO - 'tabpaint']}, 08:52:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:52:51 INFO - 'ts_paint', 08:52:51 INFO - 'tpaint', 08:52:51 INFO - 'sessionrestore', 08:52:51 INFO - 'sessionrestore_no_auto_restore', 08:52:51 INFO - 'tabpaint']}, 08:52:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:52:51 INFO - 'ts_paint', 08:52:51 INFO - 'tpaint', 08:52:51 INFO - 'sessionrestore', 08:52:51 INFO - 'sessionrestore_no_auto_restore', 08:52:51 INFO - 'tabpaint']}, 08:52:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:52:51 INFO - 'tests': ['a11yr', 08:52:51 INFO - 'ts_paint', 08:52:51 INFO - 'tpaint', 08:52:51 INFO - 'sessionrestore', 08:52:51 INFO - 'sessionrestore_no_auto_restore', 08:52:51 INFO - 'tabpaint']}, 08:52:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:52:51 INFO - 'tests': ['a11yr', 08:52:51 INFO - 'ts_paint', 08:52:51 INFO - 'tpaint', 08:52:51 INFO - 'sessionrestore', 08:52:51 INFO - 'sessionrestore_no_auto_restore', 08:52:51 INFO - 'tabpaint']}, 08:52:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:52:51 INFO - 'tests': ['tsvgx', 08:52:51 INFO - 'tsvgr_opacity', 08:52:51 INFO - 'tart', 08:52:51 INFO - 'tscrollx', 08:52:51 INFO - 'cart', 08:52:51 INFO - 'tsvg_static']}, 08:52:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:52:51 INFO - 'tsvgr_opacity', 08:52:51 INFO - 'tart', 08:52:51 INFO - 'tscrollx', 08:52:51 INFO - 'cart', 08:52:51 INFO - 'tsvg_static']}, 08:52:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:52:51 INFO - 'talos_options': ['--disable-e10s'], 08:52:51 INFO - 'tests': ['tp5o']}, 08:52:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:52:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:52:51 INFO - 'talos_options': ['--disable-e10s', 08:52:51 INFO - '--xperf_path', 08:52:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:51 INFO - 'tests': ['tp5n']}, 08:52:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:52:51 INFO - 'talos_options': ['--xperf_path', 08:52:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:51 INFO - 'tests': ['tp5n']}}, 08:52:51 INFO - 'talos.zip': {'path': '', 08:52:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:52:51 INFO - Running post-action listener: _resource_record_post_action 08:52:51 INFO - [mozharness: 2017-03-29 15:52:51.963721Z] Finished populate-webroot step (success) 08:52:51 INFO - [mozharness: 2017-03-29 15:52:51.963828Z] Running create-virtualenv step. 08:52:51 INFO - Running pre-action listener: _resource_record_pre_action 08:52:51 INFO - Running main action method: create_virtualenv 08:52:51 INFO - Creating virtualenv /builds/slave/test/build/venv 08:52:51 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 08:52:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:52:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:52:52 INFO - Using real prefix '/tools/python27' 08:52:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:52:52 INFO - Installing distribute.............................................................................................................................................................................................done. 08:52:55 INFO - Installing pip.................done. 08:52:55 INFO - Return code: 0 08:52:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:52:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:52:55 INFO - Reading from file tmpfile_stdout 08:52:55 INFO - Output received: 08:52:55 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) 08:52:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:52:55 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')]} 08:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:55 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')]} 08:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:55 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 0x101992b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101995768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce10d980f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101770d30>, '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 0x101697db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101765be8>, '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.iDas4Rev0J/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.Av09v9Kr6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:52:55 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 08:52:55 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 08:52:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDas4Rev0J/Render', 08:52:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:55 INFO - 'HOME': '/Users/cltbld', 08:52:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:55 INFO - 'LOGNAME': 'cltbld', 08:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:55 INFO - 'MOZ_NO_REMOTE': '1', 08:52:55 INFO - 'NO_EM_RESTART': '1', 08:52:55 INFO - 'PAGER': '/bin/cat', 08:52:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:55 INFO - 'PWD': '/builds/slave/test', 08:52:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:55 INFO - 'SHELL': '/bin/bash', 08:52:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Av09v9Kr6G/Listeners', 08:52:55 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:52:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:55 INFO - 'USER': 'cltbld', 08:52:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:55 INFO - 'XPC_FLAGS': '0x0', 08:52:55 INFO - 'XPC_SERVICE_NAME': '0', 08:52:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:56 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 08:52:56 INFO - Cleaning up... 08:52:56 INFO - Return code: 0 08:52:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101992b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101995768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce10d980f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101770d30>, '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 0x101697db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101765be8>, '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.iDas4Rev0J/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.Av09v9Kr6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDas4Rev0J/Render', 08:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:52:56 INFO - 'HOME': '/Users/cltbld', 08:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:52:56 INFO - 'LOGNAME': 'cltbld', 08:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:56 INFO - 'MOZ_NO_REMOTE': '1', 08:52:56 INFO - 'NO_EM_RESTART': '1', 08:52:56 INFO - 'PAGER': '/bin/cat', 08:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:56 INFO - 'PWD': '/builds/slave/test', 08:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:52:56 INFO - 'SHELL': '/bin/bash', 08:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Av09v9Kr6G/Listeners', 08:52:56 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:52:56 INFO - 'USER': 'cltbld', 08:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:56 INFO - 'XPC_FLAGS': '0x0', 08:52:56 INFO - 'XPC_SERVICE_NAME': '0', 08:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:56 INFO - Downloading/unpacking psutil>=3.1.1 08:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:52:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:59 INFO - Installing collected packages: psutil 08:52:59 INFO - Running setup.py install for psutil 08:53:00 INFO - building 'psutil._psutil_osx' extension 08:53:00 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 08:53:00 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 08:53:00 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 08:53:00 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 08:53:00 INFO - building 'psutil._psutil_posix' extension 08:53:00 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 08:53:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:53:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:53:00 INFO - ^ 08:53:00 INFO - 1 warning generated. 08:53:00 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 08:53:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:01 INFO - Successfully installed psutil 08:53:01 INFO - Cleaning up... 08:53:01 INFO - Return code: 0 08:53:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:01 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 0x101992b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101995768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce10d980f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101770d30>, '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 0x101697db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101765be8>, '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.iDas4Rev0J/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.Av09v9Kr6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:53:01 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 08:53:01 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 08:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDas4Rev0J/Render', 08:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:01 INFO - 'HOME': '/Users/cltbld', 08:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:01 INFO - 'LOGNAME': 'cltbld', 08:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:01 INFO - 'MOZ_NO_REMOTE': '1', 08:53:01 INFO - 'NO_EM_RESTART': '1', 08:53:01 INFO - 'PAGER': '/bin/cat', 08:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:01 INFO - 'PWD': '/builds/slave/test', 08:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:01 INFO - 'SHELL': '/bin/bash', 08:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Av09v9Kr6G/Listeners', 08:53:01 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:01 INFO - 'USER': 'cltbld', 08:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:01 INFO - 'XPC_FLAGS': '0x0', 08:53:01 INFO - 'XPC_SERVICE_NAME': '0', 08:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:53:02 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 08:53:02 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 08:53:02 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 08:53:02 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 08:53:02 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 08:53:02 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 08:53:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:53:05 INFO - Installing collected packages: mozsystemmonitor 08:53:05 INFO - Running setup.py install for mozsystemmonitor 08:53:05 INFO - Successfully installed mozsystemmonitor 08:53:05 INFO - Cleaning up... 08:53:05 INFO - Return code: 0 08:53:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:53:05 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')]} 08:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:05 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')]} 08:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:05 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 0x101992b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101995768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce10d980f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101770d30>, '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 0x101697db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101765be8>, '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.iDas4Rev0J/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.Av09v9Kr6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:53:05 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 08:53:05 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 08:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDas4Rev0J/Render', 08:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:05 INFO - 'HOME': '/Users/cltbld', 08:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:05 INFO - 'LOGNAME': 'cltbld', 08:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:05 INFO - 'MOZ_NO_REMOTE': '1', 08:53:05 INFO - 'NO_EM_RESTART': '1', 08:53:05 INFO - 'PAGER': '/bin/cat', 08:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:05 INFO - 'PWD': '/builds/slave/test', 08:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:05 INFO - 'SHELL': '/bin/bash', 08:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Av09v9Kr6G/Listeners', 08:53:05 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:05 INFO - 'USER': 'cltbld', 08:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:05 INFO - 'XPC_FLAGS': '0x0', 08:53:05 INFO - 'XPC_SERVICE_NAME': '0', 08:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:06 INFO - Downloading/unpacking jsonschema==2.5.1 08:53:06 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 08:53:06 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 08:53:06 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 08:53:06 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 08:53:06 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 08:53:06 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 08:53:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:53:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:09 INFO - Downloading functools32-3.2.3-2.tar.gz 08:53:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:53:09 INFO - warning: no files found matching '*.txt' 08:53:09 INFO - no previously-included directories found matching 'build' 08:53:09 INFO - no previously-included directories found matching 'dist' 08:53:09 INFO - no previously-included directories found matching '.git*' 08:53:09 INFO - Installing collected packages: jsonschema, functools32 08:53:09 INFO - Running setup.py install for functools32 08:53:09 INFO - warning: no files found matching '*.txt' 08:53:09 INFO - no previously-included directories found matching 'build' 08:53:09 INFO - no previously-included directories found matching 'dist' 08:53:09 INFO - no previously-included directories found matching '.git*' 08:53:09 INFO - Successfully installed jsonschema functools32 08:53:09 INFO - Cleaning up... 08:53:09 INFO - Return code: 0 08:53:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:09 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 0x101992b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101995768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce10d980f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101770d30>, '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 0x101697db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101765be8>, '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.iDas4Rev0J/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.Av09v9Kr6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:53:09 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 08:53:09 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 08:53:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDas4Rev0J/Render', 08:53:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:09 INFO - 'HOME': '/Users/cltbld', 08:53:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:09 INFO - 'LOGNAME': 'cltbld', 08:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:09 INFO - 'MOZ_NO_REMOTE': '1', 08:53:09 INFO - 'NO_EM_RESTART': '1', 08:53:09 INFO - 'PAGER': '/bin/cat', 08:53:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:09 INFO - 'PWD': '/builds/slave/test', 08:53:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:09 INFO - 'SHELL': '/bin/bash', 08:53:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Av09v9Kr6G/Listeners', 08:53:09 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:53:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:09 INFO - 'USER': 'cltbld', 08:53:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:09 INFO - 'XPC_FLAGS': '0x0', 08:53:09 INFO - 'XPC_SERVICE_NAME': '0', 08:53:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:53:09 INFO - Cleaning up... 08:53:09 INFO - Return code: 0 08:53:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:09 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 0x101992b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101995768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce10d980f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101770d30>, '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 0x101697db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101765be8>, '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.iDas4Rev0J/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.Av09v9Kr6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:53:09 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 08:53:09 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 08:53:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDas4Rev0J/Render', 08:53:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:09 INFO - 'HOME': '/Users/cltbld', 08:53:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:09 INFO - 'LOGNAME': 'cltbld', 08:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:09 INFO - 'MOZ_NO_REMOTE': '1', 08:53:09 INFO - 'NO_EM_RESTART': '1', 08:53:09 INFO - 'PAGER': '/bin/cat', 08:53:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:09 INFO - 'PWD': '/builds/slave/test', 08:53:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:09 INFO - 'SHELL': '/bin/bash', 08:53:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Av09v9Kr6G/Listeners', 08:53:09 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:53:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:09 INFO - 'USER': 'cltbld', 08:53:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:09 INFO - 'XPC_FLAGS': '0x0', 08:53:09 INFO - 'XPC_SERVICE_NAME': '0', 08:53:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:10 INFO - Downloading/unpacking blobuploader==1.2.4 08:53:10 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 08:53:10 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 08:53:10 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 08:53:10 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 08:53:10 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 08:53:10 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 08:53:12 INFO - Downloading blobuploader-1.2.4.tar.gz 08:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:53:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:53:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:53:13 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 08:53:13 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 08:53:13 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 08:53:13 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 08:53:13 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 08:53:13 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 08:53:13 INFO - Downloading docopt-0.6.1.tar.gz 08:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:53:14 INFO - Installing collected packages: blobuploader, requests, docopt 08:53:14 INFO - Running setup.py install for blobuploader 08:53:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:53:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:53:14 INFO - Running setup.py install for requests 08:53:14 INFO - Running setup.py install for docopt 08:53:14 INFO - Successfully installed blobuploader requests docopt 08:53:14 INFO - Cleaning up... 08:53:14 INFO - Return code: 0 08:53:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:14 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 0x101992b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101995768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce10d980f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101770d30>, '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 0x101697db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101765be8>, '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.iDas4Rev0J/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.Av09v9Kr6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:53:14 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 08:53:14 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 08:53:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDas4Rev0J/Render', 08:53:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:14 INFO - 'HOME': '/Users/cltbld', 08:53:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:14 INFO - 'LOGNAME': 'cltbld', 08:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:14 INFO - 'MOZ_NO_REMOTE': '1', 08:53:14 INFO - 'NO_EM_RESTART': '1', 08:53:14 INFO - 'PAGER': '/bin/cat', 08:53:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:14 INFO - 'PWD': '/builds/slave/test', 08:53:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:14 INFO - 'SHELL': '/bin/bash', 08:53:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Av09v9Kr6G/Listeners', 08:53:14 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:53:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:14 INFO - 'USER': 'cltbld', 08:53:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:14 INFO - 'XPC_FLAGS': '0x0', 08:53:14 INFO - 'XPC_SERVICE_NAME': '0', 08:53:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xbBC8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qPJ_aP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-r7GwJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Dh09L8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-6eU4T6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-6NI2CM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-q_1a9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:15 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-8vECJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-TZG6O2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-NGtUJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-tTAagj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Y9R1Hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-zuvksz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-r1FTqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-h5GRXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-uOsKB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:16 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-bMeMNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:53:16 INFO - Running setup.py install for manifestparser 08:53:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:53:17 INFO - Running setup.py install for mozcrash 08:53:17 INFO - Running setup.py install for mozdebug 08:53:17 INFO - Running setup.py install for mozdevice 08:53:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:53:17 INFO - Running setup.py install for mozfile 08:53:17 INFO - Running setup.py install for mozhttpd 08:53:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:53:17 INFO - Running setup.py install for mozinfo 08:53:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:53:17 INFO - Running setup.py install for mozInstall 08:53:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:53:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:53:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:53:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:53:18 INFO - Running setup.py install for mozleak 08:53:18 INFO - Running setup.py install for mozlog 08:53:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:53:18 INFO - Running setup.py install for moznetwork 08:53:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:53:18 INFO - Running setup.py install for mozprocess 08:53:18 INFO - Running setup.py install for mozprofile 08:53:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:53:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:53:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:53:18 INFO - Running setup.py install for mozrunner 08:53:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:53:19 INFO - Running setup.py install for mozscreenshot 08:53:19 INFO - Running setup.py install for moztest 08:53:19 INFO - Running setup.py install for mozversion 08:53:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:53:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:53:19 INFO - Cleaning up... 08:53:19 INFO - Return code: 0 08:53:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:19 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 0x101992b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101995768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce10d980f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101770d30>, '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 0x101697db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101765be8>, '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.iDas4Rev0J/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.Av09v9Kr6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:53:19 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 08:53:19 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 08:53:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDas4Rev0J/Render', 08:53:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:19 INFO - 'HOME': '/Users/cltbld', 08:53:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:19 INFO - 'LOGNAME': 'cltbld', 08:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:19 INFO - 'MOZ_NO_REMOTE': '1', 08:53:19 INFO - 'NO_EM_RESTART': '1', 08:53:19 INFO - 'PAGER': '/bin/cat', 08:53:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:19 INFO - 'PWD': '/builds/slave/test', 08:53:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:19 INFO - 'SHELL': '/bin/bash', 08:53:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Av09v9Kr6G/Listeners', 08:53:19 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:53:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:19 INFO - 'USER': 'cltbld', 08:53:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:19 INFO - 'XPC_FLAGS': '0x0', 08:53:19 INFO - 'XPC_SERVICE_NAME': '0', 08:53:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:19 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-_zUqfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:19 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)) 08:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:19 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-e6oReq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:20 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)) 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-3JZp5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:20 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)) 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-77SHGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:20 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)) 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-SkJ7Nw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:20 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)) 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-h2noM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:20 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)) 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-lVe1zG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:20 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)) 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-XVJw9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:20 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)) 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-I6kf1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:20 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)) 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-BihvHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:20 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)) 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-XCsbfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:20 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)) 08:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:20 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-l51wCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:21 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)) 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-eJyBZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:21 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)) 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xb_HnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:21 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)) 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-2eEb_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:21 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)) 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-A0RUwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:21 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)) 08:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:21 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-MdtkpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:21 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)) 08:53:21 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)) 08:53:21 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)) 08:53:21 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)) 08:53:21 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)) 08:53:21 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)) 08:53:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:53:21 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 08:53:21 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 08:53:21 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 08:53:21 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 08:53:21 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 08:53:21 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 08:53:24 INFO - Downloading blessings-1.6.tar.gz 08:53:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:53:24 INFO - Installing collected packages: blessings 08:53:24 INFO - Running setup.py install for blessings 08:53:24 INFO - Successfully installed blessings 08:53:24 INFO - Cleaning up... 08:53:24 INFO - Return code: 0 08:53:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:53:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:24 INFO - Reading from file tmpfile_stdout 08:53:24 INFO - Current package versions: 08:53:24 INFO - blessings == 1.6 08:53:24 INFO - blobuploader == 1.2.4 08:53:24 INFO - docopt == 0.6.1 08:53:24 INFO - functools32 == 3.2.3-2 08:53:24 INFO - jsonschema == 2.5.1 08:53:24 INFO - manifestparser == 1.1 08:53:24 INFO - mozInstall == 1.12 08:53:24 INFO - mozcrash == 1.0 08:53:24 INFO - mozdebug == 0.1 08:53:24 INFO - mozdevice == 0.49 08:53:24 INFO - mozfile == 1.2 08:53:24 INFO - mozhttpd == 0.7 08:53:24 INFO - mozinfo == 0.9 08:53:24 INFO - mozleak == 0.1 08:53:24 INFO - mozlog == 3.4 08:53:24 INFO - moznetwork == 0.27 08:53:24 INFO - mozprocess == 0.25 08:53:24 INFO - mozprofile == 0.28 08:53:24 INFO - mozrunner == 6.13 08:53:24 INFO - mozscreenshot == 0.1 08:53:24 INFO - mozsystemmonitor == 0.3 08:53:24 INFO - moztest == 0.8 08:53:24 INFO - mozversion == 1.4 08:53:24 INFO - psutil == 3.1.1 08:53:24 INFO - requests == 1.2.3 08:53:24 INFO - wsgiref == 0.1.2 08:53:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:24 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 0x101992b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101995768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce10d980f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101770d30>, '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 0x101697db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101765be8>, '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.iDas4Rev0J/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.Av09v9Kr6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:53:24 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 08:53:24 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 08:53:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDas4Rev0J/Render', 08:53:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:24 INFO - 'HOME': '/Users/cltbld', 08:53:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:24 INFO - 'LOGNAME': 'cltbld', 08:53:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:24 INFO - 'MOZ_NO_REMOTE': '1', 08:53:24 INFO - 'NO_EM_RESTART': '1', 08:53:24 INFO - 'PAGER': '/bin/cat', 08:53:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:24 INFO - 'PWD': '/builds/slave/test', 08:53:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:24 INFO - 'SHELL': '/bin/bash', 08:53:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Av09v9Kr6G/Listeners', 08:53:24 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:53:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:24 INFO - 'USER': 'cltbld', 08:53:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:24 INFO - 'XPC_FLAGS': '0x0', 08:53:24 INFO - 'XPC_SERVICE_NAME': '0', 08:53:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:25 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)) 08:53:25 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)) 08:53:25 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)) 08:53:25 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)) 08:53:25 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)) 08:53:25 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)) 08:53:25 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)) 08:53:25 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)) 08:53:25 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)) 08:53:25 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)) 08:53:25 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)) 08:53:25 INFO - Cleaning up... 08:53:25 INFO - Return code: 0 08:53:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:53:25 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')]} 08:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:25 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')]} 08:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:25 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 0x101992b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101995768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce10d980f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101770d30>, '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 0x101697db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101765be8>, '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.iDas4Rev0J/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.Av09v9Kr6G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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 08:53:25 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 08:53:25 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 08:53:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDas4Rev0J/Render', 08:53:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:25 INFO - 'HOME': '/Users/cltbld', 08:53:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:25 INFO - 'LOGNAME': 'cltbld', 08:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:25 INFO - 'MOZ_NO_REMOTE': '1', 08:53:25 INFO - 'NO_EM_RESTART': '1', 08:53:25 INFO - 'PAGER': '/bin/cat', 08:53:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:25 INFO - 'PWD': '/builds/slave/test', 08:53:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:25 INFO - 'SHELL': '/bin/bash', 08:53:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Av09v9Kr6G/Listeners', 08:53:25 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:53:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:25 INFO - 'USER': 'cltbld', 08:53:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:25 INFO - 'XPC_FLAGS': '0x0', 08:53:25 INFO - 'XPC_SERVICE_NAME': '0', 08:53:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:53:25 INFO - Cleaning up... 08:53:25 INFO - Return code: 0 08:53:25 INFO - Running post-action listener: _resource_record_post_action 08:53:25 INFO - Running post-action listener: _start_resource_monitoring 08:53:25 INFO - Starting resource monitoring. 08:53:25 INFO - [mozharness: 2017-03-29 15:53:25.489148Z] Finished create-virtualenv step (success) 08:53:25 INFO - [mozharness: 2017-03-29 15:53:25.490087Z] Running install step. 08:53:25 INFO - Running pre-action listener: _resource_record_pre_action 08:53:25 INFO - Running main action method: install 08:53:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:53:25 INFO - Reading from file tmpfile_stdout 08:53:25 INFO - Detecting whether we're running mozinstall >=1.0... 08:53:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:53:25 INFO - Reading from file tmpfile_stdout 08:53:25 INFO - Output received: 08:53:25 INFO - Usage: mozinstall [options] installer 08:53:25 INFO - Options: 08:53:25 INFO - -h, --help show this help message and exit 08:53:25 INFO - -d DEST, --destination=DEST 08:53:25 INFO - Directory to install application into. [default: 08:53:25 INFO - "/builds/slave/test"] 08:53:25 INFO - --app=APP Application being installed. [default: firefox] 08:53:25 INFO - mkdir: /builds/slave/test/build/application 08:53:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:53:46 INFO - Reading from file tmpfile_stdout 08:53:46 INFO - Output received: 08:53:46 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 08:53:46 INFO - Running post-action listener: _resource_record_post_action 08:53:46 INFO - [mozharness: 2017-03-29 15:53:46.189067Z] Finished install step (success) 08:53:46 INFO - [mozharness: 2017-03-29 15:53:46.189227Z] Running run-tests step. 08:53:46 INFO - Running pre-action listener: _resource_record_pre_action 08:53:46 INFO - Running main action method: run_tests 08:53:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:53:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:53:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:53:46 INFO - 2017-03-29 08:53:46.203 screenresolution[1624:7542] starting screenresolution argv=screenresolution get 08:53:46 INFO - 2017-03-29 08:53:46.206 screenresolution[1624:7542] Display 0: 1600x1200x32@60 08:53:46 INFO - 2017-03-29 08:53:46.212 screenresolution[1625:7544] starting screenresolution argv=screenresolution list 08:53:46 INFO - Available Modes on Display 0 08:53:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:53:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:53:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:53:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:53:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:53:46 INFO - Intel Iris: 08:53:46 INFO - Chipset Model: Intel Iris 08:53:46 INFO - Type: GPU 08:53:46 INFO - Bus: Built-In 08:53:46 INFO - VRAM (Dynamic, Max): 1536 MB 08:53:46 INFO - Vendor: Intel (0x8086) 08:53:46 INFO - Device ID: 0x0a2e 08:53:46 INFO - Revision ID: 0x0009 08:53:46 INFO - Displays: 08:53:46 INFO - Display: 08:53:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:53:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:53:46 INFO - Display Serial Number: 1600x1200 60 08:53:46 INFO - Main Display: Yes 08:53:46 INFO - Mirror: Off 08:53:46 INFO - Online: Yes 08:53:46 INFO - Rotation: Supported 08:53:46 INFO - Return code: 0 08:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:53:46 INFO - Python 2.7.3 08:53:46 INFO - Return code: 0 08:53:46 INFO - grabbing minidump binary from tooltool 08:53: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')]} 08:53:46 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 0x101770d30>, '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 0x101697db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101765be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:53:46 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 08:53:46 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 08:53:46 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 08:53:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:53:46 INFO - Return code: 0 08:53:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:53:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:53:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:53:46 INFO - ENV: RUST_BACKTRACE is now 1 08:53:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:53:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 08:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0147 --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 08:53:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDas4Rev0J/Render', 08:53:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:46 INFO - 'HOME': '/Users/cltbld', 08:53:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:46 INFO - 'LOGNAME': 'cltbld', 08:53:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:53:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:46 INFO - 'MOZ_NO_REMOTE': '1', 08:53:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:53:46 INFO - 'NO_EM_RESTART': '1', 08:53:46 INFO - 'PAGER': '/bin/cat', 08:53:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:46 INFO - 'PWD': '/builds/slave/test', 08:53:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:53:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:46 INFO - 'RUST_BACKTRACE': '1', 08:53:46 INFO - 'SHELL': '/bin/bash', 08:53:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Av09v9Kr6G/Listeners', 08:53:46 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 08:53:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:46 INFO - 'USER': 'cltbld', 08:53:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:46 INFO - 'XPC_FLAGS': '0x0', 08:53:46 INFO - 'XPC_SERVICE_NAME': '0', 08:53:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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 08:53:46 INFO - mozversion application_buildid: 20170329071901 08:53:46 INFO - mozversion application_changeset: 6ea713ccc9abea93126423fefb855d0e051c95e2 08:53:46 INFO - mozversion application_display_name: Nightly 08:53:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:53:46 INFO - mozversion application_name: Firefox 08:53:46 INFO - mozversion application_remotingname: firefox 08:53:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:53:46 INFO - mozversion application_vendor: Mozilla 08:53:46 INFO - mozversion application_version: 55.0a1 08:53:46 INFO - mozversion platform_buildid: 20170329071901 08:53:46 INFO - mozversion platform_changeset: 6ea713ccc9abea93126423fefb855d0e051c95e2 08:53:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:53:46 INFO - mozversion platform_version: 55.0a1 08:53:46 INFO - using testdate: 1490802826 08:53:46 INFO - actual date: 1490802826 08:53:46 INFO - starting webserver on 'localhost:49252' 08:53:46 INFO - SUITE-START | Running 2 tests 08:53:46 INFO - TEST-START | dromaeo_css 08:53:46 INFO - operating with platform_type : mac_ 08:53:46 INFO - Initialising browser for dromaeo_css test... 08:53:46 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpWlyPOx/profile http://localhost:49252/getInfo.html) 08:53:49 INFO - PID 1635 | __metrics Screen width/height:1600/1200 08:53:49 INFO - PID 1635 | colorDepth:24 08:53:49 INFO - PID 1635 | Browser inner width/height: 1024/689 08:53:49 INFO - PID 1635 | __metrics 08:53:49 ERROR - PID 1635 | 1490802829517 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 08:53:49 INFO - PID 1635 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:53:49 INFO - PID 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:53:50 INFO - TEST-INFO | 1635: exit 0 08:53:50 INFO - Browser initialized. 08:53:50 INFO - Running cycle 1/1 for dromaeo_css test... 08:53:50 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpWlyPOx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:54:02 INFO - PID 1638 | RSS: Main: 234102784 08:54:02 INFO - PID 1638 | 08:57:07 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49252/tests/dromaeo/cssquery-ext.html) 08:57:09 INFO - PID 1638 | RSS: Main: 356270080 08:57:09 INFO - PID 1638 | 08:58:52 INFO - PID 1638 | ************************* 08:58:52 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 08:58:52 INFO - PID 1638 | 08:58:52 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:52 INFO - PID 1638 | Full stack: 08:58:52 INFO - PID 1638 | ************************* 08:58:52 INFO - PID 1638 | ************************* 08:58:52 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 08:58:52 INFO - PID 1638 | 08:58:52 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:52 INFO - PID 1638 | Full stack: 08:58:52 INFO - PID 1638 | ************************* 08:58:52 INFO - PID 1638 | ************************* 08:58:52 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 08:58:52 INFO - PID 1638 | 08:58:52 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:52 INFO - PID 1638 | Full stack: 08:58:52 INFO - PID 1638 | ************************* 08:58:52 INFO - PID 1638 | ************************* 08:58:52 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 08:58:52 INFO - PID 1638 | 08:58:52 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:52 INFO - PID 1638 | Full stack: 08:58:52 INFO - PID 1638 | ************************* 09:00:12 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-ext.html (next: http://localhost:49252/tests/dromaeo/cssquery-jquery.html) 09:00:12 INFO - PID 1638 | RSS: Main: 363597824 09:00:12 INFO - PID 1638 | 09:03:15 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49252/tests/dromaeo/cssquery-mootools.html) 09:03:15 INFO - PID 1638 | RSS: Main: 332644352 09:03:15 INFO - PID 1638 | 09:06:17 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49252/tests/dromaeo/cssquery-prototype.html) 09:06:18 INFO - PID 1638 | RSS: Main: 332066816 09:06:18 INFO - PID 1638 | 09:09:22 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49252/tests/dromaeo/cssquery-yui.html) 09:09:22 INFO - PID 1638 | RSS: Main: 336998400 09:09:22 INFO - PID 1638 | 09:12:25 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-yui.html (next: http://localhost:49252/tests/dromaeo/cssquery-dojo.html) 09:12:26 INFO - PID 1638 | RSS: Main: 537739264 09:12:26 INFO - PID 1638 | 09:12:26 INFO - PID 1638 | __start_tp_report 09:12:26 INFO - PID 1638 | _x_x_mozilla_page_load 09:12:26 INFO - PID 1638 | _x_x_mozilla_page_load_details 09:12:26 INFO - PID 1638 | |i|pagename|runs| 09:12:26 INFO - PID 1638 | |0;dojo.html;2838.262051866515;3453.119454539092;3480.8433620487076;3500.2474467989377;3517.2613751112267;6008.699565021753;6098.613770999333;6163.876722465554;6186.350433204516;6190.876182476353;6123.770712314293;6131.009681329152;6188.102725104857;6198.4731297839735;6202.224721909762;5350.866228344284;5487.972560137214;5534.03154447972;5579.191017302489;5665.971670141664;5574.9721251393885;5581.441855814406;5608.046632072557;5625.606207565472;5627.831165065055;5037.974810125963;5403.864903377427;5490.890182196374;5491.176323551459;5589.664620122806;5519.751611177467;5520.585956053312;5555.611107222496;5634.492895639371;5644.096944488862;5482.91775623366;5484.067708489526;5487.807926722546;5500.532454741352;5540.335159780811;6056.515478761733;6104.641056839901;6206.100115483229;6214.5960512566535;6239.688015599202;6421.967890160519;6514;6573.605583664996;6590.242122155965;6591.209054913441;6573.375529324754;6603.471722262208;6612.900806487906;6614.470842332602;6617.437517810992;6168.537359697995;6179.073139029137;6232.781852635136;6258.780942666985;6270.968645156745;6541.0515475255825;6619.238787539398;6632.767853125214;6720.092787473726;6734.225564060098;6469.385408386196;6474.643894585749;6482.092507049017;6486.189226346707;6494.512911531559;6571.638559879157;6621.9337806621315;6628.734850605922;6634.867302654016;6658.1011563438315;6383.265924418658;6452.838679033061;6549.476041916635;6557.9344206557325;6564.6389448580785;5812.7674893003805;6456.935430645727;6541.6075035498025;6580.309067547934;6581.2102547694585;5265.104932161542;5274.92087618686;5277.050130976461;5280.419153893069;5292.629515933848;4660.572456166656;4667.206574882278;4669.789859456333;4680.578747912704;4683.882902927454;6068.848278793065;6144.941444616104;6156.907646385308;6160.969195154085;6230.096635987757;6490.610563366213;6502.902456463157;6507.479401647952;6521.673916304166;6536.509761767887;6127.5404344674325;6128;6134.754609815646;6137.171481849982;6138.478229350555;5642.915356269659;5699.259096317535;5703.54371650267;5764.308283005982;5776.220210271644;6735.292794256533;6882.311768823078;6883.483738719516;6886.070380498769;6889.379955804002;6444.871102577828;6517.348265173445;6523.543351965317;6555.541112122106;6694.129763130762;6609.405153536205;6699.263081060982;6701.262861085179;6719.126513553402;6723.7646682365885;6431.099646049463;6438.098666186831;6444.967775161094;6448.5486015980305;6452.709628066655;6467.256265529524;6529.804105876831;6551.574147680385;6606.4384527315715;6657.900131498128;6393.1688880445245;6458.418742313214;6463.482921366373;6467.062275970052;6485.902711459237;5090.26191202281;5805.82582522525;5961.344252132175;6472;6499.5125365598715;6211.81364559064;6215.471684906653;6265.467435267871;6323.841903952438;6382.6170429774365;6428.260750013808;6481.805545833632;6490.578112422678;6497.935020649922;6499.967500162469;6340;6353.3011368748475;6358.841028974128;6360.045993101072;6380.680965951777 09:12:26 INFO - PID 1638 | |1;ext.html;795.4630624328579;1114.7937631538164;1140.21325285553;1148.1905256793962;1150.3500522204959;37760;38835.41746873799;38849.417258741145;38966;38976.22047559047;41518.792406038;41822;41964;41989.74030779082;41991.790041049826;6159.8768024639485;6308.179936608234;8611.268042216403;8768.780780480476;8775.692850750225;6273.68631568422;6364.936350636503;6372.840678983016;8468.576571171448;8688.391812573122;8676.826463470727;8729.34529910256;8742.86885696712;8761.912380876174;8764.079771623983;8668.436551624156;8781.64873405063;8783.956080219557;8804.603792829337;8818.514981675973;6142.6007309525185;6300.243970723543;6300.30696623371;8636;8738.388312818106;28494.857525712447;28755.712442875512;37924;38309.42535861964;38339.233215335815;3034.848257587134;3041.1788817019283;3060.8469576521084;3067.2638566744045;3088.9382212355854;28939.56590651142;29381.853090734625;29386.41227175466;29409;29913;2719.619253304519;2800.3419196489012;2835.8440285784272;2917.212352664769;2936.8091074080116;322072;322372.38813806017;322992;323144;323262;48914;48961.75519122381;48973.755131224825;48983.02033959372;48994;52400;52514.73742631339;52542.949141016965;52555.73722131365;52630.736846316286;12272.140950133584;14558.563243102724;14723.190224537539;14933;14942.551723448314;12114.575989840314;12115.63653090391;12118.333491658044;12122.333271669966;12123.515059397525;13061.934690326678;13772.655683607789;13774.03581749288;13790.724185516434;13903.59361956307;4756.524347565216;4773.403324584427;5660.830175094754;5720.655645923205;5724.542036637059;782.6360742254878;917.7338571814099;949.1647350331664;992.4888682328607;1003.5032658833778;23236.41908952256;23497.412564685677;24606;24954.37614059663;25053.37366565814;1072.7747173093583;1081.4808891732014;1108;1111.8054340490382;1125.5385292030228;12065.948788705766;12703.96675894769;12759.362031898369;12792.424340904681;12845.779650933146;477742;479993;482577;483364.5831770819;483377;58924.11613825796;59208.11187832186;59276;59282;59369;1307.3789949773782;1326.9601911942657;1500.3248538157873;1557.314781496138;1557.906525608467;24072;28918.710812892445;29691.257718557208;29691.85154074216;29919.55120673148;52968.470315296356;53094.203586947;53120;53139;53155.4684453166;394767;395254.0237298795;395961;396351;397734.0113299415;34137;34907.4763878537;34984.650153498136;35022.47466287957;35037;44164;44261.672149835555;44838;46176;46185.76699631128;30296.939607113643;30313.696863031088;31263.53104703386;31362.843185783924;31399.372012559164;22283.554328913655;30959.69040309568;31191.376172476877;31296.530552041284;31361.372772544877;27692.584611230446;32887.34225315528;37252.813735931144;37264;37302;28400.715992839807;30047.549286760277;30495;30789.23026924255;30865.69134308628;24356;24526.754732452446;24558.508829823662;24595.954297247685;24606.630900536147 09:12:26 INFO - PID 1638 | |2;jquery.html;385.7087898636529;422.57275084199557;434.6479351725102;438.37750394439894;453.5962992936287;17290;20692.586148277045;21499.785002149994;21520.461988450297;21778.673319900176;20621.793782062174;21861.453463663416;21869.015894284756;22260.77565733888;22261.55476890461;4325.913481730364;6156.199694039779;6372.522060845432;6379.202599675041;6383.425491705745;5412.485813847678;6333.904991425133;6421.165248517686;6435.839104022413;6438.935610643904;6089.545082212869;6224.377562243762;6302.968485157568;6315.905261421082;6392.16901802765;6208.596441231351;6254.562180647356;6297.779577714804;6305.432511073979;6306;5318;5520.972395138038;5523.392426833045;5529.253550770634;5534.03154447974;25799.35501612456;25865.482690346274;25998.87000564985;26052.34869128268;26069;1970.1331414177716;1980.7821139674625;1993.0532996826391;2034.3591768592846;2036.775954644988;12588.937055314665;15257.923710381378;15741.842581574267;16289.674206515803;16404.425845095364;1635.8854880158394;1640.729279668865;1662.7755253040805;1680.201904095557;1720.6214632780768;129686.35156824156;135729;136554.3172284142;137670.93493597704;139666;9247.95376023129;11514;11555.422228888521;11622.418879056013;11749.647510574696;12658.303793291274;14643.048201866844;14654.413823446943;14655.387907330185;14669.33987970544;8741;8769.21077103051;8780.958665964064;8809.911900881038;8905.821883562292;8389.832203356022;8430.45202061864;8551.230389264996;8593.011803642536;8595.570221489026;7129.429645628337;7269.563826170447;7269.927300727031;7276.8180795479475;7285.6885760562545;2109.765787014583;2137.7220961274934;2141.2184552638487;2142.0896119149297;2145.8712477251415;808.4866110020062;923.2752289452748;931.1014870649856;931.2084727981297;971.6647756523988;19767.60464790725;20517.076731547117;20601.381958541268;20614.484637884172;20716.689249660965;974.6004138303404;976.3117002513211;1000.4247557654378;1013.5084484025242;1017.1151098544314;9481.051894810602;10172.542235599267;10237.23220758446;10431.739206519898;10577.471126443646;687804;689410;701016;706825;708926;102351;128746.35626821806;130796;132566.67433325737;135031;1151.633330759134;1160.9245399049034;1196.16866277936;1196.252342286071;1196.342011893455;17449;17473.388431405347;17474.21366038557;17500.387486437914;17930.820691792916;56422.87154256974;57457.42542574688;57513;57558.7122064387;58092;77547;79311.60344198241;81894;87227.691584625;89706.55146724224;7300.890486642598;8332.375071869512;8446.873296900674;8631.56842157902;8873.778155546162;12402.503899844267;13439.529616463376;13442.663933401353;13928.303584820516;13960.650983725489;11548.769024619629;11631.185816993067;11763.176577639482;11899.524019039141;11997.700057498632;10718.196135289729;10927.672169834917;10934.617288394868;11109.444527773418;11233.584779272496;11488.885111148782;11541.82687259675;11722.70693232676;11961.820572691591;12082.275063496218;8531.74404767858;8568.785780355542;8628.266597339296;8674.349423793115;8683.478991260545;9644.469554174588;11392.316461012035;11442.828357574477;11754.941225293818;11757.882421175678 09:12:26 INFO - PID 1638 | |3;mootools.html;1350.2371160294433;1408.3169662713583;1427.3434220258673;1434.9569512914616;1445.8481859404758;10645;11104.000639942413;11111.055560277366;11111.055560277366;11114.666560003192;10634.57461701531;10657.84013239802;11018.173636977219;11027.897210278968;11041.282316649433;2440.7559244075583;2492.588722860735;2492.8005759539283;2494.5759220932396;2494.6133349330885;2408.4821763320883;2413.5414271288487;2460.876956152194;2462.5936720441105;2465.272744540356;2387.7612238776155;2485.5153245117117;2488.2783992642203;2488.390344365633;2490.1657944588583;2421.2494126820566;2457.127719659513;2464.8397854139425;2465.938351541217;2495.762902524248;2410.879456027209;2420.1771397724856;2424.4060205249825;2430.890609922558;2440.1093600835743;10935;11028.834567481414;11033.062189713884;11054.778904421946;11083.72290692731;5366.436524164985;6222.097795819581;6457.4188323050685;6466.612003279772;6491.480681545512;3951.1900060487533;3989.085239085255;4002.7598344099156;4014.1570270243283;4030.6372426481757;1174.401055461712;1518.7038527487146;1564.2726132348398;1581.1066747287746;1645.2267434305857;12690.11169218155;15246.313915873812;15370;15432.3827046918;15482.61293467661;4406.647468202536;5067.087924173684;5366.7853285868905;5371.113766228606;5406.432324605938;11417.885821141683;11435.256708313933;11435.313881167156;11437.485313160927;11440.313581185155;6262.906056409158;6263.6241825490615;6279.811605651837;6282.4974002080635;6291.245050593958;5684.033714268584;5790.855228619234;5875.941240587625;5884.382139875337;5891.322497912709;4026.051506413455;4142.171565686875;4158.459400278005;4178.832846686159;4179.749215047107;1242.45332053896;1243.3144408078506;1251.2429979862234;1262.7663882181641;1265.702559898423;749.6701451361383;753.408574269196;776.4176867349488;779.3336697123991;800.0319613267894;5203.895922081537;5876.6180198286975;5885.705714714247;6053.334133245339;6110.725017374229;553.1895772693024;581.3517927510795;589.2457654202627;589.6963064022032;594.6135012241991;3709.4435834625024;4085.8774236772942;4230.428892099527;4308.67684923632;4354.9564504355185;13538;14019.439222431192;14023.158610483404;14040.438382464792;14044.087134336236;13405.396757145953;13925.721485570231;13934.094283871516;13987.440502379994;14021.85978140226;9968.900310996798;10262.897371026344;10277;10279.845802312822;10306.227032972407;6418.807435776934;6459.9031014533875;6488.383603557654;6666.766663166766;6704;12185.817212741993;12666.746665066832;12824.615261542169;12913.806292905427;12968.610941671765;12508.624741257778;12864.935675321563;12907.419166137359;12934.288614126313;12951.222926624432;8728.258098061737;9099.681511147079;9103.453792772454;9105.635774569208;9107.863382049407;9493.430394176372;9913.30606857513;10036.096751292418;10050.49747512636;10050.994900510183;8234.547099909563;8642.654293828176;8691.86962195555;8747.91252087471;8787.956060219658;8441.915580844114;8467.872981905153;8537.87193192115;8643.654253829776;8725.52009639476;7646.694132234648;7764.456488045783;7873.606319683879;7883.093444253984;7884.2115788423;4971.279164521196;5762.654240745569;5891.911621325769;6044.123602077762;6064.787732429344;4732.574068333866;5962.522998160223;5998.910016349845;6089.908651370321;6139.570230083851 09:12:26 INFO - PID 1638 | |4;prototype.html;390.23513912731045;396.7341880939769;399.38095951275534;404.9493813273341;406.5974685061788;5112.923306150401;6134.938650613492;6264.561480696353;6264.749410023605;6303.109067279815;4154.252234597771;6022.75908963642;6047.971844786386;6100.176476175715;6109.236345456818;4338.2191205582985;4364.799680087969;4760.000399916022;4782.617390608761;4786.282057691339;3903.512060992376;4773.474917759044;4797.808087676489;4805.591524720386;4810.110129626013;4395.516493185747;4632.0967411354795;4751.121042607128;4759.690620109699;4761.50004249554;3673.485712000322;4387.7367357958465;4559.654901803976;4705.176594096037;4709.905801883943;4146.212219678251;4152.709310348277;4153.501579810412;4194.50519819035;4202.92825329539;4549.590536851666;5984.9401505984815;5998.580099393044;5998.760049598054;6013.9699301503215;4900.411950565955;5163.18606359535;5475.917861232085;5489.6980666063355;5501.174823776426;5194.454582268883;5656.717164141776;6352.855336608362;6546.410823025951;6558.672066396661;4225.492940847087;4356.542563030899;4363.80362883671;4363.890902727426;4373.1851281718045;6437.324080971477;6602.9296377184355;6782.253952065269;6797.150356205474;6923.792286231421;3347.280334728045;4345.283028300295;4415.40392047076;4416.674997500737;4419.9337009944875;3777.8299976501125;4471.473617406155;4475.440569928759;4477.597016268552;4525.796339164745;4454.774936892316;4923.852284431473;5112.693238405707;5184.533391994739;5330.387005494418;5558.749856256478;6294.859747685718;6359.618422894641;6380.457661098766;6398.5521013528605;1505.8193016837831;1525.4660868695867;1530.9943973394888;1533.562934563644;1535.3091109000993;987.7530617345664;995.6117114325419;1001.409168590535;1002.1531805624236;1009.8384258518602;6013.009202362116;6022.728977196037;6036.5170786336985;6037.215162028908;6042.918111763032;5866.384029676908;6549.705263263165;6708.765193218214;6708.966455167693;6711.362420570038;5939.970300148471;6000.699965001732;6025.644230048291;6045.576809623284;6055.576109672369;3503.684668379807;3503.789772613651;3505.158761897177;3517.384457719889;3523.506709060682;5820.692687411285;6456;6747.932520674731;6832.692528836114;6874.725010999504;5720.856978575485;5772.94227057724;6008.3390827009;6813.977903314443;6861.656917154122;5489.533389661923;5991.7603295867675;6419.940709782927;6753.425958793558;6848.418538890788;6606.6036037837885;6676.866462670817;6707.396334329934;6708.597484150967;6741.22475915276;6397.680115994275;6537.836554086186;6615.239247486408;6635.402813746786;6659.267480577036;5604.439556044363;6741.820181468125;6790.42281406086;6792.93207067923;6864.5881247125335;3647.5987641358897;4303.354496825501;4364.286570503785;4379.408779814684;4379.562043795595;5800.332961709458;5838.737256823538;6536.640484773383;6558.475321974135;6631.005349197659;5430.2080313497245;6385.329540398191;6416.390442907917;6420.903686444613;6426.646534440651;5295.735213239245;5400.864978375572;6355.3644635536075;6442.456648843024;6493.188351456068;5210.6613070150315;5253.947460525499;5480.862978425571;6278.623282603058;6284.685765711788;6088.695565221809;6411.455026322815;6436;6459.998700201476;6494.421432889896;4962.652614316963;5710.200571920018;5797.797077102281;5853.03424934881;5913.438223368773 09:12:26 INFO - PID 1638 | |5;yui.html;706.149090346133;721.0271404560868;729.5185177782664;736.4219088015907;763.5151678684038;15707.764383534242;16036.679266414665;16271.105089220087;16276.674466510663;16278.511644650665;14530;15107.16910569921;15217.239138043109;15235.542933712006;15272.007319524224;946.2382781860582;978.0806042320196;979.6571200079951;983.1348413396202;983.8720966274375;960.2845879819545;992.4045572656385;993.5280741647696;993.6869885985927;997.825380558403;1065.7974984752873;1067.0983019348637;1069.5133714160077;1070.2829104499965;1071.3304184884448;1056.878458977216;1057.1120258982414;1057.439557034769;1061.2253055269618;1062.1290541755764;789.7907054630527;792.9246721561442;792.9841403171904;793.7420338390046;797.0634504457239;15186.848131518655;15543;15547.455839045579;16365.754513682305;16643.167841607992;14389.856101439062;14548.49080282185;14801;15022.24888755558;15496;11207.887921120766;11685.649430517098;11713.882861171449;11729.354885481294;11736.765264694659;3015.336625942289;3048.84755762211;3056.679048699899;3080.9691903080907;3196.792208506463;111100;115510.42244788806;115528.42235788851;117328.41335793266;118408.99999999914;10412.5834966602;10666.253362264722;10745.623903163352;11498.827517587242;11581.363025033705;25305.114320998677;25412.491750164892;25453.236402907944;25489.49021019569;25573.23280301594;7610.6194690265265;7783.532988020624;7819.139894611588;7898.328642065374;8047.315978141806;4167.1457351242925;4270.743755374688;4278.187144442546;4338.501072376654;4345.109252603209;4038.919221615551;4119.567445418248;4139.8965025874595;4142.984968682692;4149.688773342012;756.5082696247373;792.3819420851745;795.1094773853206;802.9879551806728;803.3091541274499;557.5567423898011;566.3628418029716;572.8539222498237;575.5251917168353;577.1602318626389;7995.560244186509;8508.887996867958;9739.366941148945;9780.804383912424;9854.950725246328;501.3795354080723;533.6931264522915;534.3801190618864;542.7693230377074;561.7809054468761;4466.7766611669285;4541.091781643684;4626.190416677068;4659.129838985987;4772.093302272557;80828;100920;106273;107918;110200;73721;93748.0625193753;95286.57070143953;95759.56360654595;97146.51426742821;77748;80721.19278807254;80924;81441.18558814455;82213;9078.45529268246;9166.724998250063;9221.49281789508;9343.837750414375;9529.332946693943;36107.63892361043;36410.45384319184;37482.625173749;37855.62144378521;37964.05089872775;30973.845130774203;48819;49418.258726118416;49699.50300496949;50486.49513504818;11404.828927565926;11626;11732.472038758106;11838.171328007302;12099.516019359127;12404.317762523147;12473.313967731861;12637.494500219887;13106.868931310946;13200.14199077057;11569.132315076571;11609.709757256136;11614.70963225926;11691.298522088702;11794.233374830608;11819.645410637695;11880.465379058136;11946.522139114339;12247.510099596273;12305.753884922073;10826.56693732242;11005.696365837644;11013.174011948975;11107.38909359993;11165.441727913409;8623.345235913835;8630.697925572575;8822.911770882209;8933.687320943736;9324.673636422693;7323.450741194325;8321.708740194064;9105.332481839196;9472.715818525456;9666.226701863694 09:12:26 INFO - PID 1638 | __end_tp_report 09:12:26 INFO - PID 1638 | __start_cc_report 09:12:26 INFO - PID 1638 | _x_x_mozilla_cycle_collect,1842 09:12:26 INFO - PID 1638 | __end_cc_report 09:12:26 INFO - PID 1638 | __startTimestamp1490803946221__endTimestamp 09:12:26 INFO - PID 1638 | 09:12:26 INFO - PID 1638 | ------- Summary: start ------- 09:12:26 INFO - PID 1638 | Number of tests: 6 09:12:26 INFO - PID 1638 | 09:12:26 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:6076.98 Median:6323.84 stddev:699.37 (11.1%) stddev-sans-first:653.73 09:12:26 INFO - PID 1638 | Values: 2838.3 3453.1 3480.8 3500.2 3517.3 6008.7 6098.6 6163.9 6186.4 6190.9 6123.8 6131.0 6188.1 6198.5 6202.2 5350.9 5488.0 5534.0 5579.2 5666.0 5575.0 5581.4 5608.0 5625.6 5627.8 5038.0 5403.9 5490.9 5491.2 5589.7 5519.8 5520.6 5555.6 5634.5 5644.1 5482.9 5484.1 5487.8 5500.5 5540.3 6056.5 6104.6 6206.1 6214.6 6239.7 6422.0 6514.0 6573.6 6590.2 6591.2 6573.4 6603.5 6612.9 6614.5 6617.4 6168.5 6179.1 6232.8 6258.8 6271.0 6541.1 6619.2 6632.8 6720.1 6734.2 6469.4 6474.6 6482.1 6486.2 6494.5 6571.6 6621.9 6628.7 6634.9 6658.1 6383.3 6452.8 6549.5 6557.9 6564.6 5812.8 6456.9 6541.6 6580.3 6581.2 5265.1 5274.9 5277.1 5280.4 5292.6 4660.6 4667.2 4669.8 4680.6 4683.9 6068.8 6144.9 6156.9 6161.0 6230.1 6490.6 6502.9 6507.5 6521.7 6536.5 6127.5 6128.0 6134.8 6137.2 6138.5 5642.9 5699.3 5703.5 5764.3 5776.2 6735.3 6882.3 6883.5 6886.1 6889.4 6444.9 6517.3 6523.5 6555.5 6694.1 6609.4 6699.3 6701.3 6719.1 6723.8 6431.1 6438.1 6445.0 6448.5 6452.7 6467.3 6529.8 6551.6 6606.4 6657.9 6393.2 6458.4 6463.5 6467.1 6485.9 5090.3 5805.8 5961.3 6472.0 6499.5 6211.8 6215.5 6265.5 6323.8 6382.6 6428.3 6481.8 6490.6 6497.9 6500.0 6340.0 6353.3 6358.8 6360.0 6380.7 09:12:26 INFO - PID 1638 | 09:12:26 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:54128.00 Median:34946.06 stddev:107584.63 (307.9%) stddev-sans-first:107811.91 09:12:26 INFO - PID 1638 | Values: 795.5 1114.8 1140.2 1148.2 1150.4 37760.0 38835.4 38849.4 38966.0 38976.2 41518.8 41822.0 41964.0 41989.7 41991.8 6159.9 6308.2 8611.3 8768.8 8775.7 6273.7 6364.9 6372.8 8468.6 8688.4 8676.8 8729.3 8742.9 8761.9 8764.1 8668.4 8781.6 8784.0 8804.6 8818.5 6142.6 6300.2 6300.3 8636.0 8738.4 28494.9 28755.7 37924.0 38309.4 38339.2 3034.8 3041.2 3060.8 3067.3 3088.9 28939.6 29381.9 29386.4 29409.0 29913.0 2719.6 2800.3 2835.8 2917.2 2936.8 322072.0 322372.4 322992.0 323144.0 323262.0 48914.0 48961.8 48973.8 48983.0 48994.0 52400.0 52514.7 52542.9 52555.7 52630.7 12272.1 14558.6 14723.2 14933.0 14942.6 12114.6 12115.6 12118.3 12122.3 12123.5 13061.9 13772.7 13774.0 13790.7 13903.6 4756.5 4773.4 5660.8 5720.7 5724.5 782.6 917.7 949.2 992.5 1003.5 23236.4 23497.4 24606.0 24954.4 25053.4 1072.8 1081.5 1108.0 1111.8 1125.5 12065.9 12704.0 12759.4 12792.4 12845.8 477742.0 479993.0 482577.0 483364.6 483377.0 58924.1 59208.1 59276.0 59282.0 59369.0 1307.4 1327.0 1500.3 1557.3 1557.9 24072.0 28918.7 29691.3 29691.9 29919.6 52968.5 53094.2 53120.0 53139.0 53155.5 394767.0 395254.0 395961.0 396351.0 397734.0 34137.0 34907.5 34984.7 35022.5 35037.0 44164.0 44261.7 44838.0 46176.0 46185.8 30296.9 30313.7 31263.5 31362.8 31399.4 22283.6 30959.7 31191.4 31296.5 31361.4 27692.6 32887.3 37252.8 37264.0 37302.0 28400.7 30047.5 30495.0 30789.2 30865.7 24356.0 24526.8 24558.5 24596.0 24606.6 09:12:26 INFO - PID 1638 | 09:12:26 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:38843.03 Median:21820.06 stddev:116151.57 (532.3%) stddev-sans-first:116441.50 09:12:26 INFO - PID 1638 | Values: 385.7 422.6 434.6 438.4 453.6 17290.0 20692.6 21499.8 21520.5 21778.7 20621.8 21861.5 21869.0 22260.8 22261.6 4325.9 6156.2 6372.5 6379.2 6383.4 5412.5 6333.9 6421.2 6435.8 6438.9 6089.5 6224.4 6303.0 6315.9 6392.2 6208.6 6254.6 6297.8 6305.4 6306.0 5318.0 5521.0 5523.4 5529.3 5534.0 25799.4 25865.5 25998.9 26052.3 26069.0 1970.1 1980.8 1993.1 2034.4 2036.8 12588.9 15257.9 15741.8 16289.7 16404.4 1635.9 1640.7 1662.8 1680.2 1720.6 129686.4 135729.0 136554.3 137670.9 139666.0 9248.0 11514.0 11555.4 11622.4 11749.6 12658.3 14643.0 14654.4 14655.4 14669.3 8741.0 8769.2 8781.0 8809.9 8905.8 8389.8 8430.5 8551.2 8593.0 8595.6 7129.4 7269.6 7269.9 7276.8 7285.7 2109.8 2137.7 2141.2 2142.1 2145.9 808.5 923.3 931.1 931.2 971.7 19767.6 20517.1 20601.4 20614.5 20716.7 974.6 976.3 1000.4 1013.5 1017.1 9481.1 10172.5 10237.2 10431.7 10577.5 687804.0 689410.0 701016.0 706825.0 708926.0 102351.0 128746.4 130796.0 132566.7 135031.0 1151.6 1160.9 1196.2 1196.3 1196.3 17449.0 17473.4 17474.2 17500.4 17930.8 56422.9 57457.4 57513.0 57558.7 58092.0 77547.0 79311.6 81894.0 87227.7 89706.6 7300.9 8332.4 8446.9 8631.6 8873.8 12402.5 13439.5 13442.7 13928.3 13960.7 11548.8 11631.2 11763.2 11899.5 11997.7 10718.2 10927.7 10934.6 11109.4 11233.6 11488.9 11541.8 11722.7 11961.8 12082.3 8531.7 8568.8 8628.3 8674.3 8683.5 9644.5 11392.3 11442.8 11754.9 11757.9 09:12:26 INFO - PID 1638 | 09:12:26 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:6780.06 Median:2495.19 stddev:4207.01 (168.6%) stddev-sans-first:4199.02 09:12:26 INFO - PID 1638 | Values: 1350.2 1408.3 1427.3 1435.0 1445.8 10645.0 11104.0 11111.1 11111.1 11114.7 10634.6 10657.8 11018.2 11027.9 11041.3 2440.8 2492.6 2492.8 2494.6 2494.6 2408.5 2413.5 2460.9 2462.6 2465.3 2387.8 2485.5 2488.3 2488.4 2490.2 2421.2 2457.1 2464.8 2465.9 2495.8 2410.9 2420.2 2424.4 2430.9 2440.1 10935.0 11028.8 11033.1 11054.8 11083.7 5366.4 6222.1 6457.4 6466.6 6491.5 3951.2 3989.1 4002.8 4014.2 4030.6 1174.4 1518.7 1564.3 1581.1 1645.2 12690.1 15246.3 15370.0 15432.4 15482.6 4406.6 5067.1 5366.8 5371.1 5406.4 11417.9 11435.3 11435.3 11437.5 11440.3 6262.9 6263.6 6279.8 6282.5 6291.2 5684.0 5790.9 5875.9 5884.4 5891.3 4026.1 4142.2 4158.5 4178.8 4179.7 1242.5 1243.3 1251.2 1262.8 1265.7 749.7 753.4 776.4 779.3 800.0 5203.9 5876.6 5885.7 6053.3 6110.7 553.2 581.4 589.2 589.7 594.6 3709.4 4085.9 4230.4 4308.7 4355.0 13538.0 14019.4 14023.2 14040.4 14044.1 13405.4 13925.7 13934.1 13987.4 14021.9 9968.9 10262.9 10277.0 10279.8 10306.2 6418.8 6459.9 6488.4 6666.8 6704.0 12185.8 12666.7 12824.6 12913.8 12968.6 12508.6 12864.9 12907.4 12934.3 12951.2 8728.3 9099.7 9103.5 9105.6 9107.9 9493.4 9913.3 10036.1 10050.5 10051.0 8234.5 8642.7 8691.9 8747.9 8788.0 8441.9 8467.9 8537.9 8643.7 8725.5 7646.7 7764.5 7873.6 7883.1 7884.2 4971.3 5762.7 5891.9 6044.1 6064.8 4732.6 5962.5 5998.9 6089.9 6139.6 09:12:26 INFO - PID 1638 | 09:12:26 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:5176.03 Median:5785.37 stddev:1589.94 (27.5%) stddev-sans-first:1553.29 09:12:26 INFO - PID 1638 | Values: 390.2 396.7 399.4 404.9 406.6 5112.9 6134.9 6264.6 6264.7 6303.1 4154.3 6022.8 6048.0 6100.2 6109.2 4338.2 4364.8 4760.0 4782.6 4786.3 3903.5 4773.5 4797.8 4805.6 4810.1 4395.5 4632.1 4751.1 4759.7 4761.5 3673.5 4387.7 4559.7 4705.2 4709.9 4146.2 4152.7 4153.5 4194.5 4202.9 4549.6 5984.9 5998.6 5998.8 6014.0 4900.4 5163.2 5475.9 5489.7 5501.2 5194.5 5656.7 6352.9 6546.4 6558.7 4225.5 4356.5 4363.8 4363.9 4373.2 6437.3 6602.9 6782.3 6797.2 6923.8 3347.3 4345.3 4415.4 4416.7 4419.9 3777.8 4471.5 4475.4 4477.6 4525.8 4454.8 4923.9 5112.7 5184.5 5330.4 5558.7 6294.9 6359.6 6380.5 6398.6 1505.8 1525.5 1531.0 1533.6 1535.3 987.8 995.6 1001.4 1002.2 1009.8 6013.0 6022.7 6036.5 6037.2 6042.9 5866.4 6549.7 6708.8 6709.0 6711.4 5940.0 6000.7 6025.6 6045.6 6055.6 3503.7 3503.8 3505.2 3517.4 3523.5 5820.7 6456.0 6747.9 6832.7 6874.7 5720.9 5772.9 6008.3 6814.0 6861.7 5489.5 5991.8 6419.9 6753.4 6848.4 6606.6 6676.9 6707.4 6708.6 6741.2 6397.7 6537.8 6615.2 6635.4 6659.3 5604.4 6741.8 6790.4 6792.9 6864.6 3647.6 4303.4 4364.3 4379.4 4379.6 5800.3 5838.7 6536.6 6558.5 6631.0 5430.2 6385.3 6416.4 6420.9 6426.6 5295.7 5400.9 6355.4 6442.5 6493.2 5210.7 5253.9 5480.9 6278.6 6284.7 6088.7 6411.5 6436.0 6460.0 6494.4 4962.7 5710.2 5797.8 5853.0 5913.4 09:12:26 INFO - PID 1638 | 09:12:26 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:19674.18 Median:4079.24 stddev:29541.05 (724.2%) stddev-sans-first:29589.59 09:12:26 INFO - PID 1638 | Values: 706.1 721.0 729.5 736.4 763.5 15707.8 16036.7 16271.1 16276.7 16278.5 14530.0 15107.2 15217.2 15235.5 15272.0 946.2 978.1 979.7 983.1 983.9 960.3 992.4 993.5 993.7 997.8 1065.8 1067.1 1069.5 1070.3 1071.3 1056.9 1057.1 1057.4 1061.2 1062.1 789.8 792.9 793.0 793.7 797.1 15186.8 15543.0 15547.5 16365.8 16643.2 14389.9 14548.5 14801.0 15022.2 15496.0 11207.9 11685.6 11713.9 11729.4 11736.8 3015.3 3048.8 3056.7 3081.0 3196.8 111100.0 115510.4 115528.4 117328.4 118409.0 10412.6 10666.3 10745.6 11498.8 11581.4 25305.1 25412.5 25453.2 25489.5 25573.2 7610.6 7783.5 7819.1 7898.3 8047.3 4167.1 4270.7 4278.2 4338.5 4345.1 4038.9 4119.6 4139.9 4143.0 4149.7 756.5 792.4 795.1 803.0 803.3 557.6 566.4 572.9 575.5 577.2 7995.6 8508.9 9739.4 9780.8 9855.0 501.4 533.7 534.4 542.8 561.8 4466.8 4541.1 4626.2 4659.1 4772.1 80828.0 100920.0 106273.0 107918.0 110200.0 73721.0 93748.1 95286.6 95759.6 97146.5 77748.0 80721.2 80924.0 81441.2 82213.0 9078.5 9166.7 9221.5 9343.8 9529.3 36107.6 36410.5 37482.6 37855.6 37964.1 30973.8 48819.0 49418.3 49699.5 50486.5 11404.8 11626.0 11732.5 11838.2 12099.5 12404.3 12473.3 12637.5 13106.9 13200.1 11569.1 11609.7 11614.7 11691.3 11794.2 11819.6 11880.5 11946.5 12247.5 12305.8 10826.6 11005.7 11013.2 11107.4 11165.4 8623.3 8630.7 8822.9 8933.7 9324.7 7323.5 8321.7 9105.3 9472.7 9666.2 09:12:26 INFO - PID 1638 | -------- Summary: end -------- 09:12:26 INFO - PID 1638 | 09:12:26 INFO - TEST-INFO | 1638: exit 0 09:12:26 INFO - TEST-OK | dromaeo_css | took 1120085ms 09:12:26 INFO - TEST-START | kraken 09:12:26 INFO - operating with platform_type : mac_ 09:12:26 INFO - Initialising browser for kraken test... 09:12:26 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpuDJyS1/profile http://localhost:49252/getInfo.html) 09:12:29 INFO - PID 1648 | __metrics Screen width/height:1600/1200 09:12:29 INFO - PID 1648 | colorDepth:24 09:12:29 INFO - PID 1648 | Browser inner width/height: 1024/689 09:12:29 INFO - PID 1648 | __metrics 09:12:29 ERROR - PID 1648 | 1490803949423 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:12:29 INFO - PID 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:12:29 INFO - PID 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:12:30 INFO - TEST-INFO | 1648: exit 0 09:12:30 INFO - Browser initialized. 09:12:30 INFO - Running cycle 1/1 for kraken test... 09:12:30 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpuDJyS1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:42 INFO - PID 1650 | RSS: Main: 234311680 09:12:42 INFO - PID 1650 | 09:12:45 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection) 09:12:46 INFO - PID 1650 | RSS: Main: 286433280 09:12:46 INFO - PID 1650 | 09:12:49 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-dft) 09:12:49 INFO - PID 1650 | RSS: Main: 287821824 09:12:49 INFO - PID 1650 | 09:12:53 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-fft) 09:12:54 INFO - PID 1650 | RSS: Main: 381853696 09:12:54 INFO - PID 1650 | 09:12:56 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator) 09:12:57 INFO - PID 1650 | RSS: Main: 283398144 09:12:57 INFO - PID 1650 | 09:12:59 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:12:59 INFO - PID 1650 | RSS: Main: 285499392 09:12:59 INFO - PID 1650 | 09:13:03 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom) 09:13:03 INFO - PID 1650 | RSS: Main: 328212480 09:13:03 INFO - PID 1650 | 09:13:06 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate) 09:13:07 INFO - PID 1650 | RSS: Main: 336076800 09:13:07 INFO - PID 1650 | 09:13:10 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial) 09:13:11 INFO - PID 1650 | RSS: Main: 338788352 09:13:11 INFO - PID 1650 | 09:13:13 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:13:13 INFO - PID 1650 | RSS: Main: 304922624 09:13:13 INFO - PID 1650 | 09:13:15 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:13:16 INFO - PID 1650 | RSS: Main: 309321728 09:13:16 INFO - PID 1650 | 09:13:18 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:13:19 INFO - PID 1650 | RSS: Main: 312041472 09:13:19 INFO - PID 1650 | 09:13:22 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:13:23 INFO - PID 1650 | RSS: Main: 328069120 09:13:23 INFO - PID 1650 | 09:13:26 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:13:27 INFO - PID 1650 | RSS: Main: 311291904 09:13:27 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49252/tests/kraken/driver.html?testName=ai-astar) 09:13:29 INFO - PID 1650 | RSS: Main: 377483264 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | __start_tp_report 09:13:29 INFO - PID 1650 | _x_x_mozilla_page_load 09:13:29 INFO - PID 1650 | _x_x_mozilla_page_load_details 09:13:29 INFO - PID 1650 | |i|pagename|runs| 09:13:29 INFO - PID 1650 | |0;ai-astar;110;121;120;120;121;120;120;120;122;121 09:13:29 INFO - PID 1650 | |1;audio-beat-detection;159;123;120;164;118;121;124;119;146;121 09:13:29 INFO - PID 1650 | |2;audio-dft;210;210;216;209;212;206;210;215;210;216 09:13:29 INFO - PID 1650 | |3;audio-fft;84;64;65;87;81;94;90;64;83;65 09:13:29 INFO - PID 1650 | |4;audio-oscillator;94;76;95;85;111;110;131;111;75;93 09:13:29 INFO - PID 1650 | |5;imaging-gaussian-blur;91;91;91;90;90;90;98;110;90;91 09:13:29 INFO - PID 1650 | |6;imaging-darkroom;100;101;101;102;109;100;102;101;103;101 09:13:29 INFO - PID 1650 | |7;imaging-desaturate;101;100;101;99;101;104;155;161;129;100 09:13:29 INFO - PID 1650 | |8;json-parse-financial;66;63;63;65;65;65;65;65;65;65 09:13:29 INFO - PID 1650 | |9;json-stringify-tinderbox;58;67;69;70;69;64;64;66;65;66 09:13:29 INFO - PID 1650 | |10;stanford-crypto-aes;91;91;91;91;91;89;87;90;89;91 09:13:29 INFO - PID 1650 | |11;stanford-crypto-ccm;169;176;149;161;153;170;175;168;189;163 09:13:29 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;254;264;204;205;202;251;216;206;200;200 09:13:29 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;73;74;73;74;73;73;72;70;75;74 09:13:29 INFO - PID 1650 | __end_tp_report 09:13:29 INFO - PID 1650 | __start_cc_report 09:13:29 INFO - PID 1650 | _x_x_mozilla_cycle_collect,2209 09:13:29 INFO - PID 1650 | __end_cc_report 09:13:29 INFO - PID 1650 | __startTimestamp1490804009478__endTimestamp 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | ------- Summary: start ------- 09:13:29 INFO - PID 1650 | Number of tests: 14 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:119.50 Median:120.50 stddev:3.41 (2.8%) stddev-sans-first:0.73 09:13:29 INFO - PID 1650 | Values: 110.0 121.0 120.0 120.0 121.0 120.0 120.0 120.0 122.0 121.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:123.50 stddev:17.77 (14.4%) stddev-sans-first:15.82 09:13:29 INFO - PID 1650 | Values: 159.0 123.0 120.0 164.0 118.0 121.0 124.0 119.0 146.0 121.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:211.40 Median:211.00 stddev:3.31 (1.6%) stddev-sans-first:3.47 09:13:29 INFO - PID 1650 | Values: 210.0 210.0 216.0 209.0 212.0 206.0 210.0 215.0 210.0 216.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:77.70 Median:83.50 stddev:11.93 (14.3%) stddev-sans-first:12.43 09:13:29 INFO - PID 1650 | Values: 84.0 64.0 65.0 87.0 81.0 94.0 90.0 64.0 83.0 65.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:98.10 Median:80.50 stddev:17.65 (21.9%) stddev-sans-first:18.66 09:13:29 INFO - PID 1650 | Values: 94.0 76.0 95.0 85.0 111.0 110.0 131.0 111.0 75.0 93.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:91.00 stddev:6.37 (7.0%) stddev-sans-first:6.71 09:13:29 INFO - PID 1650 | Values: 91.0 91.0 91.0 90.0 90.0 90.0 98.0 110.0 90.0 91.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:102.00 Median:101.50 stddev:2.62 (2.6%) stddev-sans-first:2.68 09:13:29 INFO - PID 1650 | Values: 100.0 101.0 101.0 102.0 109.0 100.0 102.0 101.0 103.0 101.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:115.10 Median:116.50 stddev:24.33 (20.9%) stddev-sans-first:25.26 09:13:29 INFO - PID 1650 | Values: 101.0 100.0 101.0 99.0 101.0 104.0 155.0 161.0 129.0 100.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:65.00 stddev:0.95 (1.5%) stddev-sans-first:0.88 09:13:29 INFO - PID 1650 | Values: 66.0 63.0 63.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.50 stddev:3.46 (5.2%) stddev-sans-first:2.24 09:13:29 INFO - PID 1650 | Values: 58.0 67.0 69.0 70.0 69.0 64.0 64.0 66.0 65.0 66.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:91.00 stddev:1.37 (1.5%) stddev-sans-first:1.41 09:13:29 INFO - PID 1650 | Values: 91.0 91.0 91.0 91.0 91.0 89.0 87.0 90.0 89.0 91.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:167.30 Median:169.50 stddev:11.61 (6.9%) stddev-sans-first:12.30 09:13:29 INFO - PID 1650 | Values: 169.0 176.0 149.0 161.0 153.0 170.0 175.0 168.0 189.0 163.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:220.20 Median:211.00 stddev:25.54 (12.1%) stddev-sans-first:23.98 09:13:29 INFO - PID 1650 | Values: 254.0 264.0 204.0 205.0 202.0 251.0 216.0 206.0 200.0 200.0 09:13:29 INFO - PID 1650 | 09:13:29 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:73.50 stddev:1.37 (1.9%) stddev-sans-first:1.45 09:13:29 INFO - PID 1650 | Values: 73.0 74.0 73.0 74.0 73.0 73.0 72.0 70.0 75.0 74.0 09:13:29 INFO - PID 1650 | -------- Summary: end -------- 09:13:29 INFO - PID 1650 | 09:13:30 INFO - TEST-INFO | 1650: exit 0 09:13:30 INFO - TEST-OK | kraken | took 63557ms 09:13:30 INFO - SUITE-END | took 1183s 09:13:30 INFO - Completed test suite (00:19:43) 09:13:30 INFO - javascript benchmark 09:13:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7597.559596011306, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2838.262051866515, 3453.119454539092, 3480.8433620487076, 3500.2474467989377, 3517.2613751112267, 6008.699565021753, 6098.613770999333, 6163.876722465554, 6186.350433204516, 6190.876182476353, 6123.770712314293, 6131.009681329152, 6188.102725104857, 6198.4731297839735, 6202.224721909762, 5350.866228344284, 5487.972560137214, 5534.03154447972, 5579.191017302489, 5665.971670141664, 5574.9721251393885, 5581.441855814406, 5608.046632072557, 5625.606207565472, 5627.831165065055, 5037.974810125963, 5403.864903377427, 5490.890182196374, 5491.176323551459, 5589.664620122806, 5519.751611177467, 5520.585956053312, 5555.611107222496, 5634.492895639371, 5644.096944488862, 5482.91775623366, 5484.067708489526, 5487.807926722546, 5500.532454741352, 5540.335159780811, 6056.515478761733, 6104.641056839901, 6206.100115483229, 6214.5960512566535, 6239.688015599202, 6421.967890160519, 6514.0, 6573.605583664996, 6590.242122155965, 6591.209054913441, 6573.375529324754, 6603.471722262208, 6612.900806487906, 6614.470842332602, 6617.437517810992, 6168.537359697995, 6179.073139029137, 6232.781852635136, 6258.780942666985, 6270.968645156745, 6541.0515475255825, 6619.238787539398, 6632.767853125214, 6720.092787473726, 6734.225564060098, 6469.385408386196, 6474.643894585749, 6482.092507049017, 6486.189226346707, 6494.512911531559, 6571.638559879157, 6621.9337806621315, 6628.734850605922, 6634.867302654016, 6658.1011563438315, 6383.265924418658, 6452.838679033061, 6549.476041916635, 6557.9344206557325, 6564.6389448580785, 5812.7674893003805, 6456.935430645727, 6541.6075035498025, 6580.309067547934, 6581.2102547694585, 5265.104932161542, 5274.92087618686, 5277.050130976461, 5280.419153893069, 5292.629515933848, 4660.572456166656, 4667.206574882278, 4669.789859456333, 4680.578747912704, 4683.882902927454, 6068.848278793065, 6144.941444616104, 6156.907646385308, 6160.969195154085, 6230.096635987757, 6490.610563366213, 6502.902456463157, 6507.479401647952, 6521.673916304166, 6536.509761767887, 6127.5404344674325, 6128.0, 6134.754609815646, 6137.171481849982, 6138.478229350555, 5642.915356269659, 5699.259096317535, 5703.54371650267, 5764.308283005982, 5776.220210271644, 6735.292794256533, 6882.311768823078, 6883.483738719516, 6886.070380498769, 6889.379955804002, 6444.871102577828, 6517.348265173445, 6523.543351965317, 6555.541112122106, 6694.129763130762, 6609.405153536205, 6699.263081060982, 6701.262861085179, 6719.126513553402, 6723.7646682365885, 6431.099646049463, 6438.098666186831, 6444.967775161094, 6448.5486015980305, 6452.709628066655, 6467.256265529524, 6529.804105876831, 6551.574147680385, 6606.4384527315715, 6657.900131498128, 6393.1688880445245, 6458.418742313214, 6463.482921366373, 6467.062275970052, 6485.902711459237, 5090.26191202281, 5805.82582522525, 5961.344252132175, 6472.0, 6499.5125365598715, 6211.81364559064, 6215.471684906653, 6265.467435267871, 6323.841903952438, 6382.6170429774365, 6428.260750013808, 6481.805545833632, 6490.578112422678, 6497.935020649922, 6499.967500162469, 6340.0, 6353.3011368748475, 6358.841028974128, 6360.045993101072, 6380.680965951777], "value": 6029.247969915604, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.4630624328579, 1114.7937631538164, 1140.21325285553, 1148.1905256793962, 1150.3500522204959, 37760.0, 38835.41746873799, 38849.417258741145, 38966.0, 38976.22047559047, 41518.792406038, 41822.0, 41964.0, 41989.74030779082, 41991.790041049826, 6159.8768024639485, 6308.179936608234, 8611.268042216403, 8768.780780480476, 8775.692850750225, 6273.68631568422, 6364.936350636503, 6372.840678983016, 8468.576571171448, 8688.391812573122, 8676.826463470727, 8729.34529910256, 8742.86885696712, 8761.912380876174, 8764.079771623983, 8668.436551624156, 8781.64873405063, 8783.956080219557, 8804.603792829337, 8818.514981675973, 6142.6007309525185, 6300.243970723543, 6300.30696623371, 8636.0, 8738.388312818106, 28494.857525712447, 28755.712442875512, 37924.0, 38309.42535861964, 38339.233215335815, 3034.848257587134, 3041.1788817019283, 3060.8469576521084, 3067.2638566744045, 3088.9382212355854, 28939.56590651142, 29381.853090734625, 29386.41227175466, 29409.0, 29913.0, 2719.619253304519, 2800.3419196489012, 2835.8440285784272, 2917.212352664769, 2936.8091074080116, 322072.0, 322372.38813806017, 322992.0, 323144.0, 323262.0, 48914.0, 48961.75519122381, 48973.755131224825, 48983.02033959372, 48994.0, 52400.0, 52514.73742631339, 52542.949141016965, 52555.73722131365, 52630.736846316286, 12272.140950133584, 14558.563243102724, 14723.190224537539, 14933.0, 14942.551723448314, 12114.575989840314, 12115.63653090391, 12118.333491658044, 12122.333271669966, 12123.515059397525, 13061.934690326678, 13772.655683607789, 13774.03581749288, 13790.724185516434, 13903.59361956307, 4756.524347565216, 4773.403324584427, 5660.830175094754, 5720.655645923205, 5724.542036637059, 782.6360742254878, 917.7338571814099, 949.1647350331664, 992.4888682328607, 1003.5032658833778, 23236.41908952256, 23497.412564685677, 24606.0, 24954.37614059663, 25053.37366565814, 1072.7747173093583, 1081.4808891732014, 1108.0, 1111.8054340490382, 1125.5385292030228, 12065.948788705766, 12703.96675894769, 12759.362031898369, 12792.424340904681, 12845.779650933146, 477742.0, 479993.0, 482577.0, 483364.5831770819, 483377.0, 58924.11613825796, 59208.11187832186, 59276.0, 59282.0, 59369.0, 1307.3789949773782, 1326.9601911942657, 1500.3248538157873, 1557.314781496138, 1557.906525608467, 24072.0, 28918.710812892445, 29691.257718557208, 29691.85154074216, 29919.55120673148, 52968.470315296356, 53094.203586947, 53120.0, 53139.0, 53155.4684453166, 394767.0, 395254.0237298795, 395961.0, 396351.0, 397734.0113299415, 34137.0, 34907.4763878537, 34984.650153498136, 35022.47466287957, 35037.0, 44164.0, 44261.672149835555, 44838.0, 46176.0, 46185.76699631128, 30296.939607113643, 30313.696863031088, 31263.53104703386, 31362.843185783924, 31399.372012559164, 22283.554328913655, 30959.69040309568, 31191.376172476877, 31296.530552041284, 31361.372772544877, 27692.584611230446, 32887.34225315528, 37252.813735931144, 37264.0, 37302.0, 28400.715992839807, 30047.549286760277, 30495.0, 30789.23026924255, 30865.69134308628, 24356.0, 24526.754732452446, 24558.508829823662, 24595.954297247685, 24606.630900536147], "value": 17947.68760268235, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.7087898636529, 422.57275084199557, 434.6479351725102, 438.37750394439894, 453.5962992936287, 17290.0, 20692.586148277045, 21499.785002149994, 21520.461988450297, 21778.673319900176, 20621.793782062174, 21861.453463663416, 21869.015894284756, 22260.77565733888, 22261.55476890461, 4325.913481730364, 6156.199694039779, 6372.522060845432, 6379.202599675041, 6383.425491705745, 5412.485813847678, 6333.904991425133, 6421.165248517686, 6435.839104022413, 6438.935610643904, 6089.545082212869, 6224.377562243762, 6302.968485157568, 6315.905261421082, 6392.16901802765, 6208.596441231351, 6254.562180647356, 6297.779577714804, 6305.432511073979, 6306.0, 5318.0, 5520.972395138038, 5523.392426833045, 5529.253550770634, 5534.03154447974, 25799.35501612456, 25865.482690346274, 25998.87000564985, 26052.34869128268, 26069.0, 1970.1331414177716, 1980.7821139674625, 1993.0532996826391, 2034.3591768592846, 2036.775954644988, 12588.937055314665, 15257.923710381378, 15741.842581574267, 16289.674206515803, 16404.425845095364, 1635.8854880158394, 1640.729279668865, 1662.7755253040805, 1680.201904095557, 1720.6214632780768, 129686.35156824156, 135729.0, 136554.3172284142, 137670.93493597704, 139666.0, 9247.95376023129, 11514.0, 11555.422228888521, 11622.418879056013, 11749.647510574696, 12658.303793291274, 14643.048201866844, 14654.413823446943, 14655.387907330185, 14669.33987970544, 8741.0, 8769.21077103051, 8780.958665964064, 8809.911900881038, 8905.821883562292, 8389.832203356022, 8430.45202061864, 8551.230389264996, 8593.011803642536, 8595.570221489026, 7129.429645628337, 7269.563826170447, 7269.927300727031, 7276.8180795479475, 7285.6885760562545, 2109.765787014583, 2137.7220961274934, 2141.2184552638487, 2142.0896119149297, 2145.8712477251415, 808.4866110020062, 923.2752289452748, 931.1014870649856, 931.2084727981297, 971.6647756523988, 19767.60464790725, 20517.076731547117, 20601.381958541268, 20614.484637884172, 20716.689249660965, 974.6004138303404, 976.3117002513211, 1000.4247557654378, 1013.5084484025242, 1017.1151098544314, 9481.051894810602, 10172.542235599267, 10237.23220758446, 10431.739206519898, 10577.471126443646, 687804.0, 689410.0, 701016.0, 706825.0, 708926.0, 102351.0, 128746.35626821806, 130796.0, 132566.67433325737, 135031.0, 1151.633330759134, 1160.9245399049034, 1196.16866277936, 1196.252342286071, 1196.342011893455, 17449.0, 17473.388431405347, 17474.21366038557, 17500.387486437914, 17930.820691792916, 56422.87154256974, 57457.42542574688, 57513.0, 57558.7122064387, 58092.0, 77547.0, 79311.60344198241, 81894.0, 87227.691584625, 89706.55146724224, 7300.890486642598, 8332.375071869512, 8446.873296900674, 8631.56842157902, 8873.778155546162, 12402.503899844267, 13439.529616463376, 13442.663933401353, 13928.303584820516, 13960.650983725489, 11548.769024619629, 11631.185816993067, 11763.176577639482, 11899.524019039141, 11997.700057498632, 10718.196135289729, 10927.672169834917, 10934.617288394868, 11109.444527773418, 11233.584779272496, 11488.885111148782, 11541.82687259675, 11722.70693232676, 11961.820572691591, 12082.275063496218, 8531.74404767858, 8568.785780355542, 8628.266597339296, 8674.349423793115, 8683.478991260545, 9644.469554174588, 11392.316461012035, 11442.828357574477, 11754.941225293818, 11757.882421175678], "value": 10086.329936575637, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.2371160294433, 1408.3169662713583, 1427.3434220258673, 1434.9569512914616, 1445.8481859404758, 10645.0, 11104.000639942413, 11111.055560277366, 11111.055560277366, 11114.666560003192, 10634.57461701531, 10657.84013239802, 11018.173636977219, 11027.897210278968, 11041.282316649433, 2440.7559244075583, 2492.588722860735, 2492.8005759539283, 2494.5759220932396, 2494.6133349330885, 2408.4821763320883, 2413.5414271288487, 2460.876956152194, 2462.5936720441105, 2465.272744540356, 2387.7612238776155, 2485.5153245117117, 2488.2783992642203, 2488.390344365633, 2490.1657944588583, 2421.2494126820566, 2457.127719659513, 2464.8397854139425, 2465.938351541217, 2495.762902524248, 2410.879456027209, 2420.1771397724856, 2424.4060205249825, 2430.890609922558, 2440.1093600835743, 10935.0, 11028.834567481414, 11033.062189713884, 11054.778904421946, 11083.72290692731, 5366.436524164985, 6222.097795819581, 6457.4188323050685, 6466.612003279772, 6491.480681545512, 3951.1900060487533, 3989.085239085255, 4002.7598344099156, 4014.1570270243283, 4030.6372426481757, 1174.401055461712, 1518.7038527487146, 1564.2726132348398, 1581.1066747287746, 1645.2267434305857, 12690.11169218155, 15246.313915873812, 15370.0, 15432.3827046918, 15482.61293467661, 4406.647468202536, 5067.087924173684, 5366.7853285868905, 5371.113766228606, 5406.432324605938, 11417.885821141683, 11435.256708313933, 11435.313881167156, 11437.485313160927, 11440.313581185155, 6262.906056409158, 6263.6241825490615, 6279.811605651837, 6282.4974002080635, 6291.245050593958, 5684.033714268584, 5790.855228619234, 5875.941240587625, 5884.382139875337, 5891.322497912709, 4026.051506413455, 4142.171565686875, 4158.459400278005, 4178.832846686159, 4179.749215047107, 1242.45332053896, 1243.3144408078506, 1251.2429979862234, 1262.7663882181641, 1265.702559898423, 749.6701451361383, 753.408574269196, 776.4176867349488, 779.3336697123991, 800.0319613267894, 5203.895922081537, 5876.6180198286975, 5885.705714714247, 6053.334133245339, 6110.725017374229, 553.1895772693024, 581.3517927510795, 589.2457654202627, 589.6963064022032, 594.6135012241991, 3709.4435834625024, 4085.8774236772942, 4230.428892099527, 4308.67684923632, 4354.9564504355185, 13538.0, 14019.439222431192, 14023.158610483404, 14040.438382464792, 14044.087134336236, 13405.396757145953, 13925.721485570231, 13934.094283871516, 13987.440502379994, 14021.85978140226, 9968.900310996798, 10262.897371026344, 10277.0, 10279.845802312822, 10306.227032972407, 6418.807435776934, 6459.9031014533875, 6488.383603557654, 6666.766663166766, 6704.0, 12185.817212741993, 12666.746665066832, 12824.615261542169, 12913.806292905427, 12968.610941671765, 12508.624741257778, 12864.935675321563, 12907.419166137359, 12934.288614126313, 12951.222926624432, 8728.258098061737, 9099.681511147079, 9103.453792772454, 9105.635774569208, 9107.863382049407, 9493.430394176372, 9913.30606857513, 10036.096751292418, 10050.49747512636, 10050.994900510183, 8234.547099909563, 8642.654293828176, 8691.86962195555, 8747.91252087471, 8787.956060219658, 8441.915580844114, 8467.872981905153, 8537.87193192115, 8643.654253829776, 8725.52009639476, 7646.694132234648, 7764.456488045783, 7873.606319683879, 7883.093444253984, 7884.2115788423, 4971.279164521196, 5762.654240745569, 5891.911621325769, 6044.123602077762, 6064.787732429344, 4732.574068333866, 5962.522998160223, 5998.910016349845, 6089.908651370321, 6139.570230083851], "value": 5142.553307529146, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.23513912731045, 396.7341880939769, 399.38095951275534, 404.9493813273341, 406.5974685061788, 5112.923306150401, 6134.938650613492, 6264.561480696353, 6264.749410023605, 6303.109067279815, 4154.252234597771, 6022.75908963642, 6047.971844786386, 6100.176476175715, 6109.236345456818, 4338.2191205582985, 4364.799680087969, 4760.000399916022, 4782.617390608761, 4786.282057691339, 3903.512060992376, 4773.474917759044, 4797.808087676489, 4805.591524720386, 4810.110129626013, 4395.516493185747, 4632.0967411354795, 4751.121042607128, 4759.690620109699, 4761.50004249554, 3673.485712000322, 4387.7367357958465, 4559.654901803976, 4705.176594096037, 4709.905801883943, 4146.212219678251, 4152.709310348277, 4153.501579810412, 4194.50519819035, 4202.92825329539, 4549.590536851666, 5984.9401505984815, 5998.580099393044, 5998.760049598054, 6013.9699301503215, 4900.411950565955, 5163.18606359535, 5475.917861232085, 5489.6980666063355, 5501.174823776426, 5194.454582268883, 5656.717164141776, 6352.855336608362, 6546.410823025951, 6558.672066396661, 4225.492940847087, 4356.542563030899, 4363.80362883671, 4363.890902727426, 4373.1851281718045, 6437.324080971477, 6602.9296377184355, 6782.253952065269, 6797.150356205474, 6923.792286231421, 3347.280334728045, 4345.283028300295, 4415.40392047076, 4416.674997500737, 4419.9337009944875, 3777.8299976501125, 4471.473617406155, 4475.440569928759, 4477.597016268552, 4525.796339164745, 4454.774936892316, 4923.852284431473, 5112.693238405707, 5184.533391994739, 5330.387005494418, 5558.749856256478, 6294.859747685718, 6359.618422894641, 6380.457661098766, 6398.5521013528605, 1505.8193016837831, 1525.4660868695867, 1530.9943973394888, 1533.562934563644, 1535.3091109000993, 987.7530617345664, 995.6117114325419, 1001.409168590535, 1002.1531805624236, 1009.8384258518602, 6013.009202362116, 6022.728977196037, 6036.5170786336985, 6037.215162028908, 6042.918111763032, 5866.384029676908, 6549.705263263165, 6708.765193218214, 6708.966455167693, 6711.362420570038, 5939.970300148471, 6000.699965001732, 6025.644230048291, 6045.576809623284, 6055.576109672369, 3503.684668379807, 3503.789772613651, 3505.158761897177, 3517.384457719889, 3523.506709060682, 5820.692687411285, 6456.0, 6747.932520674731, 6832.692528836114, 6874.725010999504, 5720.856978575485, 5772.94227057724, 6008.3390827009, 6813.977903314443, 6861.656917154122, 5489.533389661923, 5991.7603295867675, 6419.940709782927, 6753.425958793558, 6848.418538890788, 6606.6036037837885, 6676.866462670817, 6707.396334329934, 6708.597484150967, 6741.22475915276, 6397.680115994275, 6537.836554086186, 6615.239247486408, 6635.402813746786, 6659.267480577036, 5604.439556044363, 6741.820181468125, 6790.42281406086, 6792.93207067923, 6864.5881247125335, 3647.5987641358897, 4303.354496825501, 4364.286570503785, 4379.408779814684, 4379.562043795595, 5800.332961709458, 5838.737256823538, 6536.640484773383, 6558.475321974135, 6631.005349197659, 5430.2080313497245, 6385.329540398191, 6416.390442907917, 6420.903686444613, 6426.646534440651, 5295.735213239245, 5400.864978375572, 6355.3644635536075, 6442.456648843024, 6493.188351456068, 5210.6613070150315, 5253.947460525499, 5480.862978425571, 6278.623282603058, 6284.685765711788, 6088.695565221809, 6411.455026322815, 6436.0, 6459.998700201476, 6494.421432889896, 4962.652614316963, 5710.200571920018, 5797.797077102281, 5853.03424934881, 5913.438223368773], "value": 4687.709566308084, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.149090346133, 721.0271404560868, 729.5185177782664, 736.4219088015907, 763.5151678684038, 15707.764383534242, 16036.679266414665, 16271.105089220087, 16276.674466510663, 16278.511644650665, 14530.0, 15107.16910569921, 15217.239138043109, 15235.542933712006, 15272.007319524224, 946.2382781860582, 978.0806042320196, 979.6571200079951, 983.1348413396202, 983.8720966274375, 960.2845879819545, 992.4045572656385, 993.5280741647696, 993.6869885985927, 997.825380558403, 1065.7974984752873, 1067.0983019348637, 1069.5133714160077, 1070.2829104499965, 1071.3304184884448, 1056.878458977216, 1057.1120258982414, 1057.439557034769, 1061.2253055269618, 1062.1290541755764, 789.7907054630527, 792.9246721561442, 792.9841403171904, 793.7420338390046, 797.0634504457239, 15186.848131518655, 15543.0, 15547.455839045579, 16365.754513682305, 16643.167841607992, 14389.856101439062, 14548.49080282185, 14801.0, 15022.24888755558, 15496.0, 11207.887921120766, 11685.649430517098, 11713.882861171449, 11729.354885481294, 11736.765264694659, 3015.336625942289, 3048.84755762211, 3056.679048699899, 3080.9691903080907, 3196.792208506463, 111100.0, 115510.42244788806, 115528.42235788851, 117328.41335793266, 118408.99999999914, 10412.5834966602, 10666.253362264722, 10745.623903163352, 11498.827517587242, 11581.363025033705, 25305.114320998677, 25412.491750164892, 25453.236402907944, 25489.49021019569, 25573.23280301594, 7610.6194690265265, 7783.532988020624, 7819.139894611588, 7898.328642065374, 8047.315978141806, 4167.1457351242925, 4270.743755374688, 4278.187144442546, 4338.501072376654, 4345.109252603209, 4038.919221615551, 4119.567445418248, 4139.8965025874595, 4142.984968682692, 4149.688773342012, 756.5082696247373, 792.3819420851745, 795.1094773853206, 802.9879551806728, 803.3091541274499, 557.5567423898011, 566.3628418029716, 572.8539222498237, 575.5251917168353, 577.1602318626389, 7995.560244186509, 8508.887996867958, 9739.366941148945, 9780.804383912424, 9854.950725246328, 501.3795354080723, 533.6931264522915, 534.3801190618864, 542.7693230377074, 561.7809054468761, 4466.7766611669285, 4541.091781643684, 4626.190416677068, 4659.129838985987, 4772.093302272557, 80828.0, 100920.0, 106273.0, 107918.0, 110200.0, 73721.0, 93748.0625193753, 95286.57070143953, 95759.56360654595, 97146.51426742821, 77748.0, 80721.19278807254, 80924.0, 81441.18558814455, 82213.0, 9078.45529268246, 9166.724998250063, 9221.49281789508, 9343.837750414375, 9529.332946693943, 36107.63892361043, 36410.45384319184, 37482.625173749, 37855.62144378521, 37964.05089872775, 30973.845130774203, 48819.0, 49418.258726118416, 49699.50300496949, 50486.49513504818, 11404.828927565926, 11626.0, 11732.472038758106, 11838.171328007302, 12099.516019359127, 12404.317762523147, 12473.313967731861, 12637.494500219887, 13106.868931310946, 13200.14199077057, 11569.132315076571, 11609.709757256136, 11614.70963225926, 11691.298522088702, 11794.233374830608, 11819.645410637695, 11880.465379058136, 11946.522139114339, 12247.510099596273, 12305.753884922073, 10826.56693732242, 11005.696365837644, 11013.174011948975, 11107.38909359993, 11165.441727913409, 8623.345235913835, 8630.697925572575, 8822.911770882209, 8933.687320943736, 9324.673636422693, 7323.450741194325, 8321.708740194064, 9105.332481839196, 9472.715818525456, 9666.226701863694], "value": 7309.161489394993, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1629.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 121.0, 120.0, 120.0, 121.0, 120.0, 120.0, 120.0, 122.0, 121.0], "value": 119.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 123.0, 120.0, 164.0, 118.0, 121.0, 124.0, 119.0, 146.0, 121.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 210.0, 216.0, 209.0, 212.0, 206.0, 210.0, 215.0, 210.0, 216.0], "value": 211.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 64.0, 65.0, 87.0, 81.0, 94.0, 90.0, 64.0, 83.0, 65.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 76.0, 95.0, 85.0, 111.0, 110.0, 131.0, 111.0, 75.0, 93.0], "value": 98.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 90.0, 90.0, 90.0, 98.0, 110.0, 90.0, 91.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 101.0, 101.0, 102.0, 109.0, 100.0, 102.0, 101.0, 103.0, 101.0], "value": 102.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 100.0, 101.0, 99.0, 101.0, 104.0, 155.0, 161.0, 129.0, 100.0], "value": 115.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 63.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 67.0, 69.0, 70.0, 69.0, 64.0, 64.0, 66.0, 65.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 91.0, 91.0, 89.0, 87.0, 90.0, 89.0, 91.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 176.0, 149.0, 161.0, 153.0, 170.0, 175.0, 168.0, 189.0, 163.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [254.0, 264.0, 204.0, 205.0, 202.0, 251.0, 216.0, 206.0, 200.0, 200.0], "value": 220.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 74.0, 73.0, 73.0, 72.0, 70.0, 75.0, 74.0], "value": 73.1, "unit": "score"}]}]} 09:13:30 INFO - Return code: 0 09:13:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:13:30 ERROR - # TBPL SUCCESS # 09:13:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:13:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:13:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:13:30 INFO - 2017-03-29 09:13:30.807 screenresolution[1654:9962] starting screenresolution argv=screenresolution get 09:13:30 INFO - 2017-03-29 09:13:30.809 screenresolution[1654:9962] Display 0: 1600x1200x32@60 09:13:30 INFO - 2017-03-29 09:13:30.815 screenresolution[1655:9965] starting screenresolution argv=screenresolution list 09:13:30 INFO - Available Modes on Display 0 09:13:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:13:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:13:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:13:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:13:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:13:31 INFO - Intel Iris: 09:13:31 INFO - Chipset Model: Intel Iris 09:13:31 INFO - Type: GPU 09:13:31 INFO - Bus: Built-In 09:13:31 INFO - VRAM (Dynamic, Max): 1536 MB 09:13:31 INFO - Vendor: Intel (0x8086) 09:13:31 INFO - Device ID: 0x0a2e 09:13:31 INFO - Revision ID: 0x0009 09:13:31 INFO - Displays: 09:13:31 INFO - Display: 09:13:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:13:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:13:31 INFO - Display Serial Number: 1600x1200 60 09:13:31 INFO - Main Display: Yes 09:13:31 INFO - Mirror: Off 09:13:31 INFO - Online: Yes 09:13:31 INFO - Rotation: Supported 09:13:31 INFO - Return code: 0 09:13:31 INFO - Running post-action listener: _resource_record_post_action 09:13:31 INFO - [mozharness: 2017-03-29 16:13:31.066013Z] Finished run-tests step (success) 09:13:31 INFO - Running post-run listener: _resource_record_post_run 09:13:31 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 39609344; Write bytes: 514337792; Read time: 385; Write time: 1557 09:13:31 INFO - TinderboxPrint: CPU usage
53.4% 09:13:31 INFO - TinderboxPrint: I/O read bytes / time
39,609,344 / 385 09:13:31 INFO - TinderboxPrint: I/O write bytes / time
514,337,792 / 1,557 09:13:31 INFO - TinderboxPrint: CPU idle
2,247.5 (46.6%) 09:13:31 INFO - TinderboxPrint: CPU system
957.6 (19.9%) 09:13:31 INFO - TinderboxPrint: CPU user
1,616.7 (33.5%) 09:13:31 INFO - TinderboxPrint: Swap in / out
250,601,472 / 0 09:13:31 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205097472; Write bytes: 204630016; Read time: 15830; Write time: 441 09:13:31 INFO - run-tests - Wall time: 1185s; CPU: 53.0%; Read bytes: 38605824; Write bytes: 299742208; Read time: 370; Write time: 1087 09:13:31 INFO - Running post-run listener: _upload_blobber_files 09:13:31 INFO - Blob upload gear active. 09:13:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:13:31 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 09:13:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:13:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:13:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:13:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:13:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:33 INFO - (blobuploader) - INFO - Done attempting. 09:13:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:13:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:13:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:34 INFO - (blobuploader) - INFO - Done attempting. 09:13:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:13:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:13:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:35 INFO - (blobuploader) - INFO - Done attempting. 09:13:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:13:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:13:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:13:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:13:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:13:37 INFO - (blobuploader) - INFO - Done attempting. 09:13:37 INFO - (blobuploader) - INFO - Iteration through files over. 09:13:37 INFO - Return code: 0 09:13:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:13:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:13:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63dc1c784a9bfafc763c72e35fa851f22f43f848327b8c4cf2b71080e4705a2f11a0c73c98fec8ebeab15e6462e2bdf697c6f2813f65e94f51dddd41d7cca2a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b88240137b520f6446986d35714f9b7aabf6a132975941e3c3f285868c64619cfcb673590aba2f5c284f01782e47d939808feeb55d1ff98185bafd9a8c58f6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40a071ec53d76f1ab6029e6c52fd2c7e2fad72922f186b77df9bab250f9336a11bc7ee6b27348c3442e5f55eb08e5aa053e2e14c8129900ea20f2ad06a05297e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff11579c73d6c7bb2257d57d7afd0e8efbaecda72430a193df0c1752b38a5be6cf6763dc68aa7900a3978e9338a8a6cf422e5b14fc48ff575856ee02bb2ac0f1"} 09:13:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:13:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:13:37 INFO - Contents: 09:13:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63dc1c784a9bfafc763c72e35fa851f22f43f848327b8c4cf2b71080e4705a2f11a0c73c98fec8ebeab15e6462e2bdf697c6f2813f65e94f51dddd41d7cca2a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b88240137b520f6446986d35714f9b7aabf6a132975941e3c3f285868c64619cfcb673590aba2f5c284f01782e47d939808feeb55d1ff98185bafd9a8c58f6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40a071ec53d76f1ab6029e6c52fd2c7e2fad72922f186b77df9bab250f9336a11bc7ee6b27348c3442e5f55eb08e5aa053e2e14c8129900ea20f2ad06a05297e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff11579c73d6c7bb2257d57d7afd0e8efbaecda72430a193df0c1752b38a5be6cf6763dc68aa7900a3978e9338a8a6cf422e5b14fc48ff575856ee02bb2ac0f1"} 09:13:37 INFO - Running post-run listener: copy_logs_to_upload_dir 09:13:37 INFO - Copying logs to upload dir... 09:13:37 INFO - mkdir: /builds/slave/test/build/upload/logs 09:13:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.983537 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-29 09:13:37.391712) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 09:13:37.396714) ========= 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.iDas4Rev0J/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.Av09v9Kr6G/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63dc1c784a9bfafc763c72e35fa851f22f43f848327b8c4cf2b71080e4705a2f11a0c73c98fec8ebeab15e6462e2bdf697c6f2813f65e94f51dddd41d7cca2a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b88240137b520f6446986d35714f9b7aabf6a132975941e3c3f285868c64619cfcb673590aba2f5c284f01782e47d939808feeb55d1ff98185bafd9a8c58f6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40a071ec53d76f1ab6029e6c52fd2c7e2fad72922f186b77df9bab250f9336a11bc7ee6b27348c3442e5f55eb08e5aa053e2e14c8129900ea20f2ad06a05297e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff11579c73d6c7bb2257d57d7afd0e8efbaecda72430a193df0c1752b38a5be6cf6763dc68aa7900a3978e9338a8a6cf422e5b14fc48ff575856ee02bb2ac0f1"} build_url:https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011023 build_url: 'https://queue.taskcluster.net/v1/task/QHqIV52IRQKGMf9iwtWspw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63dc1c784a9bfafc763c72e35fa851f22f43f848327b8c4cf2b71080e4705a2f11a0c73c98fec8ebeab15e6462e2bdf697c6f2813f65e94f51dddd41d7cca2a2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b88240137b520f6446986d35714f9b7aabf6a132975941e3c3f285868c64619cfcb673590aba2f5c284f01782e47d939808feeb55d1ff98185bafd9a8c58f6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40a071ec53d76f1ab6029e6c52fd2c7e2fad72922f186b77df9bab250f9336a11bc7ee6b27348c3442e5f55eb08e5aa053e2e14c8129900ea20f2ad06a05297e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff11579c73d6c7bb2257d57d7afd0e8efbaecda72430a193df0c1752b38a5be6cf6763dc68aa7900a3978e9338a8a6cf422e5b14fc48ff575856ee02bb2ac0f1"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 09:13:37.435639) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 09:13:37.435974) ========= 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.iDas4Rev0J/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.Av09v9Kr6G/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.004961 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 09:13:37.487115) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 09:13:37.490561) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 09:13:37.491350) ========= ========= Total master_lag: 0.22 =========