builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0143 starttime: 1486438505.95 results: success (0) buildid: 20170206175856 builduid: a79239180cfa49528d8bc2b6b5e127e2 revision: fcc32735c01d504767a0dee94c6bc706b6a23b5e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:05.953660) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:05.954140) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:05.954415) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:05.979903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:05.980283) ========= 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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-06 19:35:05-- 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% 7.99M=0.001s 2017-02-06 19:35:06 (7.99 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.160438 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:06.164253) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:06.164600) ========= 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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.020994 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:06.213949) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 19:35:06.214336) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fcc32735c01d504767a0dee94c6bc706b6a23b5e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fcc32735c01d504767a0dee94c6bc706b6a23b5e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-06 19:35:06,294 truncating revision to first 12 chars 2017-02-06 19:35:06,294 Setting DEBUG logging. 2017-02-06 19:35:06,294 attempt 1/10 2017-02-06 19:35:06,294 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fcc32735c01d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-06 19:35:07,610 unpacking tar archive at: mozilla-aurora-fcc32735c01d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.569048 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 19:35:07.804151) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:07.804468) ========= script_repo_revision: fcc32735c01d504767a0dee94c6bc706b6a23b5e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:07.804851) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:07.805113) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 19:35:07.823672) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-06 19:35:07.823979) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 19:35:08 INFO - MultiFileLogger online at 20170206 19:35:08 in /builds/slave/test 19:35:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 19:35:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:35:08 INFO - {'append_to_log': False, 19:35:08 INFO - 'base_work_dir': '/builds/slave/test', 19:35:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:35:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:35:08 INFO - 'branch': 'Mozilla-Aurora', 19:35:08 INFO - 'buildbot_json_path': 'buildprops.json', 19:35:08 INFO - 'config_files': ('talos/mac_config.py',), 19:35:08 INFO - 'default_actions': ('clobber', 19:35:08 INFO - 'read-buildbot-config', 19:35:08 INFO - 'download-and-extract', 19:35:08 INFO - 'populate-webroot', 19:35:08 INFO - 'create-virtualenv', 19:35:08 INFO - 'install', 19:35:08 INFO - 'run-tests'), 19:35:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:35:08 INFO - 'download_minidump_stackwalk': True, 19:35:08 INFO - 'download_symbols': 'ondemand', 19:35:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:35:08 INFO - 'tooltool.py': '/tools/tooltool.py', 19:35:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:35:08 INFO - '/tools/misc-python/virtualenv.py')}, 19:35:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:35:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:35:08 INFO - 'installer_path': 'installer.exe', 19:35:08 INFO - 'log_level': 'info', 19:35:08 INFO - 'log_name': 'talos', 19:35:08 INFO - 'log_to_console': True, 19:35:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:35:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:35:08 INFO - 'opt_config_files': (), 19:35:08 INFO - 'pip_index': False, 19:35:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:35:08 INFO - 'cmd': ('bash', 19:35:08 INFO - '-c', 19:35:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:35:08 INFO - 'enabled': True, 19:35:08 INFO - 'halt_on_failure': False, 19:35:08 INFO - 'name': 'check_screen_resolution'},), 19:35:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:35:08 INFO - 'cmd': ('bash', 19:35:08 INFO - '-c', 19:35:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:35:08 INFO - 'enabled': True, 19:35:08 INFO - 'halt_on_failure': False, 19:35:08 INFO - 'name': 'check_screen_resolution'},), 19:35:08 INFO - 'run_cmd_checks_enabled': True, 19:35:08 INFO - 'sps_profile': False, 19:35:08 INFO - 'sps_profile_interval': 0, 19:35:08 INFO - 'suite': 'dromaeojs', 19:35:08 INFO - 'system_bits': '32', 19:35:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:35:08 INFO - 'title': 't-yosemite-r7-0143', 19:35:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:35:08 INFO - 'use_talos_json': True, 19:35:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:35:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:35:08 INFO - 'work_dir': 'build'} 19:35:08 INFO - [mozharness: 2017-02-07 03:35:08.017154Z] Running clobber step. 19:35:08 INFO - Running pre-action listener: _resource_record_pre_action 19:35:08 INFO - Running main action method: clobber 19:35:08 INFO - rmtree: /builds/slave/test/build 19:35:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:35:10 INFO - Running post-action listener: _resource_record_post_action 19:35:10 INFO - [mozharness: 2017-02-07 03:35:10.028219Z] Finished clobber step (success) 19:35:10 INFO - [mozharness: 2017-02-07 03:35:10.028589Z] Running read-buildbot-config step. 19:35:10 INFO - Running pre-action listener: _resource_record_pre_action 19:35:10 INFO - Running main action method: read_buildbot_config 19:35:10 INFO - Using buildbot properties: 19:35:10 INFO - { 19:35:10 INFO - "project": "", 19:35:10 INFO - "product": "firefox", 19:35:10 INFO - "script_repo_revision": "production", 19:35:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 19:35:10 INFO - "repository": "", 19:35:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 19:35:10 INFO - "buildid": "20170206175856", 19:35:10 INFO - "pgo_build": "False", 19:35:10 INFO - "basedir": "/builds/slave/test", 19:35:10 INFO - "buildnumber": 52, 19:35:10 INFO - "slavename": "t-yosemite-r7-0143", 19:35:10 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:35:10 INFO - "platform": "yosemite_r7", 19:35:10 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 19:35:10 INFO - "branch": "mozilla-aurora", 19:35:10 INFO - "repo_path": "releases/mozilla-aurora", 19:35:10 INFO - "revision": "fcc32735c01d504767a0dee94c6bc706b6a23b5e", 19:35:10 INFO - "stage_platform": "macosx64", 19:35:10 INFO - "builduid": "a79239180cfa49528d8bc2b6b5e127e2", 19:35:10 INFO - "slavebuilddir": "test" 19:35:10 INFO - } 19:35:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 19:35:10 INFO - Running post-action listener: _resource_record_post_action 19:35:10 INFO - [mozharness: 2017-02-07 03:35:10.033061Z] Finished read-buildbot-config step (success) 19:35:10 INFO - [mozharness: 2017-02-07 03:35:10.033192Z] Running download-and-extract step. 19:35:10 INFO - Running pre-action listener: _resource_record_pre_action 19:35:10 INFO - Running main action method: download_and_extract 19:35:10 INFO - mkdir: /builds/slave/test/build/tests 19:35:10 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')]} 19:35:10 INFO - trying https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 19:35:10 INFO - Downloading https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 19:35:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 19:35:10 INFO - Downloaded 1199 bytes. 19:35:10 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 19:35:10 INFO - Using the following test package requirements: 19:35:10 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 19:35:10 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 19:35:10 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 19:35:10 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 19:35:10 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 19:35:10 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 19:35:10 INFO - u'jsshell-mac.zip'], 19:35:10 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 19:35:10 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 19:35:10 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 19:35:10 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 19:35:10 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 19:35:10 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 19:35:10 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 19:35:10 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 19:35:10 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 19:35:10 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 19:35:10 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 19:35:10 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 19:35:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 19:35:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:35:10 INFO - Fetch https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 19:35:11 INFO - Expected file size: 16356042 19:35:11 INFO - Obtained file size: 16356042 19:35:18 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 19:35:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 19:35:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:35:18 INFO - Fetch https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 19:35:19 INFO - Expected file size: 16356042 19:35:19 INFO - Obtained file size: 16356042 19:35:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 19:35:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 19:35:25 INFO - Fetch https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 19:35:27 INFO - Expected file size: 14403485 19:35:27 INFO - Obtained file size: 14403485 19:35:27 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')]} 19:35:27 INFO - trying https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 19:35:27 INFO - Downloading https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 19:35:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 19:35:29 INFO - Downloaded 73810492 bytes. 19:35:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 19:35:29 INFO - mkdir: /builds/slave/test/properties 19:35:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:35:29 INFO - Writing to file /builds/slave/test/properties/build_url 19:35:29 INFO - Contents: 19:35:29 INFO - build_url:https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 19:35:30 INFO - Running post-action listener: _resource_record_post_action 19:35:30 INFO - Running post-action listener: set_extra_try_arguments 19:35:30 INFO - [mozharness: 2017-02-07 03:35:30.467959Z] Finished download-and-extract step (success) 19:35:30 INFO - [mozharness: 2017-02-07 03:35:30.468062Z] Running populate-webroot step. 19:35:30 INFO - Running pre-action listener: _resource_record_pre_action 19:35:30 INFO - Running main action method: populate_webroot 19:35:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:35:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:35:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:35:30 INFO - '--tppagecycles', 19:35:30 INFO - '7'], 19:35:30 INFO - 'tests': ['tsvgm']}}, 19:35:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:35:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:35:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:35:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:35:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:35:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:35:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:35:30 INFO - 'talos_options': ['--disable-e10s'], 19:35:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:35:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:35:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:35:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:35:30 INFO - 'talos_options': ['--disable-e10s'], 19:35:30 INFO - 'tests': ['damp', 'tps']}, 19:35:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:35:30 INFO - 'tests': ['damp', 'tps']}, 19:35:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:35:30 INFO - 'tests': ['dromaeo_dom']}, 19:35:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:35:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:35:30 INFO - 'tests': ['basic_compositor_video']}, 19:35:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:35:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:35:30 INFO - 'tests': ['a11yr', 19:35:30 INFO - 'ts_paint', 19:35:30 INFO - 'tpaint', 19:35:30 INFO - 'sessionrestore', 19:35:30 INFO - 'sessionrestore_no_auto_restore', 19:35:30 INFO - 'tabpaint']}, 19:35:30 INFO - 'other-e10s': {'tests': ['a11yr', 19:35:30 INFO - 'ts_paint', 19:35:30 INFO - 'tpaint', 19:35:30 INFO - 'sessionrestore', 19:35:30 INFO - 'sessionrestore_no_auto_restore', 19:35:30 INFO - 'tabpaint']}, 19:35:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:35:30 INFO - 'ts_paint', 19:35:30 INFO - 'tpaint', 19:35:30 INFO - 'sessionrestore', 19:35:30 INFO - 'sessionrestore_no_auto_restore', 19:35:30 INFO - 'tabpaint']}, 19:35:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:35:30 INFO - 'ts_paint', 19:35:30 INFO - 'tpaint', 19:35:30 INFO - 'sessionrestore', 19:35:30 INFO - 'sessionrestore_no_auto_restore', 19:35:30 INFO - 'tabpaint']}, 19:35:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:35:30 INFO - 'tests': ['a11yr', 19:35:30 INFO - 'ts_paint', 19:35:30 INFO - 'tpaint', 19:35:30 INFO - 'sessionrestore', 19:35:30 INFO - 'sessionrestore_no_auto_restore', 19:35:30 INFO - 'tabpaint']}, 19:35:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:35:30 INFO - 'tests': ['a11yr', 19:35:30 INFO - 'ts_paint', 19:35:30 INFO - 'tpaint', 19:35:30 INFO - 'sessionrestore', 19:35:30 INFO - 'sessionrestore_no_auto_restore', 19:35:30 INFO - 'tabpaint']}, 19:35:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:35:30 INFO - 'tests': ['tsvgx', 19:35:30 INFO - 'tsvgr_opacity', 19:35:30 INFO - 'tart', 19:35:30 INFO - 'tscrollx', 19:35:30 INFO - 'cart', 19:35:30 INFO - 'tsvg_static']}, 19:35:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:35:30 INFO - 'tsvgr_opacity', 19:35:30 INFO - 'tart', 19:35:30 INFO - 'tscrollx', 19:35:30 INFO - 'cart', 19:35:30 INFO - 'tsvg_static']}, 19:35:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:35:30 INFO - 'talos_options': ['--disable-e10s'], 19:35:30 INFO - 'tests': ['tp5o']}, 19:35:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:35:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:35:30 INFO - 'talos_options': ['--disable-e10s', 19:35:30 INFO - '--xperf_path', 19:35:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:35:30 INFO - 'tests': ['tp5n']}, 19:35:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:35:30 INFO - 'talos_options': ['--xperf_path', 19:35:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:35:30 INFO - 'tests': ['tp5n']}}, 19:35:30 INFO - 'talos.zip': {'path': '', 19:35:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:35:30 INFO - Running post-action listener: _resource_record_post_action 19:35:30 INFO - [mozharness: 2017-02-07 03:35:30.486157Z] Finished populate-webroot step (success) 19:35:30 INFO - [mozharness: 2017-02-07 03:35:30.486282Z] Running create-virtualenv step. 19:35:30 INFO - Running pre-action listener: _resource_record_pre_action 19:35:30 INFO - Running main action method: create_virtualenv 19:35:30 INFO - Creating virtualenv /builds/slave/test/build/venv 19:35:30 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 19:35:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:35:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:35:30 INFO - Using real prefix '/tools/python27' 19:35:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:35:31 INFO - Installing distribute.............................................................................................................................................................................................done. 19:35:34 INFO - Installing pip.................done. 19:35:34 INFO - Return code: 0 19:35:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:35:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:35:34 INFO - Reading from file tmpfile_stdout 19:35:34 INFO - Output received: 19:35:34 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) 19:35:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:34 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 0x101563b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde815f2920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101341d30>, '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 0x101268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101336be8>, '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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 19:35:34 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 19:35:34 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 19:35:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U7dlKoIyTJ/Render', 19:35:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:34 INFO - 'HOME': '/Users/cltbld', 19:35:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:34 INFO - 'LOGNAME': 'cltbld', 19:35:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:34 INFO - 'MOZ_NO_REMOTE': '1', 19:35:34 INFO - 'NO_EM_RESTART': '1', 19:35:34 INFO - 'PAGER': '/bin/cat', 19:35:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:34 INFO - 'PWD': '/builds/slave/test', 19:35:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:34 INFO - 'SHELL': '/bin/bash', 19:35:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6XA9nKJrHH/Listeners', 19:35:34 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 19:35:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:34 INFO - 'USER': 'cltbld', 19:35:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:34 INFO - 'XPC_FLAGS': '0x0', 19:35:34 INFO - 'XPC_SERVICE_NAME': '0', 19:35:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:34 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 19:35:34 INFO - Cleaning up... 19:35:34 INFO - Return code: 0 19:35:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:34 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 0x101563b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde815f2920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101341d30>, '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 0x101268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101336be8>, '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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 19:35:34 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 19:35:34 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 19:35:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U7dlKoIyTJ/Render', 19:35:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:34 INFO - 'HOME': '/Users/cltbld', 19:35:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:34 INFO - 'LOGNAME': 'cltbld', 19:35:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:34 INFO - 'MOZ_NO_REMOTE': '1', 19:35:34 INFO - 'NO_EM_RESTART': '1', 19:35:34 INFO - 'PAGER': '/bin/cat', 19:35:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:34 INFO - 'PWD': '/builds/slave/test', 19:35:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:34 INFO - 'SHELL': '/bin/bash', 19:35:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6XA9nKJrHH/Listeners', 19:35:34 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 19:35:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:34 INFO - 'USER': 'cltbld', 19:35:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:34 INFO - 'XPC_FLAGS': '0x0', 19:35:34 INFO - 'XPC_SERVICE_NAME': '0', 19:35:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:34 INFO - Downloading/unpacking psutil>=3.1.1 19:35:34 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 19:35:34 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 19:35:34 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 19:35:34 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 19:35:34 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 19:35:34 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 19:35:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:35:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:35:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:35:38 INFO - Installing collected packages: psutil 19:35:38 INFO - Running setup.py install for psutil 19:35:38 INFO - building 'psutil._psutil_osx' extension 19:35:38 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 19:35:38 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 19:35:38 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 19:35:39 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 19:35:39 INFO - building 'psutil._psutil_posix' extension 19:35:39 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 19:35:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:35:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:35:39 INFO - ^ 19:35:39 INFO - 1 warning generated. 19:35:39 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 19:35:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:35:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:35:39 INFO - Successfully installed psutil 19:35:39 INFO - Cleaning up... 19:35:39 INFO - Return code: 0 19:35:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:35:39 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')]} 19:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:39 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')]} 19:35:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:39 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 0x101563b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde815f2920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101341d30>, '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 0x101268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101336be8>, '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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 19:35:39 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 19:35:39 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 19:35:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U7dlKoIyTJ/Render', 19:35:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:39 INFO - 'HOME': '/Users/cltbld', 19:35:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:39 INFO - 'LOGNAME': 'cltbld', 19:35:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:39 INFO - 'MOZ_NO_REMOTE': '1', 19:35:39 INFO - 'NO_EM_RESTART': '1', 19:35:39 INFO - 'PAGER': '/bin/cat', 19:35:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:39 INFO - 'PWD': '/builds/slave/test', 19:35:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:39 INFO - 'SHELL': '/bin/bash', 19:35:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6XA9nKJrHH/Listeners', 19:35:39 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 19:35:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:39 INFO - 'USER': 'cltbld', 19:35:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:39 INFO - 'XPC_FLAGS': '0x0', 19:35:39 INFO - 'XPC_SERVICE_NAME': '0', 19:35:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:35:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:35:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:35:42 INFO - Installing collected packages: mozsystemmonitor 19:35:42 INFO - Running setup.py install for mozsystemmonitor 19:35:42 INFO - Successfully installed mozsystemmonitor 19:35:42 INFO - Cleaning up... 19:35:42 INFO - Return code: 0 19:35:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:35:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101563b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde815f2920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101341d30>, '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 0x101268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101336be8>, '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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 19:35:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:35:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:35:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U7dlKoIyTJ/Render', 19:35:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:42 INFO - 'HOME': '/Users/cltbld', 19:35:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:42 INFO - 'LOGNAME': 'cltbld', 19:35:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:42 INFO - 'MOZ_NO_REMOTE': '1', 19:35:42 INFO - 'NO_EM_RESTART': '1', 19:35:42 INFO - 'PAGER': '/bin/cat', 19:35:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:42 INFO - 'PWD': '/builds/slave/test', 19:35:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:42 INFO - 'SHELL': '/bin/bash', 19:35:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6XA9nKJrHH/Listeners', 19:35:42 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 19:35:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:42 INFO - 'USER': 'cltbld', 19:35:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:42 INFO - 'XPC_FLAGS': '0x0', 19:35:42 INFO - 'XPC_SERVICE_NAME': '0', 19:35:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:42 INFO - Downloading/unpacking jsonschema==2.5.1 19:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:35:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:35:45 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 19:35:45 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 19:35:45 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 19:35:45 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 19:35:45 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 19:35:45 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 19:35:45 INFO - Downloading functools32-3.2.3-2.tar.gz 19:35:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:35:45 INFO - warning: no files found matching '*.txt' 19:35:45 INFO - no previously-included directories found matching 'build' 19:35:45 INFO - no previously-included directories found matching 'dist' 19:35:45 INFO - no previously-included directories found matching '.git*' 19:35:45 INFO - Installing collected packages: jsonschema, functools32 19:35:45 INFO - Running setup.py install for functools32 19:35:46 INFO - warning: no files found matching '*.txt' 19:35:46 INFO - no previously-included directories found matching 'build' 19:35:46 INFO - no previously-included directories found matching 'dist' 19:35:46 INFO - no previously-included directories found matching '.git*' 19:35:46 INFO - Successfully installed jsonschema functools32 19:35:46 INFO - Cleaning up... 19:35:46 INFO - Return code: 0 19:35:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:35: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')]} 19:35:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35: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')]} 19:35:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:46 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 0x101563b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde815f2920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101341d30>, '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 0x101268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101336be8>, '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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 19:35:46 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 19:35:46 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 19:35:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U7dlKoIyTJ/Render', 19:35:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:46 INFO - 'HOME': '/Users/cltbld', 19:35:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:46 INFO - 'LOGNAME': 'cltbld', 19:35:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:46 INFO - 'MOZ_NO_REMOTE': '1', 19:35:46 INFO - 'NO_EM_RESTART': '1', 19:35:46 INFO - 'PAGER': '/bin/cat', 19:35:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:46 INFO - 'PWD': '/builds/slave/test', 19:35:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:46 INFO - 'SHELL': '/bin/bash', 19:35:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6XA9nKJrHH/Listeners', 19:35:46 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 19:35:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:46 INFO - 'USER': 'cltbld', 19:35:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:46 INFO - 'XPC_FLAGS': '0x0', 19:35:46 INFO - 'XPC_SERVICE_NAME': '0', 19:35:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:35:46 INFO - Cleaning up... 19:35:46 INFO - Return code: 0 19:35:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:35: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')]} 19:35:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35: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')]} 19:35:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:46 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 0x101563b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde815f2920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101341d30>, '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 0x101268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101336be8>, '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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 19:35:46 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 19:35:46 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 19:35:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U7dlKoIyTJ/Render', 19:35:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:46 INFO - 'HOME': '/Users/cltbld', 19:35:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:46 INFO - 'LOGNAME': 'cltbld', 19:35:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:46 INFO - 'MOZ_NO_REMOTE': '1', 19:35:46 INFO - 'NO_EM_RESTART': '1', 19:35:46 INFO - 'PAGER': '/bin/cat', 19:35:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:46 INFO - 'PWD': '/builds/slave/test', 19:35:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:46 INFO - 'SHELL': '/bin/bash', 19:35:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6XA9nKJrHH/Listeners', 19:35:46 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 19:35:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:46 INFO - 'USER': 'cltbld', 19:35:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:46 INFO - 'XPC_FLAGS': '0x0', 19:35:46 INFO - 'XPC_SERVICE_NAME': '0', 19:35:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:46 INFO - Downloading/unpacking blobuploader==1.2.4 19:35:46 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 19:35:46 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 19:35:46 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 19:35:46 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 19:35:46 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 19:35:46 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 19:35:49 INFO - Downloading blobuploader-1.2.4.tar.gz 19:35:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:35:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:35:49 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 19:35:49 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 19:35:49 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 19:35:49 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 19:35:49 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 19:35:49 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 19:35:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:35:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:35:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:35:50 INFO - Downloading docopt-0.6.1.tar.gz 19:35:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:35:50 INFO - Installing collected packages: blobuploader, requests, docopt 19:35:50 INFO - Running setup.py install for blobuploader 19:35:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:35:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:35:50 INFO - Running setup.py install for requests 19:35:51 INFO - Running setup.py install for docopt 19:35:51 INFO - Successfully installed blobuploader requests docopt 19:35:51 INFO - Cleaning up... 19:35:51 INFO - Return code: 0 19:35:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:35:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:51 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 0x101563b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde815f2920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101341d30>, '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 0x101268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101336be8>, '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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 19:35:51 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 19:35:51 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 19:35:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U7dlKoIyTJ/Render', 19:35:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:51 INFO - 'HOME': '/Users/cltbld', 19:35:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:51 INFO - 'LOGNAME': 'cltbld', 19:35:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:51 INFO - 'MOZ_NO_REMOTE': '1', 19:35:51 INFO - 'NO_EM_RESTART': '1', 19:35:51 INFO - 'PAGER': '/bin/cat', 19:35:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:51 INFO - 'PWD': '/builds/slave/test', 19:35:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:51 INFO - 'SHELL': '/bin/bash', 19:35:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6XA9nKJrHH/Listeners', 19:35:51 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 19:35:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:51 INFO - 'USER': 'cltbld', 19:35:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:51 INFO - 'XPC_FLAGS': '0x0', 19:35:51 INFO - 'XPC_SERVICE_NAME': '0', 19:35:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:35:51 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-MBShOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:35:51 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-9q2WhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:35:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:35:51 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ji8g1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:35:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:35:52 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-fVfMbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:35:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:35:52 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Dbi4oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:35:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:35:52 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Q91h6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:35:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:35:52 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Le4BUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:35:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:35:52 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Np7C3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:35:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:35:52 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-8Qe00N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:35:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:35:52 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-3b6fMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:35:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:35:52 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-fU4LmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:35:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:35:52 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-0maxy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:35:53 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-n5gTbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:35:53 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-njasS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:53 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-IlbApB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:35:53 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ETE9pA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:35:53 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-S8ZU3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:35:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:35:53 INFO - Running setup.py install for manifestparser 19:35:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:35:53 INFO - Running setup.py install for mozcrash 19:35:53 INFO - Running setup.py install for mozdebug 19:35:54 INFO - Running setup.py install for mozdevice 19:35:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:35:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:35:54 INFO - Running setup.py install for mozfile 19:35:54 INFO - Running setup.py install for mozhttpd 19:35:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:35:54 INFO - Running setup.py install for mozinfo 19:35:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:35:54 INFO - Running setup.py install for mozInstall 19:35:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:35:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:35:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:35:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:35:54 INFO - Running setup.py install for mozleak 19:35:54 INFO - Running setup.py install for mozlog 19:35:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:35:55 INFO - Running setup.py install for moznetwork 19:35:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:35:55 INFO - Running setup.py install for mozprocess 19:35:55 INFO - Running setup.py install for mozprofile 19:35:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:35:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:35:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:35:55 INFO - Running setup.py install for mozrunner 19:35:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:35:55 INFO - Running setup.py install for mozscreenshot 19:35:55 INFO - Running setup.py install for moztest 19:35:56 INFO - Running setup.py install for mozversion 19:35:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:35:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:35:56 INFO - Cleaning up... 19:35:56 INFO - Return code: 0 19:35:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:35: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')]} 19:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:35:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35: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')]} 19:35:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:35:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:35:56 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 0x101563b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde815f2920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101341d30>, '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 0x101268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101336be8>, '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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 19:35:56 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 19:35:56 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 19:35:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U7dlKoIyTJ/Render', 19:35:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:35:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:35:56 INFO - 'HOME': '/Users/cltbld', 19:35:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:35:56 INFO - 'LOGNAME': 'cltbld', 19:35:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:35:56 INFO - 'MOZ_NO_REMOTE': '1', 19:35:56 INFO - 'NO_EM_RESTART': '1', 19:35:56 INFO - 'PAGER': '/bin/cat', 19:35:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:35:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:35:56 INFO - 'PWD': '/builds/slave/test', 19:35:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:35:56 INFO - 'SHELL': '/bin/bash', 19:35:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6XA9nKJrHH/Listeners', 19:35:56 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 19:35:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:35:56 INFO - 'USER': 'cltbld', 19:35:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:35:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:35:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:35:56 INFO - 'XPC_FLAGS': '0x0', 19:35:56 INFO - 'XPC_SERVICE_NAME': '0', 19:35:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:35:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:35:56 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Mu23rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:35:56 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)) 19:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:35:56 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-rqm2pH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:35:56 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)) 19:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:35:56 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ahFkVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:35:56 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)) 19:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:35:56 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-bx_sOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:35:56 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)) 19:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:35:56 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-jDtbgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:35:57 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)) 19:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:35:57 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-8PslQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:35:57 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)) 19:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:35:57 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-mLlSNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:35:57 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)) 19:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:35:57 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-vXe8EA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:35:57 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)) 19:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:35:57 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-5NAHlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:35:57 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)) 19:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:35:57 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-sRakwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:35:57 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)) 19:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:35:57 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-4hwmP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:35:57 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)) 19:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:35:57 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-xDLTZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:35:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:35:57 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Uiwv4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:35:57 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)) 19:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:35:57 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Olir8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:35:57 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)) 19:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:57 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-yjcNXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:35:58 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)) 19:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:35:58 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-R9qTG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:35:58 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)) 19:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:35:58 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-wd9sSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:35:58 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)) 19:35:58 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)) 19:35:58 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)) 19:35:58 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)) 19:35:58 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)) 19:35:58 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)) 19:35:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:35:58 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 19:35:58 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 19:35:58 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 19:35:58 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 19:35:58 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 19:35:58 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 19:36:00 INFO - Downloading blessings-1.6.tar.gz 19:36:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:36:01 INFO - Installing collected packages: blessings 19:36:01 INFO - Running setup.py install for blessings 19:36:01 INFO - Successfully installed blessings 19:36:01 INFO - Cleaning up... 19:36:01 INFO - Return code: 0 19:36:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:36:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:36:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:36:01 INFO - Reading from file tmpfile_stdout 19:36:01 INFO - Current package versions: 19:36:01 INFO - blessings == 1.6 19:36:01 INFO - blobuploader == 1.2.4 19:36:01 INFO - docopt == 0.6.1 19:36:01 INFO - functools32 == 3.2.3-2 19:36:01 INFO - jsonschema == 2.5.1 19:36:01 INFO - manifestparser == 1.1 19:36:01 INFO - mozInstall == 1.12 19:36:01 INFO - mozcrash == 1.0 19:36:01 INFO - mozdebug == 0.1 19:36:01 INFO - mozdevice == 0.49 19:36:01 INFO - mozfile == 1.2 19:36:01 INFO - mozhttpd == 0.7 19:36:01 INFO - mozinfo == 0.9 19:36:01 INFO - mozleak == 0.1 19:36:01 INFO - mozlog == 3.4 19:36:01 INFO - moznetwork == 0.27 19:36:01 INFO - mozprocess == 0.24 19:36:01 INFO - mozprofile == 0.28 19:36:01 INFO - mozrunner == 6.13 19:36:01 INFO - mozscreenshot == 0.1 19:36:01 INFO - mozsystemmonitor == 0.3 19:36:01 INFO - moztest == 0.8 19:36:01 INFO - mozversion == 1.4 19:36:01 INFO - psutil == 3.1.1 19:36:01 INFO - requests == 1.2.3 19:36:01 INFO - wsgiref == 0.1.2 19:36:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:36: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')]} 19:36:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36: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')]} 19:36:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36:01 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 0x101563b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde815f2920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101341d30>, '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 0x101268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101336be8>, '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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 19:36:01 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 19:36:01 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 19:36:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U7dlKoIyTJ/Render', 19:36:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:01 INFO - 'HOME': '/Users/cltbld', 19:36:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:01 INFO - 'LOGNAME': 'cltbld', 19:36:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:01 INFO - 'MOZ_NO_REMOTE': '1', 19:36:01 INFO - 'NO_EM_RESTART': '1', 19:36:01 INFO - 'PAGER': '/bin/cat', 19:36:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:01 INFO - 'PWD': '/builds/slave/test', 19:36:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:01 INFO - 'SHELL': '/bin/bash', 19:36:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6XA9nKJrHH/Listeners', 19:36:01 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 19:36:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:01 INFO - 'USER': 'cltbld', 19:36:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:01 INFO - 'XPC_FLAGS': '0x0', 19:36:01 INFO - 'XPC_SERVICE_NAME': '0', 19:36:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:01 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)) 19:36:01 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)) 19:36:01 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)) 19:36:01 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)) 19:36:01 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)) 19:36:01 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)) 19:36:01 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)) 19:36:01 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)) 19:36:01 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)) 19:36:01 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)) 19:36:01 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)) 19:36:01 INFO - Cleaning up... 19:36:01 INFO - Return code: 0 19:36:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:36: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')]} 19:36:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36: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')]} 19:36:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:36: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101563b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101566768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde815f2920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101341d30>, '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 0x101268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101336be8>, '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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 19:36: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', 'jsonschema'] in /builds/slave/test/build 19:36: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 jsonschema 19:36:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U7dlKoIyTJ/Render', 19:36:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:01 INFO - 'HOME': '/Users/cltbld', 19:36:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:01 INFO - 'LOGNAME': 'cltbld', 19:36:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:01 INFO - 'MOZ_NO_REMOTE': '1', 19:36:01 INFO - 'NO_EM_RESTART': '1', 19:36:01 INFO - 'PAGER': '/bin/cat', 19:36:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:01 INFO - 'PWD': '/builds/slave/test', 19:36:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:01 INFO - 'SHELL': '/bin/bash', 19:36:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6XA9nKJrHH/Listeners', 19:36:01 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 19:36:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:01 INFO - 'USER': 'cltbld', 19:36:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:01 INFO - 'XPC_FLAGS': '0x0', 19:36:01 INFO - 'XPC_SERVICE_NAME': '0', 19:36:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:36:02 INFO - Cleaning up... 19:36:02 INFO - Return code: 0 19:36:02 INFO - Running post-action listener: _resource_record_post_action 19:36:02 INFO - Running post-action listener: _start_resource_monitoring 19:36:02 INFO - Starting resource monitoring. 19:36:02 INFO - [mozharness: 2017-02-07 03:36:02.171848Z] Finished create-virtualenv step (success) 19:36:02 INFO - [mozharness: 2017-02-07 03:36:02.172888Z] Running install step. 19:36:02 INFO - Running pre-action listener: _resource_record_pre_action 19:36:02 INFO - Running main action method: install 19:36:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:36:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:36:02 INFO - Reading from file tmpfile_stdout 19:36:02 INFO - Detecting whether we're running mozinstall >=1.0... 19:36:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:36:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:36:02 INFO - Reading from file tmpfile_stdout 19:36:02 INFO - Output received: 19:36:02 INFO - Usage: mozinstall [options] installer 19:36:02 INFO - Options: 19:36:02 INFO - -h, --help show this help message and exit 19:36:02 INFO - -d DEST, --destination=DEST 19:36:02 INFO - Directory to install application into. [default: 19:36:02 INFO - "/builds/slave/test"] 19:36:02 INFO - --app=APP Application being installed. [default: firefox] 19:36:02 INFO - mkdir: /builds/slave/test/build/application 19:36:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:36:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 19:36:22 INFO - Reading from file tmpfile_stdout 19:36:22 INFO - Output received: 19:36:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:36:22 INFO - Running post-action listener: _resource_record_post_action 19:36:22 INFO - [mozharness: 2017-02-07 03:36:22.532382Z] Finished install step (success) 19:36:22 INFO - [mozharness: 2017-02-07 03:36:22.532545Z] Running run-tests step. 19:36:22 INFO - Running pre-action listener: _resource_record_pre_action 19:36:22 INFO - Running main action method: run_tests 19:36:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:36:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:36:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:36:22 INFO - 2017-02-06 19:36:22.547 screenresolution[2312:75040] starting screenresolution argv=screenresolution get 19:36:22 INFO - 2017-02-06 19:36:22.549 screenresolution[2312:75040] Display 0: 1600x1200x32@60 19:36:22 INFO - 2017-02-06 19:36:22.555 screenresolution[2313:75043] starting screenresolution argv=screenresolution list 19:36:22 INFO - Available Modes on Display 0 19:36:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:36:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:36:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:36:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:36:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:36:22 INFO - Intel Iris: 19:36:22 INFO - Chipset Model: Intel Iris 19:36:22 INFO - Type: GPU 19:36:22 INFO - Bus: Built-In 19:36:22 INFO - VRAM (Dynamic, Max): 1536 MB 19:36:22 INFO - Vendor: Intel (0x8086) 19:36:22 INFO - Device ID: 0x0a2e 19:36:22 INFO - Revision ID: 0x0009 19:36:22 INFO - Displays: 19:36:22 INFO - Display: 19:36:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:36:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:36:22 INFO - Display Serial Number: 1600x1200 60 19:36:22 INFO - Main Display: Yes 19:36:22 INFO - Mirror: Off 19:36:22 INFO - Online: Yes 19:36:22 INFO - Rotation: Supported 19:36:22 INFO - Return code: 0 19:36:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:36:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:36:22 INFO - Python 2.7.3 19:36:22 INFO - Return code: 0 19:36:22 INFO - grabbing minidump binary from tooltool 19:36:22 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')]} 19:36:22 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 0x101341d30>, '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 0x101268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101336be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:36:22 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 19:36:22 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 19:36:22 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 19:36:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:36:22 INFO - Return code: 0 19:36:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:36:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:36:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:36:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:36:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:36:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0143', '--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 19:36:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0143 --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 19:36:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U7dlKoIyTJ/Render', 19:36:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:36:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:36:22 INFO - 'HOME': '/Users/cltbld', 19:36:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:36:22 INFO - 'LOGNAME': 'cltbld', 19:36:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:36:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:36:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:22 INFO - 'MOZ_NO_REMOTE': '1', 19:36:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:36:22 INFO - 'NO_EM_RESTART': '1', 19:36:22 INFO - 'PAGER': '/bin/cat', 19:36:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:36:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:36:22 INFO - 'PWD': '/builds/slave/test', 19:36:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:36:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:36:22 INFO - 'SHELL': '/bin/bash', 19:36:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6XA9nKJrHH/Listeners', 19:36:22 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 19:36:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:36:22 INFO - 'USER': 'cltbld', 19:36:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:36:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:36:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:36:22 INFO - 'XPC_FLAGS': '0x0', 19:36:22 INFO - 'XPC_SERVICE_NAME': '0', 19:36:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:36:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0143', '--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 19:36:23 INFO - mozversion application_buildid: 20170206175856 19:36:23 INFO - mozversion application_changeset: fcc32735c01d504767a0dee94c6bc706b6a23b5e 19:36:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:36:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:36:23 INFO - mozversion application_name: Firefox 19:36:23 INFO - mozversion application_remotingname: firefox-dev 19:36:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:36:23 INFO - mozversion application_vendor: Mozilla 19:36:23 INFO - mozversion application_version: 53.0a2 19:36:23 INFO - mozversion platform_buildid: 20170206175856 19:36:23 INFO - mozversion platform_changeset: fcc32735c01d504767a0dee94c6bc706b6a23b5e 19:36:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:36:23 INFO - mozversion platform_version: 53.0a2 19:36:23 INFO - using testdate: 1486438583 19:36:23 INFO - actual date: 1486438583 19:36:23 INFO - starting webserver on 'localhost:60215' 19:36:23 INFO - SUITE-START | Running 2 tests 19:36:23 INFO - TEST-START | dromaeo_css 19:36:23 INFO - operating with platform_type : mac_ 19:36:23 INFO - Initialising browser for dromaeo_css test... 19:36:23 INFO - TEST-INFO | started process 2323 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpYdwpcH/profile http://localhost:60215/getInfo.html) 19:36:25 INFO - PROCESS | 2323 | __metrics Screen width/height:1600/1200 19:36:25 INFO - PROCESS | 2323 | colorDepth:24 19:36:25 INFO - PROCESS | 2323 | Browser inner width/height: 1024/689 19:36:25 INFO - PROCESS | 2323 | __metrics 19:36:25 INFO - PROCESS | 2323 | 1486438585436 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884: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 19:36:25 INFO - PROCESS | 2323 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:36:25 INFO - PROCESS | 2323 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:36:26 INFO - TEST-INFO | 2323: exit 0 19:36:26 INFO - Browser initialized. 19:36:26 INFO - Running cycle 1/1 for dromaeo_css test... 19:36:26 INFO - TEST-INFO | started process 2325 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpYdwpcH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:36:38 INFO - PROCESS | 2325 | RSS: Main: 212963328 19:36:38 INFO - PROCESS | 2325 | 19:39:42 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:60215/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60215/tests/dromaeo/cssquery-ext.html) 19:39:43 INFO - PROCESS | 2325 | RSS: Main: 352301056 19:39:43 INFO - PROCESS | 2325 | 19:41:28 INFO - PROCESS | 2325 | ************************* 19:41:28 INFO - PROCESS | 2325 | A coding exception was thrown and uncaught in a Task. 19:41:28 INFO - PROCESS | 2325 | 19:41:28 ERROR - PROCESS | 2325 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:41:28 INFO - PROCESS | 2325 | Full stack: 19:41:28 INFO - PROCESS | 2325 | ************************* 19:42:46 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:60215/tests/dromaeo/cssquery-ext.html (next: http://localhost:60215/tests/dromaeo/cssquery-jquery.html) 19:42:47 INFO - PROCESS | 2325 | RSS: Main: 348549120 19:42:47 INFO - PROCESS | 2325 | 19:45:49 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:60215/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60215/tests/dromaeo/cssquery-mootools.html) 19:45:49 INFO - PROCESS | 2325 | RSS: Main: 349675520 19:45:49 INFO - PROCESS | 2325 | 19:48:52 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:60215/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60215/tests/dromaeo/cssquery-prototype.html) 19:48:52 INFO - PROCESS | 2325 | RSS: Main: 327888896 19:48:52 INFO - PROCESS | 2325 | 19:51:56 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:60215/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60215/tests/dromaeo/cssquery-yui.html) 19:51:56 INFO - PROCESS | 2325 | RSS: Main: 312692736 19:51:56 INFO - PROCESS | 2325 | 19:55:00 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:60215/tests/dromaeo/cssquery-yui.html (next: http://localhost:60215/tests/dromaeo/cssquery-dojo.html) 19:55:00 INFO - PROCESS | 2325 | RSS: Main: 474492928 19:55:00 INFO - PROCESS | 2325 | 19:55:00 INFO - PROCESS | 2325 | __start_tp_report 19:55:00 INFO - PROCESS | 2325 | _x_x_mozilla_page_load 19:55:00 INFO - PROCESS | 2325 | _x_x_mozilla_page_load_details 19:55:00 INFO - PROCESS | 2325 | |i|pagename|runs| 19:55:00 INFO - PROCESS | 2325 | |0;dojo.html;3290.437042404857;3303.719183869371;3320.6679332066797;3322.3853587086405;3361.378145043167;5703.144528320749;5741.224934633823;5751.396103409134;5766.221560089387;5772.624779389337;4627.583517483426;5646.74589643466;5710.457506536873;5741.0240259155935;5763.942360576393;5160.974195129019;5188;5198.896022079556;5199.818006369778;5261.921071183916;5168.7932482700835;5175;5179.896402071975;5282.260483532308;5333.120035194208;5167.896642067157;5240.2401651760665;5247.947520524803;5262.710550919698;5268.841934741964;5250.527452529291;5251.789928402859;5254.605904557155;5254.789808407659;5260.421353651095;5088.1350170471105;5187.792488300502;5216.713080780555;5223.37319521656;5275.815346462856;5551.056320425538;5573.359063707685;5588.413216612238;5613.07384281593;5716.1425786131995;6163.96918015407;6216.316205217422;6230.096635987802;6235.033569796688;6246.125542424064;5912.852178695524;6249.375062493759;6255.874882502325;6262.405071518198;6281.309056003882;5667.716614169275;5768.9711551442815;5773.682447465346;5822.039363505017;5873.20711703923;6165.753369865245;6173.290071641729;6202.596831205957;6300.338464461257;6317.778877739257;6144.600600960924;6147.907781383283;6150.753969841246;6157.784477543354;6163.568550201443;6153.107799369156;6228.408301211378;6241.438270555672;6245.531585131133;6250.218722659668;6091.116788065706;6106.55920977448;6117.602355846856;6136.754529818846;6166.568340216231;6171.783987560414;6193.721282542297;6228.968855155786;6234.812955611248;6251.468625166821;4948.406191257072;4995.675281106716;4999.02519008797;5011.824586139468;5041.823536176216;4384.145091707151;4393.7363758174615;4396.428464299621;4412.33814927757;4492.595666389975;4981.078500477423;5779.075347944308;5788.21859049023;5867.677277749679;5892.381299963527;6109.374301065143;6153.661548614869;6215.160953271341;6236.126942228092;6256.280527739277;5718.571107166979;5735.885282294331;5746;5757.107648314518;5780.508656764138;5307.15085586312;5347.812826551052;5452.318460192476;5461.1808228766;5465.180222966586;6179.3202747698615;6193.84515387119;6213.37866213375;6221.253449585988;6286.842828929313;6201.379862013762;6233.532485063548;6238.469730072995;6242.063690446469;6243.032329988859;6295.905561416491;6300.401461861115;6313.179286692701;6313.210848643919;6317.778877739257;6032.215811944419;6048.243969503812;6054.424829641177;6079.148919151234;6080.665563394055;6148.600340977822;6171.783987560414;6177.6911154443;6185.567010309235;6185.907211391743;6030.75876964934;6076.51387888959;6091.390860913873;6111.755529778937;6116.785912493041;6004.5496587755215;6026.0659597762415;6058.909116363347;6072.453479186895;6094.08588711679;5992.191054207626;6011.068284415923;6027.608205466806;6031.457168854824;6076.665783381956;5976.462118409364;6117.174181485442;6135.662538560422;6148.077788331786;6158.3225845157895;5795.76816927335;5859.824205273849;5957.225560677084;5995.370486098896;6021.066734656135 19:55:00 INFO - PROCESS | 2325 | |1;ext.html;407.51098681582107;463.21947518431443;467.8175511550494;475.75498618211617;486.77851577532203;28345.716542834565;37583.62416375839;42731.79511536635;42933.57066429335;43031.78484107575;42328.15343693124;42941.78529107358;42951.785241073754;42990.570094299124;43037.13925721491;7652.005239318881;8771.386002979794;9350.064993500646;9577.664781732641;9582.425054496718;11205.66383008507;11494.885051149507;11520.539178432853;11660;11674.708132296675;11530.884691153107;11587.130965177603;11603.303801771879;11609.30344179352;11634.127440441958;11539.192256542045;11615.012723918477;11617.477213525412;11648.708782280426;11672.766544669144;11386.829197562043;11438.828417573743;11440.256383335056;11442.199046066778;11470.311781293149;41392.379114313306;41639;41649.3340266389;41658.79170604158;41751;3976.7812770297624;3992.201559688074;4051.351783714621;4096.672266218696;4144.896377590554;28835.42329153426;29844.701552984625;29882.103536893927;29941.550876736866;30673;3209.6950789674943;3276.6723327667282;3402.6427225141497;3430.9142271443166;3431.227973705921;489910;494948;495984;496729;498222;65333.69332613321;65574;65663.67168164128;65708.67145664337;65804.67097664576;68818.65590672115;68837.65581172062;68844.31155688479;68984.65507672429;69096.6545167271;16483.505494835175;17096.487105386608;17129.743053854203;17133.485995420157;17193;11858.644240672793;12063;12118.96988755948;12866.806997895039;12998.350082496027;17981.550461238574;18084;18398.26406943734;18495.44513664592;18617;7494.5503269803985;7540.585267810323;7582.393408527305;7594.126675432396;7664.233576642291;893.4862454088795;901.1709227510706;920.3741455810114;921.2537844346101;946.186279799372;29375.6780944858;30323;30383.544246836314;31057.378852423277;31152.68847311543;1102.2669924500117;1103.1671088328421;1110.2616759854607;1110.3005106782675;1246.052999720219;15498.767518487231;15978.776236179332;15990.920045399698;15991.760123598155;16080.678386432206;630575;635425;636298;637011;639725;50973;62314.376856231764;62481.375186247555;62488.37511624917;62771.05843412352;1448.4857875454484;1450.782382642612;1463.4219483304034;1493.835678075411;1496.6632507685683;28440.288992775346;29595.556066658588;29959;30552.236194095327;30585.847070764503;52406.21390679067;52952.94094118173;53013.734931325096;53055.734721326146;53067.46932530625;538908;539337;539996;541987.2900635629;543738;24540.772961351504;25037;25103.87448062748;41382.96542586339;41608.791956040026;42905.78547107244;43319.53443724557;43864.561354386045;44066.33900491559;44263.89340266519;35220.823895880356;35537.045462498776;36043.459348109274;37355.6264437363;37391.62608373881;35805;35819.462708058876;36259.818700906326;36343.818280908425;36519.45220821637;35091.64908350884;35244.82377588198;35258.471122932664;35529;35636.46545301875;29940.4011919756;32190.517142243352;32235.516467252542;32285.35429291354;32520.51219231761;24046.6957102547;24180.87909560441;24367.634485482376;24542.141025064037;24552.87723561371 19:55:00 INFO - PROCESS | 2325 | |2;jquery.html;369.2651623269694;393.4236343756396;393.46095848687287;404.48226270373914;423.95706561857827;14871.604310496568;16393.836061639395;18984.810151898477;20830.687539686918;21403.357899263046;21333.67999480009;21407.892960535213;21992;22345.776542234573;22692.319230423112;5856.707164641773;5897.14491398749;6132.233470816148;6149.631022138664;6203.348648391921;6104.450599446049;6138.478229350489;6153.630782153063;6158.56890017699;6159.353267906872;5857.26784151981;6153.630782153063;6192.5974811637325;6235.625862448268;6235.875282494348;5697.202391665171;6142.385761423867;6179.66011869347;6193.3496982816605;6367.61794292344;6032.638041717467;6045.6070355426755;6048.213732214828;6053.485453736438;6072.817815465543;13480.730385392237;13735.862641373558;23162.53674926492;24992.875035624886;25978.350541236432;2624.973750262492;2670.092168662664;2684.3020814588153;2703.0809524761676;2723.128598848369;10058.849117263248;10159.746006349826;10556.841647375295;10663.786724265472;13023.218606883618;1763.1536862306036;1861.7858946221088;1880.887146771198;1882.7646544181978;1893.1007771308655;122001.38999305035;125843;127229;127502.08746868804;129673;9989.500524973722;11819.704507387383;12045.096617753528;12050.578229761917;12059.819102713467;15665.68668626621;16023.839761602469;16053.518394448185;16056.197190140447;16073;7794.844103117905;7964.920350796534;8045.839083218303;8154;8154.918450815416;7563.205863384375;7585.62071896403;7620.618969051525;7668.309852113337;7734.110577283572;4866.683665561727;4891.241857512091;4894.1679914414635;4897.681650692693;4920.458749537548;1710.45265515034;1983.42480680601;2034.4506983114477;2043.6627956387326;2047.764507081675;953.3279038277998;967.6081187119228;1054.2620165883911;1073.313169263582;1073.4632683658172;21853.453663658216;21956.121755129934;22009;22070.779292206873;22327.097477328167;918.7611221082567;951.3245595627183;1012.063840947121;1048.5229220704562;1102.8897110288906;9481.525923703788;10557.841632375521;11354.545818167346;11424.657260282205;11578.710532236593;729680;734483;734623.3268833729;749209;764382;149120;149147.76278355834;152580;154713;158161;988.7676396046924;992.369845148336;1009.6769033909226;1014.127850048972;1019.2152042926905;18604.627907441503;19009.71485427692;19384.70922936185;19393.8060619392;19453;55078;56713.71643141923;58327.4167258322;58780.118298224705;60188.699056506186;82409;85677;85937;86572;86984.13015870014;8236.794080148044;8244.6808510638;8519.48883067018;8644.740657780276;8687.131286871263;11451.427428628369;13072.869271307545;13199.67000824987;13648.522301719393;13701.383437745126;8538.359623028422;10688.519016644113;10907.800141984217;11336.886631133913;11375.43122843838;4519.932201017054;8238.052623948322;10179.440130792878;11059.336439813636;11799.587014454453;11426.400303957287;11529.135314851588;11705.297682139098;11834;12164.452599633214;7261.821453564015;8593.355498337525;8710.738677839676;8842;9094.954525227331;10896.073833724222;11343;11628.18602697803;11678.532858685898;11704.180707350404 19:55:00 INFO - PROCESS | 2325 | |3;mootools.html;1347.739863227882;1351.831021122359;1428.7285415771003;1449.5433938309438;1465.347920175522;7895.723649672263;8312.91687083129;8315.584220788967;8318.20977007184;8322.459040162385;7358.595277259748;7589.544627322364;7594.848103037936;7743.148253692089;8164.979377577803;1945.3483083167157;2210.789974952381;2304.3547806614174;2304.44693273614;2308.376738280663;2287.4281429642588;2314.2941402872116;2315.305408377488;2319.7564255753155;2343.4727186383097;1985.4936991067257;2077.8753274803485;2226.365485836537;2261.1068627891973;2301.942451438719;2275.101334972685;2296.954060918789;2300.723913130418;2302.470431800687;2303.389601755536;2250.797428231467;2312.0405031911737;2315.8842057897036;2326.1858349577683;2328.0571368595574;8240.50556966584;8313.210245026712;8316.875246871301;8319.584020798935;8329.167083291683;5070.771815268285;5215.504527069922;5216;5226.529612334908;5246.422893481714;3315.834208289576;3453.326601312745;3496.8426420811124;3518.9120271993147;3534.469829525566;1192.1773975956648;1219.743853790705;1246.5076294863568;1273.414229454452;1277.0485987939044;10676.306040107369;10708.357498550113;10719.571217151382;10741.785164296749;10762.623308184176;4514.210013247669;4619.307103934437;4668.019715859639;4724.71651700899;4724.740139292372;9501.572429240701;9512.714618561455;9513.286503512265;9540.236781057498;9557.426554406758;5211.713355765393;5248.212768084818;5248.3701955765555;5249.186376111633;5258.526732594085;4541.091781643684;4686.484486706459;4702.741349225826;4775;4804.06320767447;3195.5526226328334;3758.962410375916;3808.5620153682125;3884.223155368881;3898.044978980168;1064.9307794993301;1264.759695657822;1268.5560053981033;1281.269676284509;1285.0940087238475;786.6853258696567;912.1790388650268;919.5769945824861;931.6133804471068;952.7856232347666;5199.870003249949;5315.6013299002725;5542.057850165482;5573.832785016456;5582.748776305076;560.7336515155268;561.4581928439076;573.1631817546346;573.5239751006654;577.8844231153786;3388.881389151368;3600.369935261319;3658.286634106377;3663.5970043295215;3679.0434487033563;7644.923550764533;9867.950660246654;9882.555285012191;9890.851637225447;9915.801683966425;9665.130138287588;9757.463339516395;9760.755981100529;9850.60597576089;9897.851532227023;7436.66535005926;7666.578338191453;7742.651580678883;7793.961030194926;7797.961010194913;5525.861853453535;5619.831405057855;5719.28508936383;5797.710114494174;5962.1951036611235;8905.33210009239;9010.909890901006;9069.229115525255;9149.222316103205;9263.16631503168;7615.885761713468;8023.759287221393;9285.6285748572;9428.811423771349;9453.669121580986;6982.162140543167;7157.284271572801;7162.606056666933;7167.247439018828;7174.354308112086;7533.2466753324225;7606.315431611181;7688.846223075395;7699.037620297463;7703.114141873756;6899;6928.826779330557;6974.616396098263;7035.085438892911;7054.506184567031;6847.554908930903;6852;6862.965685171542;6926.653667316513;6970.477214209056;6279.246490421178;6327.873442531216;6368.012957991518;6373.776917807854;6380.93619063822;4691.530846915418;4826.420829500483;4904.607631389409;4906.803727850989;5029.270755740324;4833.323334733211;4882.4629290777275;4885.4870238624435;4949.950500494949;4961.8511444656715 19:55:00 INFO - PROCESS | 2325 | |4;prototype.html;354.8686985815249;363.1411711302768;364.9087728067982;376.04672156084314;377.69784172661866;4651.441826980758;4653.627709783222;5081.6696914700615;5214;5261.473852614737;5010.749462526868;5125.461826508219;5227.764750586223;5256.8160114396005;5259.8691281374495;4154.273002224613;4154.898951777781;4157.189348077126;4179.770112643819;4180.122174343384;3939.487866577341;4112.712110152291;4178.56125106864;4204.369344598304;4210.136921930997;4105.158442519277;4112.444819949298;4137.40007698885;4138.9586104139025;4165.791710414483;3728.571214310362;4033.8705162554534;4127.9587204128175;4142.46148000761;4144.357624568197;3386.6274709781906;3435.7766745161484;3706.6664000240107;3714.4799728038097;3718.219173973469;4786.941615105503;5063.367079115111;5130.230465430178;5183.719163568018;5183.896322073575;3632.400653892105;4110.91778164438;4351.303791393393;4514.909701805946;4531.071130418256;5249.685018898878;5511.779528818843;5527.364353099405;5528.723563821793;5534.896739239103;2941.852907354645;3563.9664497295753;3796.886093417202;3837.827297771607;3845.288621605012;5812.273465816773;5814.360420353715;5825.533957283407;5854.970725146432;5869.941300587025;3867.9829732808453;3884.864029758945;3909.9413508797393;3914.706397020178;3921.5882332355263;3401.734003986373;3749.8500059997295;3777.660010599004;3848.748482137925;3949.526056873136;3900.570937196906;4521.638268938477;4568.406107206042;4570.794314255866;4678.719276843401;4524.524924882906;5120.359955005624;5292.550133238641;5580.525655319262;5613.887722245532;1074.6775967209805;1127.9548818047351;1172.2380452705809;1238.4303220518482;1274.4328773695802;752.9345975444685;835.8829763833069;860.3633311349556;861.6208868098017;865.2385900407601;4275.593818587291;4523.869032741814;4817.78319975602;4872.512748725099;4922.852314430573;4486.842960496367;4851.36932198819;4931.950680493149;5226.5818734501145;5389.649672771257;4353.3469979503325;4395.978020109879;4411.889702757392;4830.516948305211;4855.75721213945;2473.5547601431554;2845.857707114636;2850.8717107730204;2856.3287627407544;2859.8570071496342;5162.9415969726115;5247.291615631841;5509.5041446270025;5571.303587051619;5613.95090791858;5286.62993590445;5338.679679219259;5539.011911423769;5590.679867854795;5652.2652055233375;4518.6611004174165;5398.24424580559;5554.444555544414;5558.832645144565;5663.518600918886;4993.525615066563;5342.569131045288;5369.731513424235;5370;5379.35447746273;4486.506484286791;4981.452040275494;5223.399309079353;5332.280142180756;5366.919496207638;4743.549362810528;4766.547178018082;5184.729741213276;5484.561235101102;5607.299087614048;3135.5296705494357;3291.6543762905508;3653.3789255827105;3655.6892664123848;3675.6140605236064;4537.455505339381;4839.402997011049;5055.87360315995;5111.667741596784;5369.543588794996;4952.504749525018;5011.148104822262;5286.20706893969;5368.785248590013;5369.436209197978;4874.658773885794;5271.525562699376;5469.972650136883;5489.807856724996;5503.587230957774;4270.7010509264055;4556.703814252063;5355.384130825005;5411.864703382447;5460.952142946395;4535.092981403798;5004.47453017428;5063.367079115111;5133.10170720115;5506.256655351476;4739.668223224341;4782.808687652455;5283.78472951231;5430.728463576885;5484.506394424521 19:55:00 INFO - PROCESS | 2325 | |5;yui.html;703.5567592416777;706.6007705646306;723.5477826358526;783.804048987753;825.3108654273681;15366.539003829876;15619.140947247897;15925.203739813007;15950.76073858893;15963.84036159638;14380.280985950712;14473.131612103281;14531.982761206693;14542.054766440175;14628.195449250286;668.3350066683632;691.9481038922075;692.4840993459879;693.448708276921;846.0904527632804;686.4062585863243;690.8894576867702;834.9123342049088;869.0961400143843;869.3957699398889;909.727081875438;923.6536298887917;925.0379605210607;925.7130289610209;927.2906226736552;878.086789738671;880.2693764175783;887.5695287785451;921.235374639048;921.8478950973081;526.4183077699125;545.3265217456438;546.1261980830678;659.24845675929;659.3340725866909;12407.193532420364;12453.190542614791;14251.57245282643;14789.852101478955;14829.703405931929;14043.08719933201;14086.349542388412;14340.78488822658;14438.350274237684;14464.204468754215;11113.499892504777;11175.105991520659;11820.408979550988;12403.317817520032;12430.875691243062;3643.2167084076787;3796.3925771876643;3899.590542992973;3925.450436938831;3938.468306778577;103147.96852031533;107593.46203269012;108352;111051;111927;10727.624533141303;11292.096632269398;11473;11703.2978021319;11781.823272650918;22509;23467.211480964856;23902.64146037811;24139.517209655707;24184.274471765875;8115.8782618260775;8187;8209.79475513117;8211.93102730875;8360.707375241838;4164.7917604119675;4166.583341665809;4258.1057977824385;4275.40144379787;4411.889702757392;3673.522442082512;3796.6013568575454;3815.751976121543;4029.71791974559;4033.0723933494874;709.7764204275637;712.618748969299;718.9712411503585;727.6252729844134;731.8828987361996;673.8079647300495;683.4600665474319;704.1409480433863;721.2390927571357;722.7578761115085;9784.070513301225;10413.314008695965;10472.10987066108;10488.528016239288;10781.460926953621;719.2914978745931;722.5917356693451;724.2467833453255;730.5653136383843;731.1226528166222;4704.482506924235;5011.523905228997;5012.273220383024;5017.347744793226;5303.549198318186;74997;79813.40373192503;88105.55947220222;93675;95351.52324238335;81744.59127704443;83987.16012839916;86318.13681863227;87359.68960465599;88240.55879720561;75631.62184189043;76650.46699066099;78110.6094469524;78745.21254787494;79214.60392698114;9348.737920380661;10670.46647667629;10725.731856703645;10761.569537218424;10850.45747712627;46774.59676209719;47089.764551177024;47626.142431454224;47926.520734792204;49107;63000.684996574724;63677.681611591644;64976.675116624116;65583.67208163929;66645.33354666585;12595.408511775653;12607.43266552989;12703;13383.263920484078;13438.932805335911;13677.589672309847;14145.787813183017;14355.282235888373;14961.700765984402;15260.542183734506;12776.808347874603;13711.725765484834;13799.172049677052;14335.641608959859;14482.34829432657;13057.673558160743;13366.465341386236;13769.380377882819;13809.235813644276;14180.787288190892;10885.346879187275;12114.515419383479;12910.935445322713;13045.934770326467;13270.668233294246;10367.94816025915;11441.141914356624;11573.768524629628;11733.765324693288;11780.234284771463;8218.301444377295;9387.249020078241;10939.56241750321;11233.71915702114;11242.100631949588 19:55:00 INFO - PROCESS | 2325 | __end_tp_report 19:55:00 INFO - PROCESS | 2325 | __start_cc_report 19:55:00 INFO - PROCESS | 2325 | _x_x_mozilla_cycle_collect,1402 19:55:00 INFO - PROCESS | 2325 | __end_cc_report 19:55:00 INFO - PROCESS | 2325 | __startTimestamp1486439700435__endTimestamp 19:55:00 INFO - PROCESS | 2325 | 19:55:00 INFO - PROCESS | 2325 | ------- Summary: start ------- 19:55:00 INFO - PROCESS | 2325 | Number of tests: 6 19:55:00 INFO - PROCESS | 2325 | 19:55:00 INFO - PROCESS | 2325 | [#0] dojo.html Cycles:165 Average:5728.72 Median:6004.55 stddev:636.28 (10.6%) stddev-sans-first:608.80 19:55:00 INFO - PROCESS | 2325 | Values: 3290.4 3303.7 3320.7 3322.4 3361.4 5703.1 5741.2 5751.4 5766.2 5772.6 4627.6 5646.7 5710.5 5741.0 5763.9 5161.0 5188.0 5198.9 5199.8 5261.9 5168.8 5175.0 5179.9 5282.3 5333.1 5167.9 5240.2 5247.9 5262.7 5268.8 5250.5 5251.8 5254.6 5254.8 5260.4 5088.1 5187.8 5216.7 5223.4 5275.8 5551.1 5573.4 5588.4 5613.1 5716.1 6164.0 6216.3 6230.1 6235.0 6246.1 5912.9 6249.4 6255.9 6262.4 6281.3 5667.7 5769.0 5773.7 5822.0 5873.2 6165.8 6173.3 6202.6 6300.3 6317.8 6144.6 6147.9 6150.8 6157.8 6163.6 6153.1 6228.4 6241.4 6245.5 6250.2 6091.1 6106.6 6117.6 6136.8 6166.6 6171.8 6193.7 6229.0 6234.8 6251.5 4948.4 4995.7 4999.0 5011.8 5041.8 4384.1 4393.7 4396.4 4412.3 4492.6 4981.1 5779.1 5788.2 5867.7 5892.4 6109.4 6153.7 6215.2 6236.1 6256.3 5718.6 5735.9 5746.0 5757.1 5780.5 5307.2 5347.8 5452.3 5461.2 5465.2 6179.3 6193.8 6213.4 6221.3 6286.8 6201.4 6233.5 6238.5 6242.1 6243.0 6295.9 6300.4 6313.2 6313.2 6317.8 6032.2 6048.2 6054.4 6079.1 6080.7 6148.6 6171.8 6177.7 6185.6 6185.9 6030.8 6076.5 6091.4 6111.8 6116.8 6004.5 6026.1 6058.9 6072.5 6094.1 5992.2 6011.1 6027.6 6031.5 6076.7 5976.5 6117.2 6135.7 6148.1 6158.3 5795.8 5859.8 5957.2 5995.4 6021.1 19:55:00 INFO - PROCESS | 2325 | 19:55:00 INFO - PROCESS | 2325 | [#1] ext.html Cycles:180 Average:69717.49 Median:35720.73 stddev:149528.28 (418.6%) stddev-sans-first:149857.20 19:55:00 INFO - PROCESS | 2325 | Values: 407.5 463.2 467.8 475.8 486.8 28345.7 37583.6 42731.8 42933.6 43031.8 42328.2 42941.8 42951.8 42990.6 43037.1 7652.0 8771.4 9350.1 9577.7 9582.4 11205.7 11494.9 11520.5 11660.0 11674.7 11530.9 11587.1 11603.3 11609.3 11634.1 11539.2 11615.0 11617.5 11648.7 11672.8 11386.8 11438.8 11440.3 11442.2 11470.3 41392.4 41639.0 41649.3 41658.8 41751.0 3976.8 3992.2 4051.4 4096.7 4144.9 28835.4 29844.7 29882.1 29941.6 30673.0 3209.7 3276.7 3402.6 3430.9 3431.2 489910.0 494948.0 495984.0 496729.0 498222.0 65333.7 65574.0 65663.7 65708.7 65804.7 68818.7 68837.7 68844.3 68984.7 69096.7 16483.5 17096.5 17129.7 17133.5 17193.0 11858.6 12063.0 12119.0 12866.8 12998.4 17981.6 18084.0 18398.3 18495.4 18617.0 7494.6 7540.6 7582.4 7594.1 7664.2 893.5 901.2 920.4 921.3 946.2 29375.7 30323.0 30383.5 31057.4 31152.7 1102.3 1103.2 1110.3 1110.3 1246.1 15498.8 15978.8 15990.9 15991.8 16080.7 630575.0 635425.0 636298.0 637011.0 639725.0 50973.0 62314.4 62481.4 62488.4 62771.1 1448.5 1450.8 1463.4 1493.8 1496.7 28440.3 29595.6 29959.0 30552.2 30585.8 52406.2 52952.9 53013.7 53055.7 53067.5 538908.0 539337.0 539996.0 541987.3 543738.0 24540.8 25037.0 25103.9 41383.0 41608.8 42905.8 43319.5 43864.6 44066.3 44263.9 35220.8 35537.0 36043.5 37355.6 37391.6 35805.0 35819.5 36259.8 36343.8 36519.5 35091.6 35244.8 35258.5 35529.0 35636.5 29940.4 32190.5 32235.5 32285.4 32520.5 24046.7 24180.9 24367.6 24542.1 24552.9 19:55:00 INFO - PROCESS | 2325 | 19:55:00 INFO - PROCESS | 2325 | [#2] jquery.html Cycles:180 Average:40281.84 Median:22927.43 stddev:123599.44 (539.1%) stddev-sans-first:123909.84 19:55:00 INFO - PROCESS | 2325 | Values: 369.3 393.4 393.5 404.5 424.0 14871.6 16393.8 18984.8 20830.7 21403.4 21333.7 21407.9 21992.0 22345.8 22692.3 5856.7 5897.1 6132.2 6149.6 6203.3 6104.5 6138.5 6153.6 6158.6 6159.4 5857.3 6153.6 6192.6 6235.6 6235.9 5697.2 6142.4 6179.7 6193.3 6367.6 6032.6 6045.6 6048.2 6053.5 6072.8 13480.7 13735.9 23162.5 24992.9 25978.4 2625.0 2670.1 2684.3 2703.1 2723.1 10058.8 10159.7 10556.8 10663.8 13023.2 1763.2 1861.8 1880.9 1882.8 1893.1 122001.4 125843.0 127229.0 127502.1 129673.0 9989.5 11819.7 12045.1 12050.6 12059.8 15665.7 16023.8 16053.5 16056.2 16073.0 7794.8 7964.9 8045.8 8154.0 8154.9 7563.2 7585.6 7620.6 7668.3 7734.1 4866.7 4891.2 4894.2 4897.7 4920.5 1710.5 1983.4 2034.5 2043.7 2047.8 953.3 967.6 1054.3 1073.3 1073.5 21853.5 21956.1 22009.0 22070.8 22327.1 918.8 951.3 1012.1 1048.5 1102.9 9481.5 10557.8 11354.5 11424.7 11578.7 729680.0 734483.0 734623.3 749209.0 764382.0 149120.0 149147.8 152580.0 154713.0 158161.0 988.8 992.4 1009.7 1014.1 1019.2 18604.6 19009.7 19384.7 19393.8 19453.0 55078.0 56713.7 58327.4 58780.1 60188.7 82409.0 85677.0 85937.0 86572.0 86984.1 8236.8 8244.7 8519.5 8644.7 8687.1 11451.4 13072.9 13199.7 13648.5 13701.4 8538.4 10688.5 10907.8 11336.9 11375.4 4519.9 8238.1 10179.4 11059.3 11799.6 11426.4 11529.1 11705.3 11834.0 12164.5 7261.8 8593.4 8710.7 8842.0 9095.0 10896.1 11343.0 11628.2 11678.5 11704.2 19:55:00 INFO - PROCESS | 2325 | 19:55:00 INFO - PROCESS | 2325 | [#3] mootools.html Cycles:180 Average:5343.91 Median:5287.06 stddev:2909.55 (55.0%) stddev-sans-first:2902.21 19:55:00 INFO - PROCESS | 2325 | Values: 1347.7 1351.8 1428.7 1449.5 1465.3 7895.7 8312.9 8315.6 8318.2 8322.5 7358.6 7589.5 7594.8 7743.1 8165.0 1945.3 2210.8 2304.4 2304.4 2308.4 2287.4 2314.3 2315.3 2319.8 2343.5 1985.5 2077.9 2226.4 2261.1 2301.9 2275.1 2297.0 2300.7 2302.5 2303.4 2250.8 2312.0 2315.9 2326.2 2328.1 8240.5 8313.2 8316.9 8319.6 8329.2 5070.8 5215.5 5216.0 5226.5 5246.4 3315.8 3453.3 3496.8 3518.9 3534.5 1192.2 1219.7 1246.5 1273.4 1277.0 10676.3 10708.4 10719.6 10741.8 10762.6 4514.2 4619.3 4668.0 4724.7 4724.7 9501.6 9512.7 9513.3 9540.2 9557.4 5211.7 5248.2 5248.4 5249.2 5258.5 4541.1 4686.5 4702.7 4775.0 4804.1 3195.6 3759.0 3808.6 3884.2 3898.0 1064.9 1264.8 1268.6 1281.3 1285.1 786.7 912.2 919.6 931.6 952.8 5199.9 5315.6 5542.1 5573.8 5582.7 560.7 561.5 573.2 573.5 577.9 3388.9 3600.4 3658.3 3663.6 3679.0 7644.9 9868.0 9882.6 9890.9 9915.8 9665.1 9757.5 9760.8 9850.6 9897.9 7436.7 7666.6 7742.7 7794.0 7798.0 5525.9 5619.8 5719.3 5797.7 5962.2 8905.3 9010.9 9069.2 9149.2 9263.2 7615.9 8023.8 9285.6 9428.8 9453.7 6982.2 7157.3 7162.6 7167.2 7174.4 7533.2 7606.3 7688.8 7699.0 7703.1 6899.0 6928.8 6974.6 7035.1 7054.5 6847.6 6852.0 6863.0 6926.7 6970.5 6279.2 6327.9 6368.0 6373.8 6380.9 4691.5 4826.4 4904.6 4906.8 5029.3 4833.3 4882.5 4885.5 4950.0 4961.9 19:55:00 INFO - PROCESS | 2325 | 19:55:00 INFO - PROCESS | 2325 | [#4] prototype.html Cycles:180 Average:4362.95 Median:4824.15 stddev:1305.17 (27.1%) stddev-sans-first:1273.69 19:55:00 INFO - PROCESS | 2325 | Values: 354.9 363.1 364.9 376.0 377.7 4651.4 4653.6 5081.7 5214.0 5261.5 5010.7 5125.5 5227.8 5256.8 5259.9 4154.3 4154.9 4157.2 4179.8 4180.1 3939.5 4112.7 4178.6 4204.4 4210.1 4105.2 4112.4 4137.4 4139.0 4165.8 3728.6 4033.9 4128.0 4142.5 4144.4 3386.6 3435.8 3706.7 3714.5 3718.2 4786.9 5063.4 5130.2 5183.7 5183.9 3632.4 4110.9 4351.3 4514.9 4531.1 5249.7 5511.8 5527.4 5528.7 5534.9 2941.9 3564.0 3796.9 3837.8 3845.3 5812.3 5814.4 5825.5 5855.0 5869.9 3868.0 3884.9 3909.9 3914.7 3921.6 3401.7 3749.9 3777.7 3848.7 3949.5 3900.6 4521.6 4568.4 4570.8 4678.7 4524.5 5120.4 5292.6 5580.5 5613.9 1074.7 1128.0 1172.2 1238.4 1274.4 752.9 835.9 860.4 861.6 865.2 4275.6 4523.9 4817.8 4872.5 4922.9 4486.8 4851.4 4932.0 5226.6 5389.6 4353.3 4396.0 4411.9 4830.5 4855.8 2473.6 2845.9 2850.9 2856.3 2859.9 5162.9 5247.3 5509.5 5571.3 5614.0 5286.6 5338.7 5539.0 5590.7 5652.3 4518.7 5398.2 5554.4 5558.8 5663.5 4993.5 5342.6 5369.7 5370.0 5379.4 4486.5 4981.5 5223.4 5332.3 5366.9 4743.5 4766.5 5184.7 5484.6 5607.3 3135.5 3291.7 3653.4 3655.7 3675.6 4537.5 4839.4 5055.9 5111.7 5369.5 4952.5 5011.1 5286.2 5368.8 5369.4 4874.7 5271.5 5470.0 5489.8 5503.6 4270.7 4556.7 5355.4 5411.9 5461.0 4535.1 5004.5 5063.4 5133.1 5506.3 4739.7 4782.8 5283.8 5430.7 5484.5 19:55:00 INFO - PROCESS | 2325 | 19:55:00 INFO - PROCESS | 2325 | [#5] yui.html Cycles:180 Average:19927.30 Median:4098.93 stddev:27973.38 (682.5%) stddev-sans-first:28014.61 19:55:00 INFO - PROCESS | 2325 | Values: 703.6 706.6 723.5 783.8 825.3 15366.5 15619.1 15925.2 15950.8 15963.8 14380.3 14473.1 14532.0 14542.1 14628.2 668.3 691.9 692.5 693.4 846.1 686.4 690.9 834.9 869.1 869.4 909.7 923.7 925.0 925.7 927.3 878.1 880.3 887.6 921.2 921.8 526.4 545.3 546.1 659.2 659.3 12407.2 12453.2 14251.6 14789.9 14829.7 14043.1 14086.3 14340.8 14438.4 14464.2 11113.5 11175.1 11820.4 12403.3 12430.9 3643.2 3796.4 3899.6 3925.5 3938.5 103148.0 107593.5 108352.0 111051.0 111927.0 10727.6 11292.1 11473.0 11703.3 11781.8 22509.0 23467.2 23902.6 24139.5 24184.3 8115.9 8187.0 8209.8 8211.9 8360.7 4164.8 4166.6 4258.1 4275.4 4411.9 3673.5 3796.6 3815.8 4029.7 4033.1 709.8 712.6 719.0 727.6 731.9 673.8 683.5 704.1 721.2 722.8 9784.1 10413.3 10472.1 10488.5 10781.5 719.3 722.6 724.2 730.6 731.1 4704.5 5011.5 5012.3 5017.3 5303.5 74997.0 79813.4 88105.6 93675.0 95351.5 81744.6 83987.2 86318.1 87359.7 88240.6 75631.6 76650.5 78110.6 78745.2 79214.6 9348.7 10670.5 10725.7 10761.6 10850.5 46774.6 47089.8 47626.1 47926.5 49107.0 63000.7 63677.7 64976.7 65583.7 66645.3 12595.4 12607.4 12703.0 13383.3 13438.9 13677.6 14145.8 14355.3 14961.7 15260.5 12776.8 13711.7 13799.2 14335.6 14482.3 13057.7 13366.5 13769.4 13809.2 14180.8 10885.3 12114.5 12910.9 13045.9 13270.7 10367.9 11441.1 11573.8 11733.8 11780.2 8218.3 9387.2 10939.6 11233.7 11242.1 19:55:00 INFO - PROCESS | 2325 | -------- Summary: end -------- 19:55:00 INFO - PROCESS | 2325 | 19:55:01 INFO - TEST-INFO | 2325: exit 0 19:55:01 INFO - TEST-OK | dromaeo_css | took 1117956ms 19:55:01 INFO - TEST-START | kraken 19:55:01 INFO - operating with platform_type : mac_ 19:55:01 INFO - Initialising browser for kraken test... 19:55:01 INFO - TEST-INFO | started process 2328 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpBhoapp/profile http://localhost:60215/getInfo.html) 19:55:03 INFO - PROCESS | 2328 | __metrics Screen width/height:1600/1200 19:55:03 INFO - PROCESS | 2328 | colorDepth:24 19:55:03 INFO - PROCESS | 2328 | Browser inner width/height: 1024/689 19:55:03 INFO - PROCESS | 2328 | __metrics 19:55:03 INFO - PROCESS | 2328 | 1486439703524 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884: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 19:55:03 INFO - PROCESS | 2328 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:55:03 INFO - PROCESS | 2328 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:55:04 INFO - TEST-INFO | 2328: exit 0 19:55:04 INFO - Browser initialized. 19:55:04 INFO - Running cycle 1/1 for kraken test... 19:55:04 INFO - TEST-INFO | started process 2329 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpBhoapp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:55:16 INFO - PROCESS | 2329 | RSS: Main: 212877312 19:55:16 INFO - PROCESS | 2329 | 19:55:19 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60215/tests/kraken/driver.html?testName=audio-beat-detection) 19:55:20 INFO - PROCESS | 2329 | RSS: Main: 265748480 19:55:20 INFO - PROCESS | 2329 | 19:55:22 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60215/tests/kraken/driver.html?testName=audio-dft) 19:55:23 INFO - PROCESS | 2329 | RSS: Main: 274677760 19:55:23 INFO - PROCESS | 2329 | 19:55:26 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60215/tests/kraken/driver.html?testName=audio-fft) 19:55:27 INFO - PROCESS | 2329 | RSS: Main: 371261440 19:55:27 INFO - PROCESS | 2329 | 19:55:29 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60215/tests/kraken/driver.html?testName=audio-oscillator) 19:55:29 INFO - PROCESS | 2329 | RSS: Main: 275234816 19:55:29 INFO - PROCESS | 2329 | 19:55:31 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60215/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:55:32 INFO - PROCESS | 2329 | RSS: Main: 277319680 19:55:32 INFO - PROCESS | 2329 | 19:55:35 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60215/tests/kraken/driver.html?testName=imaging-darkroom) 19:55:35 INFO - PROCESS | 2329 | RSS: Main: 316837888 19:55:35 INFO - PROCESS | 2329 | 19:55:38 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60215/tests/kraken/driver.html?testName=imaging-desaturate) 19:55:39 INFO - PROCESS | 2329 | RSS: Main: 317259776 19:55:39 INFO - PROCESS | 2329 | 19:55:42 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60215/tests/kraken/driver.html?testName=json-parse-financial) 19:55:42 INFO - PROCESS | 2329 | RSS: Main: 314597376 19:55:42 INFO - PROCESS | 2329 | 19:55:44 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60215/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:55:44 INFO - PROCESS | 2329 | RSS: Main: 294793216 19:55:44 INFO - PROCESS | 2329 | 19:55:46 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60215/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:55:47 INFO - PROCESS | 2329 | RSS: Main: 296624128 19:55:47 INFO - PROCESS | 2329 | 19:55:49 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60215/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:55:50 INFO - PROCESS | 2329 | RSS: Main: 299532288 19:55:50 INFO - PROCESS | 2329 | 19:55:53 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60215/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:55:53 INFO - PROCESS | 2329 | RSS: Main: 316362752 19:55:53 INFO - PROCESS | 2329 | 19:55:56 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60215/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:55:57 INFO - PROCESS | 2329 | RSS: Main: 310517760 19:55:57 INFO - PROCESS | 2329 | 19:55:58 INFO - PROCESS | 2329 | Cycle 1(1): loaded http://localhost:60215/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60215/tests/kraken/driver.html?testName=ai-astar) 19:55:59 INFO - PROCESS | 2329 | RSS: Main: 354897920 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | __start_tp_report 19:55:59 INFO - PROCESS | 2329 | _x_x_mozilla_page_load 19:55:59 INFO - PROCESS | 2329 | _x_x_mozilla_page_load_details 19:55:59 INFO - PROCESS | 2329 | |i|pagename|runs| 19:55:59 INFO - PROCESS | 2329 | |0;ai-astar;116;115;114;118;165;164;114;114;113;114 19:55:59 INFO - PROCESS | 2329 | |1;audio-beat-detection;132;111;108;140;110;107;108;107;129;106 19:55:59 INFO - PROCESS | 2329 | |2;audio-dft;186;185;184;183;185;183;183;185;186;182 19:55:59 INFO - PROCESS | 2329 | |3;audio-fft;80;60;58;91;58;60;59;60;74;62 19:55:59 INFO - PROCESS | 2329 | |4;audio-oscillator;91;71;86;69;67;69;82;67;69;83 19:55:59 INFO - PROCESS | 2329 | |5;imaging-gaussian-blur;89;86;88;85;88;85;89;86;90;85 19:55:59 INFO - PROCESS | 2329 | |6;imaging-darkroom;93;94;94;95;94;94;94;94;93;94 19:55:59 INFO - PROCESS | 2329 | |7;imaging-desaturate;85;85;86;88;84;87;85;88;88;84 19:55:59 INFO - PROCESS | 2329 | |8;json-parse-financial;58;52;54;55;57;58;58;58;59;56 19:55:59 INFO - PROCESS | 2329 | |9;json-stringify-tinderbox;66;76;70;78;74;71;72;73;73;73 19:55:59 INFO - PROCESS | 2329 | |10;stanford-crypto-aes;86;85;85;83;84;82;83;84;84;83 19:55:59 INFO - PROCESS | 2329 | |11;stanford-crypto-ccm;156;146;139;138;137;142;139;141;182;151 19:55:59 INFO - PROCESS | 2329 | |12;stanford-crypto-pbkdf2;168;171;165;168;169;167;167;168;168;168 19:55:59 INFO - PROCESS | 2329 | |13;stanford-crypto-sha256-iterative;65;67;67;63;67;67;69;70;61;74 19:55:59 INFO - PROCESS | 2329 | __end_tp_report 19:55:59 INFO - PROCESS | 2329 | __start_cc_report 19:55:59 INFO - PROCESS | 2329 | _x_x_mozilla_cycle_collect,1997 19:55:59 INFO - PROCESS | 2329 | __end_cc_report 19:55:59 INFO - PROCESS | 2329 | __startTimestamp1486439759246__endTimestamp 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | ------- Summary: start ------- 19:55:59 INFO - PROCESS | 2329 | Number of tests: 14 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#0] ai-astar Cycles:10 Average:124.70 Median:115.50 stddev:21.02 (18.2%) stddev-sans-first:22.06 19:55:59 INFO - PROCESS | 2329 | Values: 116.0 115.0 114.0 118.0 165.0 164.0 114.0 114.0 113.0 114.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#1] audio-beat-detection Cycles:10 Average:115.80 Median:110.50 stddev:12.70 (11.5%) stddev-sans-first:12.04 19:55:59 INFO - PROCESS | 2329 | Values: 132.0 111.0 108.0 140.0 110.0 107.0 108.0 107.0 129.0 106.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:1.40 (0.8%) stddev-sans-first:1.32 19:55:59 INFO - PROCESS | 2329 | Values: 186.0 185.0 184.0 183.0 185.0 183.0 183.0 185.0 186.0 182.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#3] audio-fft Cycles:10 Average:66.20 Median:61.00 stddev:11.48 (18.8%) stddev-sans-first:11.03 19:55:59 INFO - PROCESS | 2329 | Values: 80.0 60.0 58.0 91.0 58.0 60.0 59.0 60.0 74.0 62.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:76.50 stddev:9.07 (11.9%) stddev-sans-first:7.66 19:55:59 INFO - PROCESS | 2329 | Values: 91.0 71.0 86.0 69.0 67.0 69.0 82.0 67.0 69.0 83.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:88.00 stddev:1.91 (2.2%) stddev-sans-first:1.90 19:55:59 INFO - PROCESS | 2329 | Values: 89.0 86.0 88.0 85.0 88.0 85.0 89.0 86.0 90.0 85.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 19:55:59 INFO - PROCESS | 2329 | Values: 93.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 93.0 94.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.50 stddev:1.63 (1.9%) stddev-sans-first:1.69 19:55:59 INFO - PROCESS | 2329 | Values: 85.0 85.0 86.0 88.0 84.0 87.0 85.0 88.0 88.0 84.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:58.00 stddev:2.22 (3.8%) stddev-sans-first:2.29 19:55:59 INFO - PROCESS | 2329 | Values: 58.0 52.0 54.0 55.0 57.0 58.0 58.0 58.0 59.0 56.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:73.00 stddev:3.27 (4.5%) stddev-sans-first:2.45 19:55:59 INFO - PROCESS | 2329 | Values: 66.0 76.0 70.0 78.0 74.0 71.0 72.0 73.0 73.0 73.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:1.20 (1.4%) stddev-sans-first:1.00 19:55:59 INFO - PROCESS | 2329 | Values: 86.0 85.0 85.0 83.0 84.0 82.0 83.0 84.0 84.0 83.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:144.00 stddev:13.71 (9.5%) stddev-sans-first:14.16 19:55:59 INFO - PROCESS | 2329 | Values: 156.0 146.0 139.0 138.0 137.0 142.0 139.0 141.0 182.0 151.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:168.00 stddev:1.52 (0.9%) stddev-sans-first:1.62 19:55:59 INFO - PROCESS | 2329 | Values: 168.0 171.0 165.0 168.0 169.0 167.0 167.0 168.0 168.0 168.0 19:55:59 INFO - PROCESS | 2329 | 19:55:59 INFO - PROCESS | 2329 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:3.62 (5.4%) stddev-sans-first:3.77 19:55:59 INFO - PROCESS | 2329 | Values: 65.0 67.0 67.0 63.0 67.0 67.0 69.0 70.0 61.0 74.0 19:55:59 INFO - PROCESS | 2329 | -------- Summary: end -------- 19:55:59 INFO - PROCESS | 2329 | 19:56:00 INFO - TEST-INFO | 2329: exit 0 19:56:00 INFO - TEST-OK | kraken | took 59154ms 19:56:00 INFO - SUITE-END | took 1177s 19:56:00 INFO - Completed test suite (00:19:37) 19:56:00 INFO - javascript benchmark 19:56:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3290.437042404857, 3303.719183869371, 3320.6679332066797, 3322.3853587086405, 3361.378145043167, 5703.144528320749, 5741.224934633823, 5751.396103409134, 5766.221560089387, 5772.624779389337, 4627.583517483426, 5646.74589643466, 5710.457506536873, 5741.0240259155935, 5763.942360576393, 5160.974195129019, 5188.0, 5198.896022079556, 5199.818006369778, 5261.921071183916, 5168.7932482700835, 5175.0, 5179.896402071975, 5282.260483532308, 5333.120035194208, 5167.896642067157, 5240.2401651760665, 5247.947520524803, 5262.710550919698, 5268.841934741964, 5250.527452529291, 5251.789928402859, 5254.605904557155, 5254.789808407659, 5260.421353651095, 5088.1350170471105, 5187.792488300502, 5216.713080780555, 5223.37319521656, 5275.815346462856, 5551.056320425538, 5573.359063707685, 5588.413216612238, 5613.07384281593, 5716.1425786131995, 6163.96918015407, 6216.316205217422, 6230.096635987802, 6235.033569796688, 6246.125542424064, 5912.852178695524, 6249.375062493759, 6255.874882502325, 6262.405071518198, 6281.309056003882, 5667.716614169275, 5768.9711551442815, 5773.682447465346, 5822.039363505017, 5873.20711703923, 6165.753369865245, 6173.290071641729, 6202.596831205957, 6300.338464461257, 6317.778877739257, 6144.600600960924, 6147.907781383283, 6150.753969841246, 6157.784477543354, 6163.568550201443, 6153.107799369156, 6228.408301211378, 6241.438270555672, 6245.531585131133, 6250.218722659668, 6091.116788065706, 6106.55920977448, 6117.602355846856, 6136.754529818846, 6166.568340216231, 6171.783987560414, 6193.721282542297, 6228.968855155786, 6234.812955611248, 6251.468625166821, 4948.406191257072, 4995.675281106716, 4999.02519008797, 5011.824586139468, 5041.823536176216, 4384.145091707151, 4393.7363758174615, 4396.428464299621, 4412.33814927757, 4492.595666389975, 4981.078500477423, 5779.075347944308, 5788.21859049023, 5867.677277749679, 5892.381299963527, 6109.374301065143, 6153.661548614869, 6215.160953271341, 6236.126942228092, 6256.280527739277, 5718.571107166979, 5735.885282294331, 5746.0, 5757.107648314518, 5780.508656764138, 5307.15085586312, 5347.812826551052, 5452.318460192476, 5461.1808228766, 5465.180222966586, 6179.3202747698615, 6193.84515387119, 6213.37866213375, 6221.253449585988, 6286.842828929313, 6201.379862013762, 6233.532485063548, 6238.469730072995, 6242.063690446469, 6243.032329988859, 6295.905561416491, 6300.401461861115, 6313.179286692701, 6313.210848643919, 6317.778877739257, 6032.215811944419, 6048.243969503812, 6054.424829641177, 6079.148919151234, 6080.665563394055, 6148.600340977822, 6171.783987560414, 6177.6911154443, 6185.567010309235, 6185.907211391743, 6030.75876964934, 6076.51387888959, 6091.390860913873, 6111.755529778937, 6116.785912493041, 6004.5496587755215, 6026.0659597762415, 6058.909116363347, 6072.453479186895, 6094.08588711679, 5992.191054207626, 6011.068284415923, 6027.608205466806, 6031.457168854824, 6076.665783381956, 5976.462118409364, 6117.174181485442, 6135.662538560422, 6148.077788331786, 6158.3225845157895, 5795.76816927335, 5859.824205273849, 5957.225560677084, 5995.370486098896, 6021.066734656135], "value": 5687.644660078866, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.51098681582107, 463.21947518431443, 467.8175511550494, 475.75498618211617, 486.77851577532203, 28345.716542834565, 37583.62416375839, 42731.79511536635, 42933.57066429335, 43031.78484107575, 42328.15343693124, 42941.78529107358, 42951.785241073754, 42990.570094299124, 43037.13925721491, 7652.005239318881, 8771.386002979794, 9350.064993500646, 9577.664781732641, 9582.425054496718, 11205.66383008507, 11494.885051149507, 11520.539178432853, 11660.0, 11674.708132296675, 11530.884691153107, 11587.130965177603, 11603.303801771879, 11609.30344179352, 11634.127440441958, 11539.192256542045, 11615.012723918477, 11617.477213525412, 11648.708782280426, 11672.766544669144, 11386.829197562043, 11438.828417573743, 11440.256383335056, 11442.199046066778, 11470.311781293149, 41392.379114313306, 41639.0, 41649.3340266389, 41658.79170604158, 41751.0, 3976.7812770297624, 3992.201559688074, 4051.351783714621, 4096.672266218696, 4144.896377590554, 28835.42329153426, 29844.701552984625, 29882.103536893927, 29941.550876736866, 30673.0, 3209.6950789674943, 3276.6723327667282, 3402.6427225141497, 3430.9142271443166, 3431.227973705921, 489910.0, 494948.0, 495984.0, 496729.0, 498222.0, 65333.69332613321, 65574.0, 65663.67168164128, 65708.67145664337, 65804.67097664576, 68818.65590672115, 68837.65581172062, 68844.31155688479, 68984.65507672429, 69096.6545167271, 16483.505494835175, 17096.487105386608, 17129.743053854203, 17133.485995420157, 17193.0, 11858.644240672793, 12063.0, 12118.96988755948, 12866.806997895039, 12998.350082496027, 17981.550461238574, 18084.0, 18398.26406943734, 18495.44513664592, 18617.0, 7494.5503269803985, 7540.585267810323, 7582.393408527305, 7594.126675432396, 7664.233576642291, 893.4862454088795, 901.1709227510706, 920.3741455810114, 921.2537844346101, 946.186279799372, 29375.6780944858, 30323.0, 30383.544246836314, 31057.378852423277, 31152.68847311543, 1102.2669924500117, 1103.1671088328421, 1110.2616759854607, 1110.3005106782675, 1246.052999720219, 15498.767518487231, 15978.776236179332, 15990.920045399698, 15991.760123598155, 16080.678386432206, 630575.0, 635425.0, 636298.0, 637011.0, 639725.0, 50973.0, 62314.376856231764, 62481.375186247555, 62488.37511624917, 62771.05843412352, 1448.4857875454484, 1450.782382642612, 1463.4219483304034, 1493.835678075411, 1496.6632507685683, 28440.288992775346, 29595.556066658588, 29959.0, 30552.236194095327, 30585.847070764503, 52406.21390679067, 52952.94094118173, 53013.734931325096, 53055.734721326146, 53067.46932530625, 538908.0, 539337.0, 539996.0, 541987.2900635629, 543738.0, 24540.772961351504, 25037.0, 25103.87448062748, 41382.96542586339, 41608.791956040026, 42905.78547107244, 43319.53443724557, 43864.561354386045, 44066.33900491559, 44263.89340266519, 35220.823895880356, 35537.045462498776, 36043.459348109274, 37355.6264437363, 37391.62608373881, 35805.0, 35819.462708058876, 36259.818700906326, 36343.818280908425, 36519.45220821637, 35091.64908350884, 35244.82377588198, 35258.471122932664, 35529.0, 35636.46545301875, 29940.4011919756, 32190.517142243352, 32235.516467252542, 32285.35429291354, 32520.51219231761, 24046.6957102547, 24180.87909560441, 24367.634485482376, 24542.141025064037, 24552.87723561371], "value": 20483.701879361917, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.2651623269694, 393.4236343756396, 393.46095848687287, 404.48226270373914, 423.95706561857827, 14871.604310496568, 16393.836061639395, 18984.810151898477, 20830.687539686918, 21403.357899263046, 21333.67999480009, 21407.892960535213, 21992.0, 22345.776542234573, 22692.319230423112, 5856.707164641773, 5897.14491398749, 6132.233470816148, 6149.631022138664, 6203.348648391921, 6104.450599446049, 6138.478229350489, 6153.630782153063, 6158.56890017699, 6159.353267906872, 5857.26784151981, 6153.630782153063, 6192.5974811637325, 6235.625862448268, 6235.875282494348, 5697.202391665171, 6142.385761423867, 6179.66011869347, 6193.3496982816605, 6367.61794292344, 6032.638041717467, 6045.6070355426755, 6048.213732214828, 6053.485453736438, 6072.817815465543, 13480.730385392237, 13735.862641373558, 23162.53674926492, 24992.875035624886, 25978.350541236432, 2624.973750262492, 2670.092168662664, 2684.3020814588153, 2703.0809524761676, 2723.128598848369, 10058.849117263248, 10159.746006349826, 10556.841647375295, 10663.786724265472, 13023.218606883618, 1763.1536862306036, 1861.7858946221088, 1880.887146771198, 1882.7646544181978, 1893.1007771308655, 122001.38999305035, 125843.0, 127229.0, 127502.08746868804, 129673.0, 9989.500524973722, 11819.704507387383, 12045.096617753528, 12050.578229761917, 12059.819102713467, 15665.68668626621, 16023.839761602469, 16053.518394448185, 16056.197190140447, 16073.0, 7794.844103117905, 7964.920350796534, 8045.839083218303, 8154.0, 8154.918450815416, 7563.205863384375, 7585.62071896403, 7620.618969051525, 7668.309852113337, 7734.110577283572, 4866.683665561727, 4891.241857512091, 4894.1679914414635, 4897.681650692693, 4920.458749537548, 1710.45265515034, 1983.42480680601, 2034.4506983114477, 2043.6627956387326, 2047.764507081675, 953.3279038277998, 967.6081187119228, 1054.2620165883911, 1073.313169263582, 1073.4632683658172, 21853.453663658216, 21956.121755129934, 22009.0, 22070.779292206873, 22327.097477328167, 918.7611221082567, 951.3245595627183, 1012.063840947121, 1048.5229220704562, 1102.8897110288906, 9481.525923703788, 10557.841632375521, 11354.545818167346, 11424.657260282205, 11578.710532236593, 729680.0, 734483.0, 734623.3268833729, 749209.0, 764382.0, 149120.0, 149147.76278355834, 152580.0, 154713.0, 158161.0, 988.7676396046924, 992.369845148336, 1009.6769033909226, 1014.127850048972, 1019.2152042926905, 18604.627907441503, 19009.71485427692, 19384.70922936185, 19393.8060619392, 19453.0, 55078.0, 56713.71643141923, 58327.4167258322, 58780.118298224705, 60188.699056506186, 82409.0, 85677.0, 85937.0, 86572.0, 86984.13015870014, 8236.794080148044, 8244.6808510638, 8519.48883067018, 8644.740657780276, 8687.131286871263, 11451.427428628369, 13072.869271307545, 13199.67000824987, 13648.522301719393, 13701.383437745126, 8538.359623028422, 10688.519016644113, 10907.800141984217, 11336.886631133913, 11375.43122843838, 4519.932201017054, 8238.052623948322, 10179.440130792878, 11059.336439813636, 11799.587014454453, 11426.400303957287, 11529.135314851588, 11705.297682139098, 11834.0, 12164.452599633214, 7261.821453564015, 8593.355498337525, 8710.738677839676, 8842.0, 9094.954525227331, 10896.073833724222, 11343.0, 11628.18602697803, 11678.532858685898, 11704.180707350404], "value": 9891.027518543953, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.739863227882, 1351.831021122359, 1428.7285415771003, 1449.5433938309438, 1465.347920175522, 7895.723649672263, 8312.91687083129, 8315.584220788967, 8318.20977007184, 8322.459040162385, 7358.595277259748, 7589.544627322364, 7594.848103037936, 7743.148253692089, 8164.979377577803, 1945.3483083167157, 2210.789974952381, 2304.3547806614174, 2304.44693273614, 2308.376738280663, 2287.4281429642588, 2314.2941402872116, 2315.305408377488, 2319.7564255753155, 2343.4727186383097, 1985.4936991067257, 2077.8753274803485, 2226.365485836537, 2261.1068627891973, 2301.942451438719, 2275.101334972685, 2296.954060918789, 2300.723913130418, 2302.470431800687, 2303.389601755536, 2250.797428231467, 2312.0405031911737, 2315.8842057897036, 2326.1858349577683, 2328.0571368595574, 8240.50556966584, 8313.210245026712, 8316.875246871301, 8319.584020798935, 8329.167083291683, 5070.771815268285, 5215.504527069922, 5216.0, 5226.529612334908, 5246.422893481714, 3315.834208289576, 3453.326601312745, 3496.8426420811124, 3518.9120271993147, 3534.469829525566, 1192.1773975956648, 1219.743853790705, 1246.5076294863568, 1273.414229454452, 1277.0485987939044, 10676.306040107369, 10708.357498550113, 10719.571217151382, 10741.785164296749, 10762.623308184176, 4514.210013247669, 4619.307103934437, 4668.019715859639, 4724.71651700899, 4724.740139292372, 9501.572429240701, 9512.714618561455, 9513.286503512265, 9540.236781057498, 9557.426554406758, 5211.713355765393, 5248.212768084818, 5248.3701955765555, 5249.186376111633, 5258.526732594085, 4541.091781643684, 4686.484486706459, 4702.741349225826, 4775.0, 4804.06320767447, 3195.5526226328334, 3758.962410375916, 3808.5620153682125, 3884.223155368881, 3898.044978980168, 1064.9307794993301, 1264.759695657822, 1268.5560053981033, 1281.269676284509, 1285.0940087238475, 786.6853258696567, 912.1790388650268, 919.5769945824861, 931.6133804471068, 952.7856232347666, 5199.870003249949, 5315.6013299002725, 5542.057850165482, 5573.832785016456, 5582.748776305076, 560.7336515155268, 561.4581928439076, 573.1631817546346, 573.5239751006654, 577.8844231153786, 3388.881389151368, 3600.369935261319, 3658.286634106377, 3663.5970043295215, 3679.0434487033563, 7644.923550764533, 9867.950660246654, 9882.555285012191, 9890.851637225447, 9915.801683966425, 9665.130138287588, 9757.463339516395, 9760.755981100529, 9850.60597576089, 9897.851532227023, 7436.66535005926, 7666.578338191453, 7742.651580678883, 7793.961030194926, 7797.961010194913, 5525.861853453535, 5619.831405057855, 5719.28508936383, 5797.710114494174, 5962.1951036611235, 8905.33210009239, 9010.909890901006, 9069.229115525255, 9149.222316103205, 9263.16631503168, 7615.885761713468, 8023.759287221393, 9285.6285748572, 9428.811423771349, 9453.669121580986, 6982.162140543167, 7157.284271572801, 7162.606056666933, 7167.247439018828, 7174.354308112086, 7533.2466753324225, 7606.315431611181, 7688.846223075395, 7699.037620297463, 7703.114141873756, 6899.0, 6928.826779330557, 6974.616396098263, 7035.085438892911, 7054.506184567031, 6847.554908930903, 6852.0, 6862.965685171542, 6926.653667316513, 6970.477214209056, 6279.246490421178, 6327.873442531216, 6368.012957991518, 6373.776917807854, 6380.93619063822, 4691.530846915418, 4826.420829500483, 4904.607631389409, 4906.803727850989, 5029.270755740324, 4833.323334733211, 4882.4629290777275, 4885.4870238624435, 4949.950500494949, 4961.8511444656715], "value": 4306.964516235507, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.8686985815249, 363.1411711302768, 364.9087728067982, 376.04672156084314, 377.69784172661866, 4651.441826980758, 4653.627709783222, 5081.6696914700615, 5214.0, 5261.473852614737, 5010.749462526868, 5125.461826508219, 5227.764750586223, 5256.8160114396005, 5259.8691281374495, 4154.273002224613, 4154.898951777781, 4157.189348077126, 4179.770112643819, 4180.122174343384, 3939.487866577341, 4112.712110152291, 4178.56125106864, 4204.369344598304, 4210.136921930997, 4105.158442519277, 4112.444819949298, 4137.40007698885, 4138.9586104139025, 4165.791710414483, 3728.571214310362, 4033.8705162554534, 4127.9587204128175, 4142.46148000761, 4144.357624568197, 3386.6274709781906, 3435.7766745161484, 3706.6664000240107, 3714.4799728038097, 3718.219173973469, 4786.941615105503, 5063.367079115111, 5130.230465430178, 5183.719163568018, 5183.896322073575, 3632.400653892105, 4110.91778164438, 4351.303791393393, 4514.909701805946, 4531.071130418256, 5249.685018898878, 5511.779528818843, 5527.364353099405, 5528.723563821793, 5534.896739239103, 2941.852907354645, 3563.9664497295753, 3796.886093417202, 3837.827297771607, 3845.288621605012, 5812.273465816773, 5814.360420353715, 5825.533957283407, 5854.970725146432, 5869.941300587025, 3867.9829732808453, 3884.864029758945, 3909.9413508797393, 3914.706397020178, 3921.5882332355263, 3401.734003986373, 3749.8500059997295, 3777.660010599004, 3848.748482137925, 3949.526056873136, 3900.570937196906, 4521.638268938477, 4568.406107206042, 4570.794314255866, 4678.719276843401, 4524.524924882906, 5120.359955005624, 5292.550133238641, 5580.525655319262, 5613.887722245532, 1074.6775967209805, 1127.9548818047351, 1172.2380452705809, 1238.4303220518482, 1274.4328773695802, 752.9345975444685, 835.8829763833069, 860.3633311349556, 861.6208868098017, 865.2385900407601, 4275.593818587291, 4523.869032741814, 4817.78319975602, 4872.512748725099, 4922.852314430573, 4486.842960496367, 4851.36932198819, 4931.950680493149, 5226.5818734501145, 5389.649672771257, 4353.3469979503325, 4395.978020109879, 4411.889702757392, 4830.516948305211, 4855.75721213945, 2473.5547601431554, 2845.857707114636, 2850.8717107730204, 2856.3287627407544, 2859.8570071496342, 5162.9415969726115, 5247.291615631841, 5509.5041446270025, 5571.303587051619, 5613.95090791858, 5286.62993590445, 5338.679679219259, 5539.011911423769, 5590.679867854795, 5652.2652055233375, 4518.6611004174165, 5398.24424580559, 5554.444555544414, 5558.832645144565, 5663.518600918886, 4993.525615066563, 5342.569131045288, 5369.731513424235, 5370.0, 5379.35447746273, 4486.506484286791, 4981.452040275494, 5223.399309079353, 5332.280142180756, 5366.919496207638, 4743.549362810528, 4766.547178018082, 5184.729741213276, 5484.561235101102, 5607.299087614048, 3135.5296705494357, 3291.6543762905508, 3653.3789255827105, 3655.6892664123848, 3675.6140605236064, 4537.455505339381, 4839.402997011049, 5055.87360315995, 5111.667741596784, 5369.543588794996, 4952.504749525018, 5011.148104822262, 5286.20706893969, 5368.785248590013, 5369.436209197978, 4874.658773885794, 5271.525562699376, 5469.972650136883, 5489.807856724996, 5503.587230957774, 4270.7010509264055, 4556.703814252063, 5355.384130825005, 5411.864703382447, 5460.952142946395, 4535.092981403798, 5004.47453017428, 5063.367079115111, 5133.10170720115, 5506.256655351476, 4739.668223224341, 4782.808687652455, 5283.78472951231, 5430.728463576885, 5484.506394424521], "value": 3959.0754372521696, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.5567592416777, 706.6007705646306, 723.5477826358526, 783.804048987753, 825.3108654273681, 15366.539003829876, 15619.140947247897, 15925.203739813007, 15950.76073858893, 15963.84036159638, 14380.280985950712, 14473.131612103281, 14531.982761206693, 14542.054766440175, 14628.195449250286, 668.3350066683632, 691.9481038922075, 692.4840993459879, 693.448708276921, 846.0904527632804, 686.4062585863243, 690.8894576867702, 834.9123342049088, 869.0961400143843, 869.3957699398889, 909.727081875438, 923.6536298887917, 925.0379605210607, 925.7130289610209, 927.2906226736552, 878.086789738671, 880.2693764175783, 887.5695287785451, 921.235374639048, 921.8478950973081, 526.4183077699125, 545.3265217456438, 546.1261980830678, 659.24845675929, 659.3340725866909, 12407.193532420364, 12453.190542614791, 14251.57245282643, 14789.852101478955, 14829.703405931929, 14043.08719933201, 14086.349542388412, 14340.78488822658, 14438.350274237684, 14464.204468754215, 11113.499892504777, 11175.105991520659, 11820.408979550988, 12403.317817520032, 12430.875691243062, 3643.2167084076787, 3796.3925771876643, 3899.590542992973, 3925.450436938831, 3938.468306778577, 103147.96852031533, 107593.46203269012, 108352.0, 111051.0, 111927.0, 10727.624533141303, 11292.096632269398, 11473.0, 11703.2978021319, 11781.823272650918, 22509.0, 23467.211480964856, 23902.64146037811, 24139.517209655707, 24184.274471765875, 8115.8782618260775, 8187.0, 8209.79475513117, 8211.93102730875, 8360.707375241838, 4164.7917604119675, 4166.583341665809, 4258.1057977824385, 4275.40144379787, 4411.889702757392, 3673.522442082512, 3796.6013568575454, 3815.751976121543, 4029.71791974559, 4033.0723933494874, 709.7764204275637, 712.618748969299, 718.9712411503585, 727.6252729844134, 731.8828987361996, 673.8079647300495, 683.4600665474319, 704.1409480433863, 721.2390927571357, 722.7578761115085, 9784.070513301225, 10413.314008695965, 10472.10987066108, 10488.528016239288, 10781.460926953621, 719.2914978745931, 722.5917356693451, 724.2467833453255, 730.5653136383843, 731.1226528166222, 4704.482506924235, 5011.523905228997, 5012.273220383024, 5017.347744793226, 5303.549198318186, 74997.0, 79813.40373192503, 88105.55947220222, 93675.0, 95351.52324238335, 81744.59127704443, 83987.16012839916, 86318.13681863227, 87359.68960465599, 88240.55879720561, 75631.62184189043, 76650.46699066099, 78110.6094469524, 78745.21254787494, 79214.60392698114, 9348.737920380661, 10670.46647667629, 10725.731856703645, 10761.569537218424, 10850.45747712627, 46774.59676209719, 47089.764551177024, 47626.142431454224, 47926.520734792204, 49107.0, 63000.684996574724, 63677.681611591644, 64976.675116624116, 65583.67208163929, 66645.33354666585, 12595.408511775653, 12607.43266552989, 12703.0, 13383.263920484078, 13438.932805335911, 13677.589672309847, 14145.787813183017, 14355.282235888373, 14961.700765984402, 15260.542183734506, 12776.808347874603, 13711.725765484834, 13799.172049677052, 14335.641608959859, 14482.34829432657, 13057.673558160743, 13366.465341386236, 13769.380377882819, 13809.235813644276, 14180.787288190892, 10885.346879187275, 12114.515419383479, 12910.935445322713, 13045.934770326467, 13270.668233294246, 10367.94816025915, 11441.141914356624, 11573.768524629628, 11733.765324693288, 11780.234284771463, 8218.301444377295, 9387.249020078241, 10939.56241750321, 11233.71915702114, 11242.100631949588], "value": 7490.010347642555, "unit": "score"}], "name": "dromaeo_css", "value": 7266.303380829349, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 114.0, 118.0, 165.0, 164.0, 114.0, 114.0, 113.0, 114.0], "value": 124.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 111.0, 108.0, 140.0, 110.0, 107.0, 108.0, 107.0, 129.0, 106.0], "value": 115.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 184.0, 183.0, 185.0, 183.0, 183.0, 185.0, 186.0, 182.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 58.0, 91.0, 58.0, 60.0, 59.0, 60.0, 74.0, 62.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 86.0, 69.0, 67.0, 69.0, 82.0, 67.0, 69.0, 83.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 85.0, 88.0, 85.0, 89.0, 86.0, 90.0, 85.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 88.0, 84.0, 87.0, 85.0, 88.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 52.0, 54.0, 55.0, 57.0, 58.0, 58.0, 58.0, 59.0, 56.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 70.0, 78.0, 74.0, 71.0, 72.0, 73.0, 73.0, 73.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 83.0, 84.0, 82.0, 83.0, 84.0, 84.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 146.0, 139.0, 138.0, 137.0, 142.0, 139.0, 141.0, 182.0, 151.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 171.0, 165.0, 168.0, 169.0, 167.0, 167.0, 168.0, 168.0, 168.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 67.0, 63.0, 67.0, 67.0, 69.0, 70.0, 61.0, 74.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1428.3, "alertThreshold": 2.0}]} 19:56:00 INFO - Return code: 0 19:56:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:56:00 ERROR - # TBPL SUCCESS # 19:56:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:56:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:56:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:56:00 INFO - 2017-02-06 19:56:00.568 screenresolution[2333:77142] starting screenresolution argv=screenresolution get 19:56:00 INFO - 2017-02-06 19:56:00.570 screenresolution[2333:77142] Display 0: 1600x1200x32@60 19:56:00 INFO - 2017-02-06 19:56:00.576 screenresolution[2334:77144] starting screenresolution argv=screenresolution list 19:56:00 INFO - Available Modes on Display 0 19:56:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:56:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:56:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:56:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:56:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:56:00 INFO - Intel Iris: 19:56:00 INFO - Chipset Model: Intel Iris 19:56:00 INFO - Type: GPU 19:56:00 INFO - Bus: Built-In 19:56:00 INFO - VRAM (Dynamic, Max): 1536 MB 19:56:00 INFO - Vendor: Intel (0x8086) 19:56:00 INFO - Device ID: 0x0a2e 19:56:00 INFO - Revision ID: 0x0009 19:56:00 INFO - Displays: 19:56:00 INFO - Display: 19:56:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:56:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:56:00 INFO - Display Serial Number: 1600x1200 60 19:56:00 INFO - Main Display: Yes 19:56:00 INFO - Mirror: Off 19:56:00 INFO - Online: Yes 19:56:00 INFO - Rotation: Supported 19:56:00 INFO - Return code: 0 19:56:00 INFO - Running post-action listener: _resource_record_post_action 19:56:00 INFO - [mozharness: 2017-02-07 03:56:00.824310Z] Finished run-tests step (success) 19:56:00 INFO - Running post-run listener: _resource_record_post_run 19:56:00 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 217088; Write bytes: 507076608; Read time: 3; Write time: 1613 19:56:00 INFO - TinderboxPrint: CPU usage
53.6% 19:56:00 INFO - TinderboxPrint: I/O read bytes / time
217,088 / 3 19:56:00 INFO - TinderboxPrint: I/O write bytes / time
507,076,608 / 1,613 19:56:00 INFO - TinderboxPrint: CPU idle
2,223.6 (46.4%) 19:56:00 INFO - TinderboxPrint: CPU system
961.9 (20.1%) 19:56:00 INFO - TinderboxPrint: CPU user
1,608.2 (33.5%) 19:56:00 INFO - TinderboxPrint: Swap in / out
207,470,592 / 0 19:56:00 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205160960; Write bytes: 200435200; Read time: 15793; Write time: 416 19:56:00 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 217088; Write bytes: 295684608; Read time: 3; Write time: 1161 19:56:01 INFO - Running post-run listener: _upload_blobber_files 19:56:01 INFO - Blob upload gear active. 19:56:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:56:01 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 19:56:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:56:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:56:01 INFO - (blobuploader) - INFO - Open directory for files ... 19:56:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:56:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:56:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:02 INFO - (blobuploader) - INFO - Done attempting. 19:56:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:56:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:56:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:03 INFO - (blobuploader) - INFO - Done attempting. 19:56:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:56:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:56:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:04 INFO - (blobuploader) - INFO - Done attempting. 19:56:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:56:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:56:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:56:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:56:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:56:07 INFO - (blobuploader) - INFO - Done attempting. 19:56:07 INFO - (blobuploader) - INFO - Iteration through files over. 19:56:07 INFO - Return code: 0 19:56:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:56:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:56:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d0f9cb79075178b21049013c4436cd6a7039018e768b251d7672ea882c458a064b9e20288317b6bf08bf2acee6cdeec634a0d6b399be85aa045cb42239a2856", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52f25caa7303c30ff14b0be861b12f78f8a574b49df2f365d519ba531facaac4c07651bbee25a916281b845ae7b5d062e5f7955d9c0392571154575ee4655160", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9514ff95ca46d5d8a8c964d5215aa15e9d2a41b2cc8d699ef3a33dd5ea809956f6cb19450177b023b6b2b2ee40bb4c1c985400cdd4c5b1769e8506f3c996112", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e56c4b9c504c21eb4b10c999d141875addd771c85bdb84628b02720b63a1bf8013830638b78976598e2ab94b07012493fc7860c6604f3ca7dae8eacbad95cc53"} 19:56:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:56:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:56:07 INFO - Contents: 19:56:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d0f9cb79075178b21049013c4436cd6a7039018e768b251d7672ea882c458a064b9e20288317b6bf08bf2acee6cdeec634a0d6b399be85aa045cb42239a2856", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52f25caa7303c30ff14b0be861b12f78f8a574b49df2f365d519ba531facaac4c07651bbee25a916281b845ae7b5d062e5f7955d9c0392571154575ee4655160", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9514ff95ca46d5d8a8c964d5215aa15e9d2a41b2cc8d699ef3a33dd5ea809956f6cb19450177b023b6b2b2ee40bb4c1c985400cdd4c5b1769e8506f3c996112", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e56c4b9c504c21eb4b10c999d141875addd771c85bdb84628b02720b63a1bf8013830638b78976598e2ab94b07012493fc7860c6604f3ca7dae8eacbad95cc53"} 19:56:07 INFO - Running post-run listener: copy_logs_to_upload_dir 19:56:07 INFO - Copying logs to upload dir... 19:56:07 INFO - mkdir: /builds/slave/test/build/upload/logs 19:56:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.401434 ========= master_lag: 0.95 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-06 19:56:08.174773) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 19:56:08.178200) ========= 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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d0f9cb79075178b21049013c4436cd6a7039018e768b251d7672ea882c458a064b9e20288317b6bf08bf2acee6cdeec634a0d6b399be85aa045cb42239a2856", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52f25caa7303c30ff14b0be861b12f78f8a574b49df2f365d519ba531facaac4c07651bbee25a916281b845ae7b5d062e5f7955d9c0392571154575ee4655160", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9514ff95ca46d5d8a8c964d5215aa15e9d2a41b2cc8d699ef3a33dd5ea809956f6cb19450177b023b6b2b2ee40bb4c1c985400cdd4c5b1769e8506f3c996112", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e56c4b9c504c21eb4b10c999d141875addd771c85bdb84628b02720b63a1bf8013830638b78976598e2ab94b07012493fc7860c6604f3ca7dae8eacbad95cc53"} build_url:https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010972 build_url: 'https://queue.taskcluster.net/v1/task/fAFwArKzRt-HAWXidBS4kw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d0f9cb79075178b21049013c4436cd6a7039018e768b251d7672ea882c458a064b9e20288317b6bf08bf2acee6cdeec634a0d6b399be85aa045cb42239a2856", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52f25caa7303c30ff14b0be861b12f78f8a574b49df2f365d519ba531facaac4c07651bbee25a916281b845ae7b5d062e5f7955d9c0392571154575ee4655160", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9514ff95ca46d5d8a8c964d5215aa15e9d2a41b2cc8d699ef3a33dd5ea809956f6cb19450177b023b6b2b2ee40bb4c1c985400cdd4c5b1769e8506f3c996112", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e56c4b9c504c21eb4b10c999d141875addd771c85bdb84628b02720b63a1bf8013830638b78976598e2ab94b07012493fc7860c6604f3ca7dae8eacbad95cc53"}' ========= master_lag: 0.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 19:56:08.290582) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:56:08.290955) ========= 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.U7dlKoIyTJ/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.6XA9nKJrHH/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.005329 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 19:56:08.318466) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 19:56:08.318812) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 19:56:08.319176) ========= ========= Total master_lag: 1.15 =========