builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0267 starttime: 1486139570.59 results: success (0) buildid: 20170203071809 builduid: 3483b90b1356414e92d9d24c5a816980 revision: 1ac998be4f4df44740552a900ab5f35af80727e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:50.594933) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:50.595383) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:50.595658) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:50.617974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:50.618262) ========= 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.EBMLJ2cn35/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.NeGsuXU46y/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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-03 08:32:50-- 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% 8.01M=0.001s 2017-02-03 08:32:50 (8.01 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090770 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:50.731719) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:50.732021) ========= 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.EBMLJ2cn35/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.NeGsuXU46y/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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.019930 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:50.782614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-03 08:32:50.782958) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1ac998be4f4df44740552a900ab5f35af80727e6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1ac998be4f4df44740552a900ab5f35af80727e6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EBMLJ2cn35/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.NeGsuXU46y/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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-03 08:32:50,878 truncating revision to first 12 chars 2017-02-03 08:32:50,879 Setting DEBUG logging. 2017-02-03 08:32:50,879 attempt 1/10 2017-02-03 08:32:50,879 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1ac998be4f4d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-03 08:32:52,967 unpacking tar archive at: mozilla-central-1ac998be4f4d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.345969 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-03 08:32:53.154694) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:53.155039) ========= script_repo_revision: 1ac998be4f4df44740552a900ab5f35af80727e6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:53.158438) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:53.158704) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 08:32:53.176625) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-03 08:32:53.176892) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EBMLJ2cn35/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.NeGsuXU46y/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:32:53 INFO - MultiFileLogger online at 20170203 08:32:53 in /builds/slave/test 08:32:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:32:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:32:53 INFO - {'append_to_log': False, 08:32:53 INFO - 'base_work_dir': '/builds/slave/test', 08:32:53 INFO - 'blob_upload_branch': 'Firefox', 08:32:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:32:53 INFO - 'branch': 'Firefox', 08:32:53 INFO - 'buildbot_json_path': 'buildprops.json', 08:32:53 INFO - 'config_files': ('talos/mac_config.py',), 08:32:53 INFO - 'default_actions': ('clobber', 08:32:53 INFO - 'read-buildbot-config', 08:32:53 INFO - 'download-and-extract', 08:32:53 INFO - 'populate-webroot', 08:32:53 INFO - 'create-virtualenv', 08:32:53 INFO - 'install', 08:32:53 INFO - 'run-tests'), 08:32:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:32:53 INFO - 'download_minidump_stackwalk': True, 08:32:53 INFO - 'download_symbols': 'ondemand', 08:32:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:32:53 INFO - 'tooltool.py': '/tools/tooltool.py', 08:32:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:32:53 INFO - '/tools/misc-python/virtualenv.py')}, 08:32:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:32:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:32:53 INFO - 'installer_path': 'installer.exe', 08:32:53 INFO - 'log_level': 'info', 08:32:53 INFO - 'log_name': 'talos', 08:32:53 INFO - 'log_to_console': True, 08:32:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:32:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:32:53 INFO - 'opt_config_files': (), 08:32:53 INFO - 'pip_index': False, 08:32:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:32:53 INFO - 'cmd': ('bash', 08:32:53 INFO - '-c', 08:32:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:32:53 INFO - 'enabled': True, 08:32:53 INFO - 'halt_on_failure': False, 08:32:53 INFO - 'name': 'check_screen_resolution'},), 08:32:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:32:53 INFO - 'cmd': ('bash', 08:32:53 INFO - '-c', 08:32:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:32:53 INFO - 'enabled': True, 08:32:53 INFO - 'halt_on_failure': False, 08:32:53 INFO - 'name': 'check_screen_resolution'},), 08:32:53 INFO - 'run_cmd_checks_enabled': True, 08:32:53 INFO - 'sps_profile': False, 08:32:53 INFO - 'sps_profile_interval': 0, 08:32:53 INFO - 'suite': 'dromaeojs', 08:32:53 INFO - 'system_bits': '32', 08:32:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:32:53 INFO - 'title': 't-yosemite-r7-0267', 08:32:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:32:53 INFO - 'use_talos_json': True, 08:32:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:32:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:32:53 INFO - 'work_dir': 'build'} 08:32:53 INFO - [mozharness: 2017-02-03 16:32:53.387484Z] Running clobber step. 08:32:53 INFO - Running pre-action listener: _resource_record_pre_action 08:32:53 INFO - Running main action method: clobber 08:32:53 INFO - rmtree: /builds/slave/test/build 08:32:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:32:55 INFO - Running post-action listener: _resource_record_post_action 08:32:55 INFO - [mozharness: 2017-02-03 16:32:55.406029Z] Finished clobber step (success) 08:32:55 INFO - [mozharness: 2017-02-03 16:32:55.406161Z] Running read-buildbot-config step. 08:32:55 INFO - Running pre-action listener: _resource_record_pre_action 08:32:55 INFO - Running main action method: read_buildbot_config 08:32:55 INFO - Using buildbot properties: 08:32:55 INFO - { 08:32:55 INFO - "project": "", 08:32:55 INFO - "product": "firefox", 08:32:55 INFO - "script_repo_revision": "production", 08:32:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:32:55 INFO - "repository": "", 08:32:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:32:55 INFO - "buildid": "20170203071809", 08:32:55 INFO - "pgo_build": "False", 08:32:55 INFO - "basedir": "/builds/slave/test", 08:32:55 INFO - "buildnumber": 61, 08:32:55 INFO - "slavename": "t-yosemite-r7-0267", 08:32:55 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:32:55 INFO - "platform": "yosemite_r7", 08:32:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:32:55 INFO - "branch": "mozilla-central", 08:32:55 INFO - "repo_path": "mozilla-central", 08:32:55 INFO - "revision": "1ac998be4f4df44740552a900ab5f35af80727e6", 08:32:55 INFO - "stage_platform": "macosx64", 08:32:55 INFO - "builduid": "3483b90b1356414e92d9d24c5a816980", 08:32:55 INFO - "slavebuilddir": "test" 08:32:55 INFO - } 08:32:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 08:32:55 INFO - Running post-action listener: _resource_record_post_action 08:32:55 INFO - [mozharness: 2017-02-03 16:32:55.409471Z] Finished read-buildbot-config step (success) 08:32:55 INFO - [mozharness: 2017-02-03 16:32:55.409602Z] Running download-and-extract step. 08:32:55 INFO - Running pre-action listener: _resource_record_pre_action 08:32:55 INFO - Running main action method: download_and_extract 08:32:55 INFO - mkdir: /builds/slave/test/build/tests 08:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:55 INFO - trying https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 08:32:55 INFO - Downloading https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:32:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 08:32:56 INFO - Downloaded 1199 bytes. 08:32:56 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:32:56 INFO - Using the following test package requirements: 08:32:56 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:32:56 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:32:56 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 08:32:56 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:32:56 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 08:32:56 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:32:56 INFO - u'jsshell-mac.zip'], 08:32:56 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:32:56 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 08:32:56 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:32:56 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:32:56 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 08:32:56 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:32:56 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 08:32:56 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:32:56 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 08:32:56 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:32:56 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 08:32:56 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:32:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:32:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:32:56 INFO - Fetch https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:32:58 INFO - Expected file size: 34849738 08:32:58 INFO - Obtained file size: 34849738 08:33:05 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:33:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:33:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:33:05 INFO - Fetch https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:33:06 INFO - Expected file size: 34849738 08:33:06 INFO - Obtained file size: 34849738 08:33:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 08:33:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:33:13 INFO - Fetch https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 08:33:13 INFO - Expected file size: 14403454 08:33:13 INFO - Obtained file size: 14403454 08:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:14 INFO - trying https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:33:14 INFO - Downloading https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 08:33:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 08:33:17 INFO - Downloaded 70288393 bytes. 08:33:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:33:17 INFO - mkdir: /builds/slave/test/properties 08:33:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:33:17 INFO - Writing to file /builds/slave/test/properties/build_url 08:33:17 INFO - Contents: 08:33:17 INFO - build_url:https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:33:17 INFO - Running post-action listener: _resource_record_post_action 08:33:17 INFO - Running post-action listener: set_extra_try_arguments 08:33:17 INFO - [mozharness: 2017-02-03 16:33:17.743858Z] Finished download-and-extract step (success) 08:33:17 INFO - [mozharness: 2017-02-03 16:33:17.743991Z] Running populate-webroot step. 08:33:17 INFO - Running pre-action listener: _resource_record_pre_action 08:33:17 INFO - Running main action method: populate_webroot 08:33:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:33:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:33:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:33:17 INFO - '--tppagecycles', 08:33:17 INFO - '7'], 08:33:17 INFO - 'tests': ['tsvgm']}}, 08:33:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:33:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:33:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:33:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:33:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:33:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:33:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:33:17 INFO - 'talos_options': ['--disable-e10s'], 08:33:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:33:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:33:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:33:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:33:17 INFO - 'talos_options': ['--disable-e10s'], 08:33:17 INFO - 'tests': ['damp', 'tps']}, 08:33:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:33:17 INFO - 'tests': ['damp', 'tps']}, 08:33:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:33:17 INFO - 'tests': ['dromaeo_dom']}, 08:33:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:33:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:33:17 INFO - 'tests': ['basic_compositor_video']}, 08:33:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:33:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:33:17 INFO - 'tests': ['a11yr', 08:33:17 INFO - 'ts_paint', 08:33:17 INFO - 'tpaint', 08:33:17 INFO - 'sessionrestore', 08:33:17 INFO - 'sessionrestore_no_auto_restore', 08:33:17 INFO - 'tabpaint']}, 08:33:17 INFO - 'other-e10s': {'tests': ['a11yr', 08:33:17 INFO - 'ts_paint', 08:33:17 INFO - 'tpaint', 08:33:17 INFO - 'sessionrestore', 08:33:17 INFO - 'sessionrestore_no_auto_restore', 08:33:17 INFO - 'tabpaint']}, 08:33:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:33:17 INFO - 'ts_paint', 08:33:17 INFO - 'tpaint', 08:33:17 INFO - 'sessionrestore', 08:33:17 INFO - 'sessionrestore_no_auto_restore', 08:33:17 INFO - 'tabpaint']}, 08:33:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:33:17 INFO - 'ts_paint', 08:33:17 INFO - 'tpaint', 08:33:17 INFO - 'sessionrestore', 08:33:17 INFO - 'sessionrestore_no_auto_restore', 08:33:17 INFO - 'tabpaint']}, 08:33:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:33:17 INFO - 'tests': ['a11yr', 08:33:17 INFO - 'ts_paint', 08:33:17 INFO - 'tpaint', 08:33:17 INFO - 'sessionrestore', 08:33:17 INFO - 'sessionrestore_no_auto_restore', 08:33:17 INFO - 'tabpaint']}, 08:33:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:33:17 INFO - 'tests': ['a11yr', 08:33:17 INFO - 'ts_paint', 08:33:17 INFO - 'tpaint', 08:33:17 INFO - 'sessionrestore', 08:33:17 INFO - 'sessionrestore_no_auto_restore', 08:33:17 INFO - 'tabpaint']}, 08:33:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:33:17 INFO - 'tests': ['tsvgx', 08:33:17 INFO - 'tsvgr_opacity', 08:33:17 INFO - 'tart', 08:33:17 INFO - 'tscrollx', 08:33:17 INFO - 'cart', 08:33:17 INFO - 'tsvg_static']}, 08:33:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:33:17 INFO - 'tsvgr_opacity', 08:33:17 INFO - 'tart', 08:33:17 INFO - 'tscrollx', 08:33:17 INFO - 'cart', 08:33:17 INFO - 'tsvg_static']}, 08:33:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:33:17 INFO - 'talos_options': ['--disable-e10s'], 08:33:17 INFO - 'tests': ['tp5o']}, 08:33:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:33:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:33:17 INFO - 'talos_options': ['--disable-e10s', 08:33:17 INFO - '--xperf_path', 08:33:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:33:17 INFO - 'tests': ['tp5n']}, 08:33:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:33:17 INFO - 'talos_options': ['--xperf_path', 08:33:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:33:17 INFO - 'tests': ['tp5n']}}, 08:33:17 INFO - 'talos.zip': {'path': '', 08:33:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:33:17 INFO - Running post-action listener: _resource_record_post_action 08:33:17 INFO - [mozharness: 2017-02-03 16:33:17.762381Z] Finished populate-webroot step (success) 08:33:17 INFO - [mozharness: 2017-02-03 16:33:17.762522Z] Running create-virtualenv step. 08:33:17 INFO - Running pre-action listener: _resource_record_pre_action 08:33:17 INFO - Running main action method: create_virtualenv 08:33:17 INFO - Creating virtualenv /builds/slave/test/build/venv 08:33:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:33:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:33:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:33:17 INFO - Using real prefix '/tools/python27' 08:33:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:33:18 INFO - Installing distribute.............................................................................................................................................................................................done. 08:33:21 INFO - Installing pip.................done. 08:33:21 INFO - Return code: 0 08:33:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:33:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:33:21 INFO - Reading from file tmpfile_stdout 08:33:21 INFO - Output received: 08:33:21 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:33:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:33:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:21 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 0x10c551b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a0d71ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c32fd30>, '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 0x10c256db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c324be8>, '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.EBMLJ2cn35/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.NeGsuXU46y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:33:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:33:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:33:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBMLJ2cn35/Render', 08:33:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:21 INFO - 'HOME': '/Users/cltbld', 08:33:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:21 INFO - 'LOGNAME': 'cltbld', 08:33:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:21 INFO - 'MOZ_NO_REMOTE': '1', 08:33:21 INFO - 'NO_EM_RESTART': '1', 08:33:21 INFO - 'PAGER': '/bin/cat', 08:33:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:21 INFO - 'PWD': '/builds/slave/test', 08:33:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:21 INFO - 'SHELL': '/bin/bash', 08:33:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NeGsuXU46y/Listeners', 08:33:21 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 08:33:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:21 INFO - 'USER': 'cltbld', 08:33:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:21 INFO - 'XPC_FLAGS': '0x0', 08:33:21 INFO - 'XPC_SERVICE_NAME': '0', 08:33:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:33:21 INFO - Cleaning up... 08:33:21 INFO - Return code: 0 08:33:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:33:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:21 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 0x10c551b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a0d71ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c32fd30>, '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 0x10c256db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c324be8>, '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.EBMLJ2cn35/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.NeGsuXU46y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:33:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:33:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:33:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBMLJ2cn35/Render', 08:33:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:21 INFO - 'HOME': '/Users/cltbld', 08:33:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:21 INFO - 'LOGNAME': 'cltbld', 08:33:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:21 INFO - 'MOZ_NO_REMOTE': '1', 08:33:21 INFO - 'NO_EM_RESTART': '1', 08:33:21 INFO - 'PAGER': '/bin/cat', 08:33:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:21 INFO - 'PWD': '/builds/slave/test', 08:33:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:21 INFO - 'SHELL': '/bin/bash', 08:33:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NeGsuXU46y/Listeners', 08:33:21 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 08:33:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:21 INFO - 'USER': 'cltbld', 08:33:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:21 INFO - 'XPC_FLAGS': '0x0', 08:33:21 INFO - 'XPC_SERVICE_NAME': '0', 08:33:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:22 INFO - Downloading/unpacking psutil>=3.1.1 08:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:33:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:33:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:33:25 INFO - Installing collected packages: psutil 08:33:25 INFO - Running setup.py install for psutil 08:33:25 INFO - building 'psutil._psutil_osx' extension 08:33:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:33:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:33:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:33:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:33:26 INFO - building 'psutil._psutil_posix' extension 08:33:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:33:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:33:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:33:26 INFO - ^ 08:33:26 INFO - 1 warning generated. 08:33:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:33:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:33:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:33:26 INFO - Successfully installed psutil 08:33:26 INFO - Cleaning up... 08:33:26 INFO - Return code: 0 08:33:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:26 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 0x10c551b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a0d71ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c32fd30>, '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 0x10c256db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c324be8>, '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.EBMLJ2cn35/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.NeGsuXU46y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:33:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBMLJ2cn35/Render', 08:33:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:26 INFO - 'HOME': '/Users/cltbld', 08:33:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:26 INFO - 'LOGNAME': 'cltbld', 08:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:26 INFO - 'MOZ_NO_REMOTE': '1', 08:33:26 INFO - 'NO_EM_RESTART': '1', 08:33:26 INFO - 'PAGER': '/bin/cat', 08:33:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:26 INFO - 'PWD': '/builds/slave/test', 08:33:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:26 INFO - 'SHELL': '/bin/bash', 08:33:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NeGsuXU46y/Listeners', 08:33:26 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 08:33:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:26 INFO - 'USER': 'cltbld', 08:33:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:26 INFO - 'XPC_FLAGS': '0x0', 08:33:26 INFO - 'XPC_SERVICE_NAME': '0', 08:33:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:33:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:33:29 INFO - Installing collected packages: mozsystemmonitor 08:33:29 INFO - Running setup.py install for mozsystemmonitor 08:33:29 INFO - Successfully installed mozsystemmonitor 08:33:29 INFO - Cleaning up... 08:33:29 INFO - Return code: 0 08:33:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:33:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:30 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 0x10c551b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a0d71ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c32fd30>, '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 0x10c256db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c324be8>, '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.EBMLJ2cn35/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.NeGsuXU46y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:33:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:33:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBMLJ2cn35/Render', 08:33:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:30 INFO - 'HOME': '/Users/cltbld', 08:33:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:30 INFO - 'LOGNAME': 'cltbld', 08:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:30 INFO - 'MOZ_NO_REMOTE': '1', 08:33:30 INFO - 'NO_EM_RESTART': '1', 08:33:30 INFO - 'PAGER': '/bin/cat', 08:33:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:30 INFO - 'PWD': '/builds/slave/test', 08:33:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:30 INFO - 'SHELL': '/bin/bash', 08:33:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NeGsuXU46y/Listeners', 08:33:30 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 08:33:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:30 INFO - 'USER': 'cltbld', 08:33:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:30 INFO - 'XPC_FLAGS': '0x0', 08:33:30 INFO - 'XPC_SERVICE_NAME': '0', 08:33:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:30 INFO - Downloading/unpacking jsonschema==2.5.1 08:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:33:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33:34 INFO - Downloading functools32-3.2.3-2.tar.gz 08:33:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:33:34 INFO - warning: no files found matching '*.txt' 08:33:34 INFO - no previously-included directories found matching 'build' 08:33:34 INFO - no previously-included directories found matching 'dist' 08:33:34 INFO - no previously-included directories found matching '.git*' 08:33:34 INFO - Installing collected packages: jsonschema, functools32 08:33:34 INFO - Running setup.py install for functools32 08:33:34 INFO - warning: no files found matching '*.txt' 08:33:34 INFO - no previously-included directories found matching 'build' 08:33:34 INFO - no previously-included directories found matching 'dist' 08:33:34 INFO - no previously-included directories found matching '.git*' 08:33:34 INFO - Successfully installed jsonschema functools32 08:33:34 INFO - Cleaning up... 08:33:34 INFO - Return code: 0 08:33:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:33: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')]} 08:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33: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')]} 08:33:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33: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', '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 0x10c551b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a0d71ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c32fd30>, '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 0x10c256db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c324be8>, '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.EBMLJ2cn35/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.NeGsuXU46y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:33: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:33: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 functools32==3.2.3-2 08:33:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBMLJ2cn35/Render', 08:33:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:34 INFO - 'HOME': '/Users/cltbld', 08:33:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:34 INFO - 'LOGNAME': 'cltbld', 08:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:34 INFO - 'MOZ_NO_REMOTE': '1', 08:33:34 INFO - 'NO_EM_RESTART': '1', 08:33:34 INFO - 'PAGER': '/bin/cat', 08:33:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:34 INFO - 'PWD': '/builds/slave/test', 08:33:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:34 INFO - 'SHELL': '/bin/bash', 08:33:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NeGsuXU46y/Listeners', 08:33:34 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 08:33:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:34 INFO - 'USER': 'cltbld', 08:33:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:34 INFO - 'XPC_FLAGS': '0x0', 08:33:34 INFO - 'XPC_SERVICE_NAME': '0', 08:33:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:33:35 INFO - Cleaning up... 08:33:35 INFO - Return code: 0 08:33:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:35 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 0x10c551b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a0d71ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c32fd30>, '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 0x10c256db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c324be8>, '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.EBMLJ2cn35/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.NeGsuXU46y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:33:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:33:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:33:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBMLJ2cn35/Render', 08:33:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:35 INFO - 'HOME': '/Users/cltbld', 08:33:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:35 INFO - 'LOGNAME': 'cltbld', 08:33:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:35 INFO - 'MOZ_NO_REMOTE': '1', 08:33:35 INFO - 'NO_EM_RESTART': '1', 08:33:35 INFO - 'PAGER': '/bin/cat', 08:33:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:35 INFO - 'PWD': '/builds/slave/test', 08:33:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:35 INFO - 'SHELL': '/bin/bash', 08:33:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NeGsuXU46y/Listeners', 08:33:35 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 08:33:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:35 INFO - 'USER': 'cltbld', 08:33:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:35 INFO - 'XPC_FLAGS': '0x0', 08:33:35 INFO - 'XPC_SERVICE_NAME': '0', 08:33:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:35 INFO - Downloading/unpacking blobuploader==1.2.4 08:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:37 INFO - Downloading blobuploader-1.2.4.tar.gz 08:33:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:33:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:33:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:33:39 INFO - Downloading docopt-0.6.1.tar.gz 08:33:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:33:39 INFO - Installing collected packages: blobuploader, requests, docopt 08:33:39 INFO - Running setup.py install for blobuploader 08:33:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:33:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:33:39 INFO - Running setup.py install for requests 08:33:39 INFO - Running setup.py install for docopt 08:33:40 INFO - Successfully installed blobuploader requests docopt 08:33:40 INFO - Cleaning up... 08:33:40 INFO - Return code: 0 08:33:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:40 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 0x10c551b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a0d71ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c32fd30>, '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 0x10c256db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c324be8>, '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.EBMLJ2cn35/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.NeGsuXU46y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:33:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:33:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:33:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBMLJ2cn35/Render', 08:33:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:40 INFO - 'HOME': '/Users/cltbld', 08:33:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:40 INFO - 'LOGNAME': 'cltbld', 08:33:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:40 INFO - 'MOZ_NO_REMOTE': '1', 08:33:40 INFO - 'NO_EM_RESTART': '1', 08:33:40 INFO - 'PAGER': '/bin/cat', 08:33:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:40 INFO - 'PWD': '/builds/slave/test', 08:33:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:40 INFO - 'SHELL': '/bin/bash', 08:33:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NeGsuXU46y/Listeners', 08:33:40 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 08:33:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:40 INFO - 'USER': 'cltbld', 08:33:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:40 INFO - 'XPC_FLAGS': '0x0', 08:33:40 INFO - 'XPC_SERVICE_NAME': '0', 08:33:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:33:40 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-rAB8Qa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:33:40 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-5aXqrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:33:40 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-bzCi89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:33:40 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-0Y1peo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:33:40 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-SrdW0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:33:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:33:40 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-OTZZJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:33:41 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-xkU_Ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:33:41 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-PNAm3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:33:41 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-EhvgF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:33:41 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-llrrJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:33:41 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-Gq06YP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:33:41 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-oGIzkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:33:41 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-NjzIdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:33:41 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-poiFV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:33:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:41 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-5rLrWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:33:42 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-_GXu2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:33:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:33:42 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-0NmL73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:33:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:33:42 INFO - Running setup.py install for manifestparser 08:33:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:33:42 INFO - Running setup.py install for mozcrash 08:33:42 INFO - Running setup.py install for mozdebug 08:33:42 INFO - Running setup.py install for mozdevice 08:33:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:33:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:33:42 INFO - Running setup.py install for mozfile 08:33:42 INFO - Running setup.py install for mozhttpd 08:33:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:33:43 INFO - Running setup.py install for mozinfo 08:33:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:33:43 INFO - Running setup.py install for mozInstall 08:33:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:33:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:33:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:33:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:33:43 INFO - Running setup.py install for mozleak 08:33:43 INFO - Running setup.py install for mozlog 08:33:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:33:43 INFO - Running setup.py install for moznetwork 08:33:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:33:43 INFO - Running setup.py install for mozprocess 08:33:44 INFO - Running setup.py install for mozprofile 08:33:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:33:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:33:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:33:44 INFO - Running setup.py install for mozrunner 08:33:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:33:44 INFO - Running setup.py install for mozscreenshot 08:33:44 INFO - Running setup.py install for moztest 08:33:44 INFO - Running setup.py install for mozversion 08:33:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:33:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:33:44 INFO - Cleaning up... 08:33:44 INFO - Return code: 0 08:33:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:44 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 0x10c551b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a0d71ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c32fd30>, '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 0x10c256db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c324be8>, '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.EBMLJ2cn35/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.NeGsuXU46y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:33:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:33:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:33:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBMLJ2cn35/Render', 08:33:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:44 INFO - 'HOME': '/Users/cltbld', 08:33:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:44 INFO - 'LOGNAME': 'cltbld', 08:33:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:44 INFO - 'MOZ_NO_REMOTE': '1', 08:33:44 INFO - 'NO_EM_RESTART': '1', 08:33:44 INFO - 'PAGER': '/bin/cat', 08:33:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:44 INFO - 'PWD': '/builds/slave/test', 08:33:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:44 INFO - 'SHELL': '/bin/bash', 08:33:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NeGsuXU46y/Listeners', 08:33:44 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 08:33:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:44 INFO - 'USER': 'cltbld', 08:33:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:44 INFO - 'XPC_FLAGS': '0x0', 08:33:44 INFO - 'XPC_SERVICE_NAME': '0', 08:33:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:33:45 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-dmHbEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:33:45 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-gEVowu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:33:45 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-zbINnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:33:45 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-CdO2Fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:33:45 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-tioxI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:33:45 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-60nZgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:33:45 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-qLsN61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:33:45 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-zj97_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:33:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:33:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:33:45 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-S8ckR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:33:46 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-cPplhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:33:46 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-D1Gm02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:33:46 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-TJaacq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:33:46 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)) 08:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:33:46 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-4feyay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:33:46 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-ms0XeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:46 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-e9sU9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:33:46 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-l1D_PN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:33:46 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-d26uLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:33:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33: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 08:33:49 INFO - Downloading blessings-1.6.tar.gz 08:33:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:33:49 INFO - Installing collected packages: blessings 08:33:49 INFO - Running setup.py install for blessings 08:33:49 INFO - Successfully installed blessings 08:33:49 INFO - Cleaning up... 08:33:49 INFO - Return code: 0 08:33:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:33:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:33:50 INFO - Reading from file tmpfile_stdout 08:33:50 INFO - Current package versions: 08:33:50 INFO - blessings == 1.6 08:33:50 INFO - blobuploader == 1.2.4 08:33:50 INFO - docopt == 0.6.1 08:33:50 INFO - functools32 == 3.2.3-2 08:33:50 INFO - jsonschema == 2.5.1 08:33:50 INFO - manifestparser == 1.1 08:33:50 INFO - mozInstall == 1.12 08:33:50 INFO - mozcrash == 1.0 08:33:50 INFO - mozdebug == 0.1 08:33:50 INFO - mozdevice == 0.49 08:33:50 INFO - mozfile == 1.2 08:33:50 INFO - mozhttpd == 0.7 08:33:50 INFO - mozinfo == 0.9 08:33:50 INFO - mozleak == 0.1 08:33:50 INFO - mozlog == 3.4 08:33:50 INFO - moznetwork == 0.27 08:33:50 INFO - mozprocess == 0.24 08:33:50 INFO - mozprofile == 0.28 08:33:50 INFO - mozrunner == 6.13 08:33:50 INFO - mozscreenshot == 0.1 08:33:50 INFO - mozsystemmonitor == 0.3 08:33:50 INFO - moztest == 0.8 08:33:50 INFO - mozversion == 1.4 08:33:50 INFO - psutil == 3.1.1 08:33:50 INFO - requests == 1.2.3 08:33:50 INFO - wsgiref == 0.1.2 08:33:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:50 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 0x10c551b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a0d71ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c32fd30>, '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 0x10c256db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c324be8>, '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.EBMLJ2cn35/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.NeGsuXU46y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:33:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:33:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBMLJ2cn35/Render', 08:33:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:50 INFO - 'HOME': '/Users/cltbld', 08:33:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:50 INFO - 'LOGNAME': 'cltbld', 08:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:50 INFO - 'MOZ_NO_REMOTE': '1', 08:33:50 INFO - 'NO_EM_RESTART': '1', 08:33:50 INFO - 'PAGER': '/bin/cat', 08:33:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:50 INFO - 'PWD': '/builds/slave/test', 08:33:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:50 INFO - 'SHELL': '/bin/bash', 08:33:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NeGsuXU46y/Listeners', 08:33:50 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 08:33:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:50 INFO - 'USER': 'cltbld', 08:33:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:50 INFO - 'XPC_FLAGS': '0x0', 08:33:50 INFO - 'XPC_SERVICE_NAME': '0', 08:33:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:33:50 INFO - Cleaning up... 08:33:50 INFO - Return code: 0 08:33:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:33:50 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 0x10c551b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c554768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2a0d71ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c32fd30>, '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 0x10c256db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c324be8>, '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.EBMLJ2cn35/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.NeGsuXU46y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:33:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:33:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBMLJ2cn35/Render', 08:33:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:33:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:33:50 INFO - 'HOME': '/Users/cltbld', 08:33:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:33:50 INFO - 'LOGNAME': 'cltbld', 08:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:50 INFO - 'MOZ_NO_REMOTE': '1', 08:33:50 INFO - 'NO_EM_RESTART': '1', 08:33:50 INFO - 'PAGER': '/bin/cat', 08:33:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:33:50 INFO - 'PWD': '/builds/slave/test', 08:33:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:33:50 INFO - 'SHELL': '/bin/bash', 08:33:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NeGsuXU46y/Listeners', 08:33:50 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 08:33:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:33:50 INFO - 'USER': 'cltbld', 08:33:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:33:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:33:50 INFO - 'XPC_FLAGS': '0x0', 08:33:50 INFO - 'XPC_SERVICE_NAME': '0', 08:33:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:33:50 INFO - Cleaning up... 08:33:50 INFO - Return code: 0 08:33:50 INFO - Running post-action listener: _resource_record_post_action 08:33:50 INFO - Running post-action listener: _start_resource_monitoring 08:33:50 INFO - Starting resource monitoring. 08:33:50 INFO - [mozharness: 2017-02-03 16:33:50.778379Z] Finished create-virtualenv step (success) 08:33:50 INFO - [mozharness: 2017-02-03 16:33:50.779368Z] Running install step. 08:33:50 INFO - Running pre-action listener: _resource_record_pre_action 08:33:50 INFO - Running main action method: install 08:33:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:33:51 INFO - Reading from file tmpfile_stdout 08:33:51 INFO - Detecting whether we're running mozinstall >=1.0... 08:33:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:33:51 INFO - Reading from file tmpfile_stdout 08:33:51 INFO - Output received: 08:33:51 INFO - Usage: mozinstall [options] installer 08:33:51 INFO - Options: 08:33:51 INFO - -h, --help show this help message and exit 08:33:51 INFO - -d DEST, --destination=DEST 08:33:51 INFO - Directory to install application into. [default: 08:33:51 INFO - "/builds/slave/test"] 08:33:51 INFO - --app=APP Application being installed. [default: firefox] 08:33:51 INFO - mkdir: /builds/slave/test/build/application 08:33:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:34:12 INFO - Reading from file tmpfile_stdout 08:34:12 INFO - Output received: 08:34:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:34:12 INFO - Running post-action listener: _resource_record_post_action 08:34:12 INFO - [mozharness: 2017-02-03 16:34:12.860371Z] Finished install step (success) 08:34:12 INFO - [mozharness: 2017-02-03 16:34:12.860547Z] Running run-tests step. 08:34:12 INFO - Running pre-action listener: _resource_record_pre_action 08:34:12 INFO - Running main action method: run_tests 08:34:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:34:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:34:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:34:12 INFO - 2017-02-03 08:34:12.875 screenresolution[1909:18780] starting screenresolution argv=screenresolution get 08:34:12 INFO - 2017-02-03 08:34:12.877 screenresolution[1909:18780] Display 0: 1600x1200x32@60 08:34:12 INFO - 2017-02-03 08:34:12.883 screenresolution[1910:18783] starting screenresolution argv=screenresolution list 08:34:12 INFO - Available Modes on Display 0 08:34:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:34:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:34:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:34:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:34:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:34:13 INFO - Intel Iris: 08:34:13 INFO - Chipset Model: Intel Iris 08:34:13 INFO - Type: GPU 08:34:13 INFO - Bus: Built-In 08:34:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:34:13 INFO - Vendor: Intel (0x8086) 08:34:13 INFO - Device ID: 0x0a2e 08:34:13 INFO - Revision ID: 0x0009 08:34:13 INFO - Displays: 08:34:13 INFO - Display: 08:34:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:34:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:34:13 INFO - Display Serial Number: 1600x1200 60 08:34:13 INFO - Main Display: Yes 08:34:13 INFO - Mirror: Off 08:34:13 INFO - Online: Yes 08:34:13 INFO - Rotation: Supported 08:34:13 INFO - Return code: 0 08:34:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:34:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:34:13 INFO - Python 2.7.3 08:34:13 INFO - Return code: 0 08:34:13 INFO - grabbing minidump binary from tooltool 08:34:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:13 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 0x10c32fd30>, '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 0x10c256db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c324be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:34:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:34:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:34:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:34:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:34:13 INFO - Return code: 0 08:34:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:34:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:34:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:34:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:34:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:34:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0267', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:34:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0267 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:34:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EBMLJ2cn35/Render', 08:34:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:13 INFO - 'HOME': '/Users/cltbld', 08:34:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:13 INFO - 'LOGNAME': 'cltbld', 08:34:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:34:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:34:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:13 INFO - 'MOZ_NO_REMOTE': '1', 08:34:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:34:13 INFO - 'NO_EM_RESTART': '1', 08:34:13 INFO - 'PAGER': '/bin/cat', 08:34:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:13 INFO - 'PWD': '/builds/slave/test', 08:34:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:34:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:13 INFO - 'SHELL': '/bin/bash', 08:34:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NeGsuXU46y/Listeners', 08:34:13 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 08:34:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:13 INFO - 'USER': 'cltbld', 08:34:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:13 INFO - 'XPC_FLAGS': '0x0', 08:34:13 INFO - 'XPC_SERVICE_NAME': '0', 08:34:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0267', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:34:13 INFO - mozversion application_buildid: 20170203071809 08:34:13 INFO - mozversion application_changeset: 1ac998be4f4df44740552a900ab5f35af80727e6 08:34:13 INFO - mozversion application_display_name: Nightly 08:34:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:34:13 INFO - mozversion application_name: Firefox 08:34:13 INFO - mozversion application_remotingname: firefox 08:34:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:34:13 INFO - mozversion application_vendor: Mozilla 08:34:13 INFO - mozversion application_version: 54.0a1 08:34:13 INFO - mozversion platform_buildid: 20170203071809 08:34:13 INFO - mozversion platform_changeset: 1ac998be4f4df44740552a900ab5f35af80727e6 08:34:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:34:13 INFO - mozversion platform_version: 54.0a1 08:34:13 INFO - using testdate: 1486139653 08:34:13 INFO - actual date: 1486139653 08:34:13 INFO - starting webserver on 'localhost:56540' 08:34:13 INFO - SUITE-START | Running 2 tests 08:34:13 INFO - TEST-START | dromaeo_css 08:34:13 INFO - operating with platform_type : mac_ 08:34:13 INFO - Initialising browser for dromaeo_css test... 08:34:13 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpL6NL1D/profile http://localhost:56540/getInfo.html) 08:34:15 INFO - PROCESS | 1920 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 08:34:15 INFO - PROCESS | 1920 | __metrics Screen width/height:1600/1200 08:34:15 INFO - PROCESS | 1920 | colorDepth:24 08:34:15 INFO - PROCESS | 1920 | Browser inner width/height: 1024/689 08:34:15 INFO - PROCESS | 1920 | __metrics 08:34:15 INFO - PROCESS | 1920 | 1486139655768 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/Nightly.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/Nightly.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 08:34:15 INFO - PROCESS | 1920 | 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" 08:34:15 INFO - PROCESS | 1920 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:34:16 INFO - TEST-INFO | 1920: exit 0 08:34:16 INFO - Browser initialized. 08:34:16 INFO - Running cycle 1/1 for dromaeo_css test... 08:34:16 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpL6NL1D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:34:28 INFO - PROCESS | 1922 | RSS: Main: 237150208 08:34:28 INFO - PROCESS | 1922 | 08:37:32 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:56540/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56540/tests/dromaeo/cssquery-ext.html) 08:37:34 INFO - PROCESS | 1922 | RSS: Main: 410140672 08:37:34 INFO - PROCESS | 1922 | 08:39:18 INFO - PROCESS | 1922 | ************************* 08:39:18 INFO - PROCESS | 1922 | A coding exception was thrown and uncaught in a Task. 08:39:18 INFO - PROCESS | 1922 | 08:39:18 ERROR - PROCESS | 1922 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:39:18 INFO - PROCESS | 1922 | Full stack: 08:39:18 INFO - PROCESS | 1922 | ************************* 08:40:37 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:56540/tests/dromaeo/cssquery-ext.html (next: http://localhost:56540/tests/dromaeo/cssquery-jquery.html) 08:40:37 INFO - PROCESS | 1922 | RSS: Main: 373088256 08:40:37 INFO - PROCESS | 1922 | 08:43:40 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:56540/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56540/tests/dromaeo/cssquery-mootools.html) 08:43:40 INFO - PROCESS | 1922 | RSS: Main: 352407552 08:43:40 INFO - PROCESS | 1922 | 08:46:43 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:56540/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56540/tests/dromaeo/cssquery-prototype.html) 08:46:43 INFO - PROCESS | 1922 | RSS: Main: 343990272 08:46:43 INFO - PROCESS | 1922 | 08:49:46 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:56540/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56540/tests/dromaeo/cssquery-yui.html) 08:49:47 INFO - PROCESS | 1922 | RSS: Main: 341233664 08:49:47 INFO - PROCESS | 1922 | 08:52:50 INFO - PROCESS | 1922 | Cycle 1(1): loaded http://localhost:56540/tests/dromaeo/cssquery-yui.html (next: http://localhost:56540/tests/dromaeo/cssquery-dojo.html) 08:52:50 INFO - PROCESS | 1922 | RSS: Main: 507916288 08:52:50 INFO - PROCESS | 1922 | 08:52:50 INFO - PROCESS | 1922 | __start_tp_report 08:52:50 INFO - PROCESS | 1922 | _x_x_mozilla_page_load 08:52:50 INFO - PROCESS | 1922 | _x_x_mozilla_page_load_details 08:52:50 INFO - PROCESS | 1922 | |i|pagename|runs| 08:52:50 INFO - PROCESS | 1922 | |0;dojo.html;3287.605487341518;3302.7688061835674;3307.9999999999995;3338.8831390901323;3366.949495757564;5457.2359869618285;5565.499105080542;5665.461781130791;5683.119116536938;5717.342505611856;4533;5574.275344205258;5617.101263797793;5687;5688.886222275552;5032.899342013157;5054.1155297822825;5089.821856235032;5118.59051275897;5152.613553983448;5206.713630750307;5266.157414813631;5278.234655974881;5288.762005709752;5289.365276166865;5057.519535644108;5062.291279220912;5094.108531007059;5101.897962040738;5129.17933130701;5111.361079865017;5124.820631277925;5156.639035267533;5183.714895680736;5237.816676416345;5102.770375333119;5124.0776660201145;5131.050755610224;5175.120229560984;5188.766505507261;5428.158635411518;5655.208270842086;5665.3201615806365;5675.233843431167;5683.886322273573;5939.46544810969;5958.612690175125;6104.769568543321;6145.201123853915;6160.815175544741;5321.642981039849;6303.590266632654;6316.873662526724;6318.2733985591785;6326.87346253077;5758.971205143947;5773.538116950675;5779.248697669318;5780.104083867007;5782.392848750821;6172.105044768573;6230.127782110508;6236.844078897973;6275.96862015687;6307.4954003679595;5977.312609049928;6087.57386982907;6105.755769769248;6111.755529778759;6134.110553969738;6175.311672372589;6242.719077641517;6247.187865577446;6248.156498872594;6260.499160067184;5970.731317090741;6108.3280839107665;6161.969190154021;6173.228346456693;6174.950258456073;6025.758969641165;6128.142060111588;6142.170806941006;6147.385261473817;6154.5384096192965;4910.435299940481;4916.336294600254;4917.852464426073;4933.062718083553;4961.454240033593;4419.911601768011;4426.070525189682;4426.291793313118;4439.4228750262255;4442.97811503356;5507.0637991541535;5549;5553.666779993213;5600.775968961278;5679.914801277984;6248.593841400295;6282.434580887742;6299.6535190564955;6325.810225693236;6345.524085693592;5605.271314729141;5682.119271512922;5704.857378565569;5725.51333136688;5735.598508104393;5173.586113111018;5408.107662235766;5410;5413.593980451482;5421.539169170585;6154;6216.844578885473;6285.434310911857;6303.684815759286;6355.968220158869;6007.669578173242;6018.969905150621;6312.5265605078885;6320.557560970872;6333.904991425225;6222.751089956351;6239.438450539473;6271.874562508633;6301.558890877778;6318.968405157945;6145.72344244519;6151.9234134027265;6156.045812899008;6156.661383623944;6161.229846269216;5903.970480147572;5936.287645482397;5957.702114894325;6224.408681175281;6229.283632382334;5967.343592204941;6007.519398448201;6027;6097.695115244132;6125.663088529996;6046.727897244723;6062.302835173835;6068.696565171812;6083.361247069171;6083.996140636746;4336.937450324691;5655;5865.354810970704;5892.970535147297;5899.793507227227;5787.016207244695;5919.911201331897;5989.460948514655;6027.246594175728;6074.969625151846;5908.202392676933;5926.288845338587;5934.228550288608;5938.495227905676;5945.405459454193 08:52:50 INFO - PROCESS | 1922 | |1;ext.html;442.3011641606262;459.3270858192747;510.5660188839487;521.384765976148;530.9964167723002;29637;39777.20445591086;41168.79415602918;41272.17455650885;43517.56482435175;42448.36327455091;42709.35935960955;42727.3590896136;42780.35829462553;42884.356734649;9270.304727145458;9477.715668529921;9478.81042379152;9498.81002379952;9512.096350846694;8027.237412445837;8243.05204901435;11024.283421577611;11291.887081129165;11302.660920172408;11294.435278236097;11385.487653055632;11387.145964052688;11398.145139114558;11418.31490110592;11265.774684506305;11327.546898124065;11352.659420217407;11358.943205284004;11368.772624547546;7971.760847174594;8188.918110818875;10903.400312982783;11174.944125279322;11189.552417903356;40229.79885100556;40477.595224047975;40502.18995620101;40566.985825354604;40594.59405405938;3835.884923452301;3903.043754280191;3982.4424580558743;4016.136530645925;4126.381042843567;29976.8501157495;30168;30856.53715194274;30861.228469288224;30863.382732345453;3048.4969979953503;3173.2066983254185;3217.1635374802718;3254.9674503255137;3283.622383425889;437468;446941.7652911747;447203.76398118126;447819;457668;54464.727676362156;65425.34574654288;66170;66240.66879665571;66244;57255.71372143113;59686.70156649189;59736;59788.40211597915;59816.102758458655;14502.564923052325;16164.75752863708;16497.91751041237;16530.58673533171;16782.993020418813;12383.876161238271;12484.50061997528;12498.812517812241;12504.812427813407;12511.812322814983;18339.769243370913;21008;21055.789442105688;21074.157033718788;21093;8949.642014319485;8955.194032537103;8968.10318968098;9139.405938613969;9174.357794954289;857.1899554920665;863.9524826134498;889.3107841422949;892.4734406700078;903.2818908967255;29822.850885745866;29886.551701724493;29970.25074373158;30313.393732125234;30488;1032.8708911386077;1042.7549525861416;1075.1076606416655;1084.5661735305941;1173.9962332205841;14252.786208206886;15520.456784012506;15576.376944922076;15577.376904923904;15646.9217653911;518591;542345;577004;583684;585073;59372.703136484044;59971.40171589631;60050.699746500984;60140;60182;1389.451166789133;1424.508544552128;1446.067286600158;1451.6588601678598;1452.5134080083078;29236.26909327199;29285.560716588843;29287.12138635844;30078.54882176813;30098.097057088322;51822.22266666078;52191;52240.7387963073;52470;52484;424620;426116;426361;427620;491446;40862.257727474636;41252.58747412488;42054;42059.7897010513;42188.94527636834;46088;47021.294680579136;47287;47353.816154595035;47475.762621186674;37416;37669.434958475096;38114;38615.2276954465;39110.6088939107;36556;37566;38198.64490145377;38853.22293554169;38934.026649333995;36181.81909090543;36889.815550923144;37455;37652.81173594115;37781.43327850029;31354;32555;32908.1772955678;34027.65972340245;34193.487097693054;22021.559568808854;24495.877520612285;24606.630900536864;24623.13819016326;24990.75009249957 08:52:50 INFO - PROCESS | 1922 | |2;jquery.html;368.96679298863097;392.24492851261317;397.5408403294196;402.06519841368817;424.44441123283167;14232.335946559358;14916;17930.37243696469;20629.484262893435;21264;19886;20618.9690515474;20726.689099663516;21298.787012129873;21394.57210855782;5160.716160611165;5228.189630607252;5319.281896943921;5399.028174928511;5407.215953686713;3119.5788568543185;5224.425313215544;5304.283921670583;5312.362516498026;5320.441353657869;3875.147467557147;5692.5161361284345;5922.970385148068;5950.613210141349;6081.695915204245;5764.74058667361;5900.085486749561;6048.969755151218;6056.364081771395;6056.545759068087;5806.635440671439;5806.912896306559;5810.33181184165;5817.767289308423;5834.912476312858;13362.064655474122;15581.065136091871;20982.475438114016;24806.627900581505;25460;2610.3213164577155;2857.7992648233903;3030.10611869499;3050.80169788963;3120.2823350629483;9981.700548983543;10052.89947100527;10342.120919721832;10482.842757358645;14807.407703691839;1535.21703930996;1774.5829730013436;1818.6999145141035;1836.40316897009;1838.3106335124328;134999;139276;139923;140784.29607851896;145577;11294.152938529644;11729.894536912012;11734.178607497393;11755.647330580096;11932.88067119335;15711.921440392725;15747.68504629901;15772.60568485797;15774.05355678663;15774.76337854933;8149;8453.915460845436;8478.279346255636;8584.957075214708;8612.397132200811;8155.143709910492;8157.959210203911;8163.55100469468;8332.458390204618;8346.624401901929;3982.283189026003;4658.813647454132;4745.383100197021;4757.95242047582;4839.443464001614;1811.7191835265555;1921.4523860699633;1968.3701215610868;2005.455799034735;2027.7049794084612;386.4300157268007;414.8299197329081;423.11568821163917;432.8852853993694;1030.0069365777244;9389.577469013911;11468.31190128595;11676.182667213214;11917.284962902253;11953.342566158772;438.717027517256;446.3438745044779;452.58136223993336;455.3897776978864;470.1067970855402;5961.642301461926;5985.16207730918;6013.488853447506;6106.755729770848;6114.205153330127;674029;688218;695239;706659;708440;132582.93543284963;144028;151850;153101;155064.1897296427;451.56649616368;459.61622045592304;461.4600916927203;462.110437407995;464.04406921740645;10475.842862357224;11239.213255072393;18383.26466941361;20123.899380503004;20141.99290035463;53205.73397132989;53533.929321414136;53964.460355395946;54428;54644;71762;72242.63878680574;79786;80694.19305807102;81844;8134.227248411392;8252.504849709037;8326.791830204293;8645.654173832976;8685.826283474169;9453.621855125717;12845.42195601219;12986.935065324613;13327.400266988203;13644.863551364358;11019.173561982723;11053.557857685602;11488.48301826403;11570.88429115698;11600.767984640092;11070.114390848552;11224.989750922456;11440.771184576428;11453.255538389978;11745.47145378443;11462.885371146182;11714.414279285831;11733.530658773894;11796.174267801514;11902.285862848257;8084.272415482635;8121.187881211831;8412.158784121539;8550.401471897156;8551.007147498325;11150.498227579616;11226.831597525881;11483.655490335303;11490.77018459643;11577.189596728149 08:52:50 INFO - PROCESS | 1922 | |3;mootools.html;1428.9857101428988;1443.5820001497502;1451.731429685508;1456.97814532782;1457.8323492798324;8335.666573337066;8704.912950870481;8726.083761205076;8739.737807865775;8753.387262891592;8270.297024752888;8500.192481714228;8501.44740591861;8614.138586141382;8622.741317760461;2201.119552179125;2234.128689810975;2238.4963383238724;2240.5854916840353;2252.087904398721;2168.8915554222303;2193.9232126875563;2198;2198.7251593550805;2214.501737109146;2243.4054975431523;2248.8875556222206;2271.5684020036224;2284.1548627007946;2286.8399212055165;2234.54191890662;2241.1595651630637;2257.24382331919;2260.525289689159;2265.5129147233424;1944.708293755948;2233.251860626692;2299.057386471555;2304.135949019118;2307.7692230776956;8453.788655283606;8524.8721269181;8529.616167272488;8536.146385361477;8555;4950.405951285833;5110.2590124431745;5115.693058416507;5117;5118.10433174193;3065.5401689746495;3143.7956532825524;3270.8855190068234;3313.072339744875;3332.6000879894327;1195.910306726999;1207.601491507809;1215.392303848076;1253.260576260002;1255.2970336611527;10216.642417515426;10476.214283928659;10495.685129446128;11070.944645276802;11099.167562432769;4198.349255865346;4318.35224716295;4320.503142138694;4357.324614684729;4371.431713877176;8925.196732293995;8930.910690893137;8933.419327743675;8933.46399216049;8936.374453788172;4436.889077773017;4619.191641462798;4803.063402636452;5000.67495612784;5015.724135172528;4320.4383430153875;4441.467023957081;4452.109578084396;4452.443444569429;4454;3454.740894432877;3583.6595523425235;3632.4369722693023;3672.485851980725;3679.3377192105145;1118.7986162490668;1128.2779021426293;1129.6272230164027;1138.4478527913955;1139.88601139887;884.7611144990816;912.6303846942;913.8126684029758;933.4599154020773;945.9337846350827;5693.402192769782;5758.136279558016;5815.127730840408;5964.669206788985;6077.392260773887;754.3550264523863;911.7264820553808;930.8696782450462;945.9054094590485;961.7355227312405;2798.916032519028;3707.14735610811;3814.5422549293708;3849.51706149532;3860.652541271299;10030.498475076218;10253.487325633838;10313.742156445998;10324;10328;9499.28755343352;9939.850902236472;9952.900470995342;10256.897431025594;10271.640492582874;7694.153643099255;7921.841563168819;7925.603719813987;7954.721584744622;7957.005374328209;5659.26429564154;5702.45826646468;5736.254286942671;5904.911426328695;6007.12896629995;9338.859917101385;9440.716778496657;9443.622255109993;9468.195203407511;9468.905310946802;8323.916760832315;8495.787605309917;8945.552722363986;8975.955120224357;9114.13415725505;7163.462740294603;7450;7464.813379665552;7544.245575442412;7545.132309784445;7936.84126317482;8073.515589064675;8149.022117345957;8156.714514991947;8185.590720464072;7312.012878261579;7315.30504602062;7326.3040011198855;7338.449616278907;7393.966659168772;7077.079979602619;7098.219195888551;7149.249328820399;7204;7242.927570724225;6380.872382552416;6381.234251889803;6430.099786030053;6435.646039467874;6487.9026814598765;4961.7271050092595;4979.203327467588;5035.194368900959;5085.313482679939;5270.367555893317;5088.074357244525;5105.463926287686;5120.155174396183;5144;5222.817201397933 08:52:50 INFO - PROCESS | 1922 | |4;prototype.html;356.95894972078213;358.92642008388265;368.1587572397073;374.2795119395165;374.3019269063198;5325.893482130355;5397.703126328055;5397.784088636455;5448.275862068966;5469.781208751645;4332.458442694663;4727.574518293353;5086.135356989312;5337.412884582703;5499.7250137493165;3138.686131386866;3557.679808817206;3876.554196267423;4076.5515793262716;4079.1841631673637;4140.358244472111;4158.8544400945975;4163.812628431728;4167.979160104211;4168.62482376586;3112.4242015227255;3865.5747867734526;4121.7733024683785;4134.627883490485;4148.2947898857265;3037.8936737214312;3402.5066365377006;4070.310821009278;4099.995501102219;4100.425940368351;3530.6292839252023;3532.0463474861644;3535.434330507132;3538.3630946429635;3547.3596980800653;4606.585407313358;5260.237820330194;5270.341207349082;5292.629515933886;5323.387810401815;4487.730736155841;4611.792469338854;4669.159551280768;4670.229412146991;4682.719036857766;4790.544898234697;4979.701217926936;5220.564344805171;5656.688882111483;5734.971325143348;3752.5496940367057;3891.3384724596885;3919.8236079376497;3928.4107383892647;3936.2717897189114;4859.441164266119;4956.430010548761;5923.5853490255695;5970.402959703995;5994.880102397928;3323.5180898769545;3940.4680368149934;3958.5051868516434;3962.1679447316387;3989.940150897739;4002.6991227851063;4010.2380547695843;4018.2767101921936;4031.657309128698;4036.798160091984;4341.39220509129;4671.182543054952;4685.81256749733;4688.390509233778;4691.390119284471;5101.438841727407;5664.603477756517;5673.035583950738;5698.629589076697;5743.1385292206505;1159.478234794329;1270.8943219399182;1303.5046682260681;1308.613958882132;1320.320035181882;758.5638258001671;797.4935915693576;881.453498830729;893.6693423433371;895.230102112183;4386.71486353386;4438.359176290102;4721.362616046858;5075.467075956972;5088.542031217208;4902.754862256873;5007.546302311914;5011.571702064894;5257.763400646903;5608.766071464272;4495.685302028892;4598.586127248564;5048.47576211894;5084.847454576368;5186.662866913639;2381.7499162588024;2519.924402267935;2543.6391530531077;2709.5588602474745;2906.593076969226;4998.9500104999215;5240.895182096337;5267.077516706375;5739.62692424991;5749.885002300014;5437.809676661377;5520.558355331564;5748.091297917373;5785.363610002896;5793.275840519935;4780.976095119502;5695.430456954271;5766;5788.797392091341;5834.27071616048;4854.271859221004;4905.0569658204795;5158.97420512895;5257.553107985864;5484.862878428071;4882.5605695487575;5023.924641130313;5063.544281014579;5516.641418307797;5557.972210138923;5373.274607928036;5545.390007099135;5548.944510554843;5593.944060559343;5788.395222150133;3393.8303084845165;3444.207832198558;3734.3091528067393;3743.2300097926795;3750.7562008469363;5171.741412929263;5608.579356548356;5843.357230704534;5879.647221166743;5886.793962211302;5668.671448838079;5705.543556515552;5710.371859095413;5721.313442386774;5742.684152371659;5429.638107423283;5694.288213973254;5708.743106560131;5747.626404283708;5753.90675771602;5109.437961824122;5535.948169847716;5555.638883472561;5595.860103497283;5605.383407825218;4781.724518704698;5015.22264049073;5675.858103547444;5699.458551437607;5714.342850572071;4888.584470320063;4910.8772280693265;5469.453054694499;5539.338198540502;5600.171913183816 08:52:50 INFO - PROCESS | 1922 | |5;yui.html;706.7420391557083;731.6780616528727;769.2230846844689;803.5339503088209;827.8758186272061;14254.358553865075;14935.551933441988;14956.925215373907;14996.100233985955;15001.099934003954;13710.17738935662;13824.239666818321;13837.238951857644;13842.169469831817;13847;881.9073997230263;884.0717246890755;904.4256896870502;908.6637943960744;920.9401388909699;864.9697260595881;897.2507955856868;900.9234215091725;901.8602116671893;902.5848109869468;935.5462600638684;937.2876613773548;939.3471537281592;939.3518472254157;939.5537119868043;931.7158266728645;936.676846487964;937.142514599141;937.329809186435;939.1594522902033;695.6313154028373;695.7321431248955;698.266819839172;698.9545679530814;700.1702981966387;14500.854991450056;14836.184009879453;14905.105693658415;15258.542243732594;15524.379024838992;13782.310884455737;13949.651258718512;14069.788953165711;14080.366383512766;14088.225147616877;11697.3566453845;11762.17664763467;11785;11901.583444579399;11908.973705513788;3231.0791424444155;3582.5521809773777;3676.356637588438;3701.407774756053;3715;95829;97805.0219497803;98363.03273934481;102316.99999999926;103830.44254336116;9212.171738224244;10554.788904221872;10594.205434592282;10717.410542420086;11017.504212310465;20948.581028379347;21926.561468770535;21951;21985.78014219869;22043.228487002878;7324.6337683115635;7413;7459.403247740276;7572.129205141479;7690.077190737147;4138.0689344897155;4138.937915931264;4142.06803469217;4144.668426525931;4195.8741237762915;2904.4917139500503;3523.1544429337287;3550.1302180965827;3573.10672331917;3583.032581203113;616.4513582911212;620.8385819686823;631.2897989761518;639.0861068671751;643.9581427207216;654.6072356586105;670.3263220463404;681.2676372899153;697.4873468001015;723.392350425645;9790.21678265737;10032.69901902944;10115.848262276071;10211.438370889673;10283.53724082418;667.3760034367882;682.8634273145391;696.2689176364796;702.7505235641376;706.6431452116751;4560.635149188057;4889.242167464049;4890.559849613552;4893.657443978958;4904.779284932187;76933.6153319241;103044.96955030503;104196;104203.4789826046;105511.41732874014;72691;78979.60510197413;82994.58502707448;86089.56955215309;88096.55951720329;79451.60274198592;80849.78725319124;80944.19055809366;81024.59487702642;82462.58768706238;9405.50663425903;9519.904800951901;9567.521623918637;9589.760255993657;9689.757756056155;38139.13034785073;41432.3785143217;50862.49137508726;53224;53608.19587706266;64484;67258.32741672521;67460;68923;69604.6519767398;12046.98003298769;12873.098883078095;12951.481940722266;13166.40751166219;13344.655344655344;14698.706025879208;14720.852791471949;14747.631309217355;14753.778693319395;14771.852281477048;11414.48634811452;12079.27524348542;12234;12278.938605306916;14303.213323266904;11776.293422394341;11868.169228154291;11902.523899044287;12034.819477708017;12244;12160.939195303967;12342.074344424384;13190.030317916366;13539.79690304666;13901.860981390057;11298.83051754208;11340.432978350884;11428.085753139563;11439.199256052329;12055.51777928873;9862.852057219005;10097.343672661253;11289.784080886095;11333.659990200307;11375.20373573875 08:52:50 INFO - PROCESS | 1922 | __end_tp_report 08:52:50 INFO - PROCESS | 1922 | __start_cc_report 08:52:50 INFO - PROCESS | 1922 | _x_x_mozilla_cycle_collect,1968 08:52:50 INFO - PROCESS | 1922 | __end_cc_report 08:52:50 INFO - PROCESS | 1922 | __startTimestamp1486140770870__endTimestamp 08:52:50 INFO - PROCESS | 1922 | 08:52:50 INFO - PROCESS | 1922 | ------- Summary: start ------- 08:52:50 INFO - PROCESS | 1922 | Number of tests: 6 08:52:50 INFO - PROCESS | 1922 | 08:52:50 INFO - PROCESS | 1922 | [#0] dojo.html Cycles:165 Average:5674.27 Median:5903.97 stddev:649.17 (11.0%) stddev-sans-first:623.58 08:52:50 INFO - PROCESS | 1922 | Values: 3287.6 3302.8 3308.0 3338.9 3366.9 5457.2 5565.5 5665.5 5683.1 5717.3 4533.0 5574.3 5617.1 5687.0 5688.9 5032.9 5054.1 5089.8 5118.6 5152.6 5206.7 5266.2 5278.2 5288.8 5289.4 5057.5 5062.3 5094.1 5101.9 5129.2 5111.4 5124.8 5156.6 5183.7 5237.8 5102.8 5124.1 5131.1 5175.1 5188.8 5428.2 5655.2 5665.3 5675.2 5683.9 5939.5 5958.6 6104.8 6145.2 6160.8 5321.6 6303.6 6316.9 6318.3 6326.9 5759.0 5773.5 5779.2 5780.1 5782.4 6172.1 6230.1 6236.8 6276.0 6307.5 5977.3 6087.6 6105.8 6111.8 6134.1 6175.3 6242.7 6247.2 6248.2 6260.5 5970.7 6108.3 6162.0 6173.2 6175.0 6025.8 6128.1 6142.2 6147.4 6154.5 4910.4 4916.3 4917.9 4933.1 4961.5 4419.9 4426.1 4426.3 4439.4 4443.0 5507.1 5549.0 5553.7 5600.8 5679.9 6248.6 6282.4 6299.7 6325.8 6345.5 5605.3 5682.1 5704.9 5725.5 5735.6 5173.6 5408.1 5410.0 5413.6 5421.5 6154.0 6216.8 6285.4 6303.7 6356.0 6007.7 6019.0 6312.5 6320.6 6333.9 6222.8 6239.4 6271.9 6301.6 6319.0 6145.7 6151.9 6156.0 6156.7 6161.2 5904.0 5936.3 5957.7 6224.4 6229.3 5967.3 6007.5 6027.0 6097.7 6125.7 6046.7 6062.3 6068.7 6083.4 6084.0 4336.9 5655.0 5865.4 5893.0 5899.8 5787.0 5919.9 5989.5 6027.2 6075.0 5908.2 5926.3 5934.2 5938.5 5945.4 08:52:50 INFO - PROCESS | 1922 | 08:52:50 INFO - PROCESS | 1922 | [#1] ext.html Cycles:180 Average:63651.14 Median:38893.62 stddev:129143.36 (332.0%) stddev-sans-first:129418.44 08:52:50 INFO - PROCESS | 1922 | Values: 442.3 459.3 510.6 521.4 531.0 29637.0 39777.2 41168.8 41272.2 43517.6 42448.4 42709.4 42727.4 42780.4 42884.4 9270.3 9477.7 9478.8 9498.8 9512.1 8027.2 8243.1 11024.3 11291.9 11302.7 11294.4 11385.5 11387.1 11398.1 11418.3 11265.8 11327.5 11352.7 11358.9 11368.8 7971.8 8188.9 10903.4 11174.9 11189.6 40229.8 40477.6 40502.2 40567.0 40594.6 3835.9 3903.0 3982.4 4016.1 4126.4 29976.9 30168.0 30856.5 30861.2 30863.4 3048.5 3173.2 3217.2 3255.0 3283.6 437468.0 446941.8 447203.8 447819.0 457668.0 54464.7 65425.3 66170.0 66240.7 66244.0 57255.7 59686.7 59736.0 59788.4 59816.1 14502.6 16164.8 16497.9 16530.6 16783.0 12383.9 12484.5 12498.8 12504.8 12511.8 18339.8 21008.0 21055.8 21074.2 21093.0 8949.6 8955.2 8968.1 9139.4 9174.4 857.2 864.0 889.3 892.5 903.3 29822.9 29886.6 29970.3 30313.4 30488.0 1032.9 1042.8 1075.1 1084.6 1174.0 14252.8 15520.5 15576.4 15577.4 15646.9 518591.0 542345.0 577004.0 583684.0 585073.0 59372.7 59971.4 60050.7 60140.0 60182.0 1389.5 1424.5 1446.1 1451.7 1452.5 29236.3 29285.6 29287.1 30078.5 30098.1 51822.2 52191.0 52240.7 52470.0 52484.0 424620.0 426116.0 426361.0 427620.0 491446.0 40862.3 41252.6 42054.0 42059.8 42188.9 46088.0 47021.3 47287.0 47353.8 47475.8 37416.0 37669.4 38114.0 38615.2 39110.6 36556.0 37566.0 38198.6 38853.2 38934.0 36181.8 36889.8 37455.0 37652.8 37781.4 31354.0 32555.0 32908.2 34027.7 34193.5 22021.6 24495.9 24606.6 24623.1 24990.8 08:52:50 INFO - PROCESS | 1922 | 08:52:50 INFO - PROCESS | 1922 | [#2] jquery.html Cycles:180 Average:38222.89 Median:2130.79 stddev:116216.43 (5454.2%) stddev-sans-first:116507.69 08:52:50 INFO - PROCESS | 1922 | Values: 369.0 392.2 397.5 402.1 424.4 14232.3 14916.0 17930.4 20629.5 21264.0 19886.0 20619.0 20726.7 21298.8 21394.6 5160.7 5228.2 5319.3 5399.0 5407.2 3119.6 5224.4 5304.3 5312.4 5320.4 3875.1 5692.5 5923.0 5950.6 6081.7 5764.7 5900.1 6049.0 6056.4 6056.5 5806.6 5806.9 5810.3 5817.8 5834.9 13362.1 15581.1 20982.5 24806.6 25460.0 2610.3 2857.8 3030.1 3050.8 3120.3 9981.7 10052.9 10342.1 10482.8 14807.4 1535.2 1774.6 1818.7 1836.4 1838.3 134999.0 139276.0 139923.0 140784.3 145577.0 11294.2 11729.9 11734.2 11755.6 11932.9 15711.9 15747.7 15772.6 15774.1 15774.8 8149.0 8453.9 8478.3 8585.0 8612.4 8155.1 8158.0 8163.6 8332.5 8346.6 3982.3 4658.8 4745.4 4758.0 4839.4 1811.7 1921.5 1968.4 2005.5 2027.7 386.4 414.8 423.1 432.9 1030.0 9389.6 11468.3 11676.2 11917.3 11953.3 438.7 446.3 452.6 455.4 470.1 5961.6 5985.2 6013.5 6106.8 6114.2 674029.0 688218.0 695239.0 706659.0 708440.0 132582.9 144028.0 151850.0 153101.0 155064.2 451.6 459.6 461.5 462.1 464.0 10475.8 11239.2 18383.3 20123.9 20142.0 53205.7 53533.9 53964.5 54428.0 54644.0 71762.0 72242.6 79786.0 80694.2 81844.0 8134.2 8252.5 8326.8 8645.7 8685.8 9453.6 12845.4 12986.9 13327.4 13644.9 11019.2 11053.6 11488.5 11570.9 11600.8 11070.1 11225.0 11440.8 11453.3 11745.5 11462.9 11714.4 11733.5 11796.2 11902.3 8084.3 8121.2 8412.2 8550.4 8551.0 11150.5 11226.8 11483.7 11490.8 11577.2 08:52:50 INFO - PROCESS | 1922 | 08:52:50 INFO - PROCESS | 1922 | [#3] mootools.html Cycles:180 Average:5437.18 Median:5116.35 stddev:3011.33 (58.9%) stddev-sans-first:3004.71 08:52:50 INFO - PROCESS | 1922 | Values: 1429.0 1443.6 1451.7 1457.0 1457.8 8335.7 8704.9 8726.1 8739.7 8753.4 8270.3 8500.2 8501.4 8614.1 8622.7 2201.1 2234.1 2238.5 2240.6 2252.1 2168.9 2193.9 2198.0 2198.7 2214.5 2243.4 2248.9 2271.6 2284.2 2286.8 2234.5 2241.2 2257.2 2260.5 2265.5 1944.7 2233.3 2299.1 2304.1 2307.8 8453.8 8524.9 8529.6 8536.1 8555.0 4950.4 5110.3 5115.7 5117.0 5118.1 3065.5 3143.8 3270.9 3313.1 3332.6 1195.9 1207.6 1215.4 1253.3 1255.3 10216.6 10476.2 10495.7 11070.9 11099.2 4198.3 4318.4 4320.5 4357.3 4371.4 8925.2 8930.9 8933.4 8933.5 8936.4 4436.9 4619.2 4803.1 5000.7 5015.7 4320.4 4441.5 4452.1 4452.4 4454.0 3454.7 3583.7 3632.4 3672.5 3679.3 1118.8 1128.3 1129.6 1138.4 1139.9 884.8 912.6 913.8 933.5 945.9 5693.4 5758.1 5815.1 5964.7 6077.4 754.4 911.7 930.9 945.9 961.7 2798.9 3707.1 3814.5 3849.5 3860.7 10030.5 10253.5 10313.7 10324.0 10328.0 9499.3 9939.9 9952.9 10256.9 10271.6 7694.2 7921.8 7925.6 7954.7 7957.0 5659.3 5702.5 5736.3 5904.9 6007.1 9338.9 9440.7 9443.6 9468.2 9468.9 8323.9 8495.8 8945.6 8976.0 9114.1 7163.5 7450.0 7464.8 7544.2 7545.1 7936.8 8073.5 8149.0 8156.7 8185.6 7312.0 7315.3 7326.3 7338.4 7394.0 7077.1 7098.2 7149.2 7204.0 7242.9 6380.9 6381.2 6430.1 6435.6 6487.9 4961.7 4979.2 5035.2 5085.3 5270.4 5088.1 5105.5 5120.2 5144.0 5222.8 08:52:50 INFO - PROCESS | 1922 | 08:52:50 INFO - PROCESS | 1922 | [#4] prototype.html Cycles:180 Average:4478.26 Median:5003.25 stddev:1379.33 (27.6%) stddev-sans-first:1348.07 08:52:50 INFO - PROCESS | 1922 | Values: 357.0 358.9 368.2 374.3 374.3 5325.9 5397.7 5397.8 5448.3 5469.8 4332.5 4727.6 5086.1 5337.4 5499.7 3138.7 3557.7 3876.6 4076.6 4079.2 4140.4 4158.9 4163.8 4168.0 4168.6 3112.4 3865.6 4121.8 4134.6 4148.3 3037.9 3402.5 4070.3 4100.0 4100.4 3530.6 3532.0 3535.4 3538.4 3547.4 4606.6 5260.2 5270.3 5292.6 5323.4 4487.7 4611.8 4669.2 4670.2 4682.7 4790.5 4979.7 5220.6 5656.7 5735.0 3752.5 3891.3 3919.8 3928.4 3936.3 4859.4 4956.4 5923.6 5970.4 5994.9 3323.5 3940.5 3958.5 3962.2 3989.9 4002.7 4010.2 4018.3 4031.7 4036.8 4341.4 4671.2 4685.8 4688.4 4691.4 5101.4 5664.6 5673.0 5698.6 5743.1 1159.5 1270.9 1303.5 1308.6 1320.3 758.6 797.5 881.5 893.7 895.2 4386.7 4438.4 4721.4 5075.5 5088.5 4902.8 5007.5 5011.6 5257.8 5608.8 4495.7 4598.6 5048.5 5084.8 5186.7 2381.7 2519.9 2543.6 2709.6 2906.6 4999.0 5240.9 5267.1 5739.6 5749.9 5437.8 5520.6 5748.1 5785.4 5793.3 4781.0 5695.4 5766.0 5788.8 5834.3 4854.3 4905.1 5159.0 5257.6 5484.9 4882.6 5023.9 5063.5 5516.6 5558.0 5373.3 5545.4 5548.9 5593.9 5788.4 3393.8 3444.2 3734.3 3743.2 3750.8 5171.7 5608.6 5843.4 5879.6 5886.8 5668.7 5705.5 5710.4 5721.3 5742.7 5429.6 5694.3 5708.7 5747.6 5753.9 5109.4 5535.9 5555.6 5595.9 5605.4 4781.7 5015.2 5675.9 5699.5 5714.3 4888.6 4910.9 5469.5 5539.3 5600.2 08:52:50 INFO - PROCESS | 1922 | 08:52:50 INFO - PROCESS | 1922 | [#5] yui.html Cycles:180 Average:19872.19 Median:4140.50 stddev:28314.14 (683.8%) stddev-sans-first:28357.00 08:52:50 INFO - PROCESS | 1922 | Values: 706.7 731.7 769.2 803.5 827.9 14254.4 14935.6 14956.9 14996.1 15001.1 13710.2 13824.2 13837.2 13842.2 13847.0 881.9 884.1 904.4 908.7 920.9 865.0 897.3 900.9 901.9 902.6 935.5 937.3 939.3 939.4 939.6 931.7 936.7 937.1 937.3 939.2 695.6 695.7 698.3 699.0 700.2 14500.9 14836.2 14905.1 15258.5 15524.4 13782.3 13949.7 14069.8 14080.4 14088.2 11697.4 11762.2 11785.0 11901.6 11909.0 3231.1 3582.6 3676.4 3701.4 3715.0 95829.0 97805.0 98363.0 102317.0 103830.4 9212.2 10554.8 10594.2 10717.4 11017.5 20948.6 21926.6 21951.0 21985.8 22043.2 7324.6 7413.0 7459.4 7572.1 7690.1 4138.1 4138.9 4142.1 4144.7 4195.9 2904.5 3523.2 3550.1 3573.1 3583.0 616.5 620.8 631.3 639.1 644.0 654.6 670.3 681.3 697.5 723.4 9790.2 10032.7 10115.8 10211.4 10283.5 667.4 682.9 696.3 702.8 706.6 4560.6 4889.2 4890.6 4893.7 4904.8 76933.6 103045.0 104196.0 104203.5 105511.4 72691.0 78979.6 82994.6 86089.6 88096.6 79451.6 80849.8 80944.2 81024.6 82462.6 9405.5 9519.9 9567.5 9589.8 9689.8 38139.1 41432.4 50862.5 53224.0 53608.2 64484.0 67258.3 67460.0 68923.0 69604.7 12047.0 12873.1 12951.5 13166.4 13344.7 14698.7 14720.9 14747.6 14753.8 14771.9 11414.5 12079.3 12234.0 12278.9 14303.2 11776.3 11868.2 11902.5 12034.8 12244.0 12160.9 12342.1 13190.0 13539.8 13901.9 11298.8 11340.4 11428.1 11439.2 12055.5 9862.9 10097.3 11289.8 11333.7 11375.2 08:52:50 INFO - PROCESS | 1922 | -------- Summary: end -------- 08:52:50 INFO - PROCESS | 1922 | 08:52:51 INFO - TEST-INFO | 1922: exit 0 08:52:51 INFO - TEST-OK | dromaeo_css | took 1118128ms 08:52:51 INFO - TEST-START | kraken 08:52:51 INFO - operating with platform_type : mac_ 08:52:51 INFO - Initialising browser for kraken test... 08:52:51 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmp31fC9X/profile http://localhost:56540/getInfo.html) 08:52:53 INFO - PROCESS | 1926 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 08:52:53 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 08:52:53 INFO - PROCESS | 1926 | colorDepth:24 08:52:53 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 08:52:53 INFO - PROCESS | 1926 | __metrics 08:52:53 INFO - PROCESS | 1926 | 1486140773875 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/Nightly.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/Nightly.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 08:52:53 INFO - PROCESS | 1926 | 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" 08:52:53 INFO - PROCESS | 1926 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:52:54 INFO - TEST-INFO | 1926: exit 0 08:52:54 INFO - Browser initialized. 08:52:54 INFO - Running cycle 1/1 for kraken test... 08:52:54 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmp31fC9X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:53:06 INFO - PROCESS | 1927 | RSS: Main: 236703744 08:53:06 INFO - PROCESS | 1927 | 08:53:09 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56540/tests/kraken/driver.html?testName=audio-beat-detection) 08:53:10 INFO - PROCESS | 1927 | RSS: Main: 288620544 08:53:10 INFO - PROCESS | 1927 | 08:53:13 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56540/tests/kraken/driver.html?testName=audio-dft) 08:53:13 INFO - PROCESS | 1927 | RSS: Main: 290508800 08:53:13 INFO - PROCESS | 1927 | 08:53:17 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56540/tests/kraken/driver.html?testName=audio-fft) 08:53:17 INFO - PROCESS | 1927 | RSS: Main: 380801024 08:53:17 INFO - PROCESS | 1927 | 08:53:19 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56540/tests/kraken/driver.html?testName=audio-oscillator) 08:53:20 INFO - PROCESS | 1927 | RSS: Main: 285831168 08:53:20 INFO - PROCESS | 1927 | 08:53:22 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56540/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:53:23 INFO - PROCESS | 1927 | RSS: Main: 287293440 08:53:23 INFO - PROCESS | 1927 | 08:53:26 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56540/tests/kraken/driver.html?testName=imaging-darkroom) 08:53:26 INFO - PROCESS | 1927 | RSS: Main: 329945088 08:53:26 INFO - PROCESS | 1927 | 08:53:29 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56540/tests/kraken/driver.html?testName=imaging-desaturate) 08:53:30 INFO - PROCESS | 1927 | RSS: Main: 331227136 08:53:30 INFO - PROCESS | 1927 | 08:53:32 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56540/tests/kraken/driver.html?testName=json-parse-financial) 08:53:33 INFO - PROCESS | 1927 | RSS: Main: 329854976 08:53:33 INFO - PROCESS | 1927 | 08:53:35 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56540/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:53:35 INFO - PROCESS | 1927 | RSS: Main: 306487296 08:53:35 INFO - PROCESS | 1927 | 08:53:37 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56540/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:53:38 INFO - PROCESS | 1927 | RSS: Main: 309886976 08:53:38 INFO - PROCESS | 1927 | 08:53:40 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56540/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:53:41 INFO - PROCESS | 1927 | RSS: Main: 311492608 08:53:41 INFO - PROCESS | 1927 | 08:53:44 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56540/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:53:45 INFO - PROCESS | 1927 | RSS: Main: 328654848 08:53:45 INFO - PROCESS | 1927 | 08:53:48 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56540/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:53:48 INFO - PROCESS | 1927 | RSS: Main: 322465792 08:53:48 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56540/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56540/tests/kraken/driver.html?testName=ai-astar) 08:53:50 INFO - PROCESS | 1927 | RSS: Main: 367628288 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | __start_tp_report 08:53:50 INFO - PROCESS | 1927 | _x_x_mozilla_page_load 08:53:50 INFO - PROCESS | 1927 | _x_x_mozilla_page_load_details 08:53:50 INFO - PROCESS | 1927 | |i|pagename|runs| 08:53:50 INFO - PROCESS | 1927 | |0;ai-astar;114;113;113;114;112;114;112;113;113;113 08:53:50 INFO - PROCESS | 1927 | |1;audio-beat-detection;135;109;109;147;113;108;121;109;128;108 08:53:50 INFO - PROCESS | 1927 | |2;audio-dft;187;179;184;185;186;190;263;184;184;184 08:53:50 INFO - PROCESS | 1927 | |3;audio-fft;80;58;64;91;59;62;75;84;101;69 08:53:50 INFO - PROCESS | 1927 | |4;audio-oscillator;91;71;113;104;99;73;85;68;71;83 08:53:50 INFO - PROCESS | 1927 | |5;imaging-gaussian-blur;89;86;88;86;88;85;90;86;88;86 08:53:50 INFO - PROCESS | 1927 | |6;imaging-darkroom;94;94;94;95;95;95;94;95;94;94 08:53:50 INFO - PROCESS | 1927 | |7;imaging-desaturate;87;86;86;86;87;87;87;85;83;86 08:53:50 INFO - PROCESS | 1927 | |8;json-parse-financial;61;60;58;57;60;60;60;59;58;58 08:53:50 INFO - PROCESS | 1927 | |9;json-stringify-tinderbox;66;79;75;76;77;72;72;70;72;72 08:53:50 INFO - PROCESS | 1927 | |10;stanford-crypto-aes;86;87;97;110;116;85;87;90;87;87 08:53:50 INFO - PROCESS | 1927 | |11;stanford-crypto-ccm;141;142;144;151;154;147;147;153;187;186 08:53:50 INFO - PROCESS | 1927 | |12;stanford-crypto-pbkdf2;172;169;173;170;173;230;174;172;171;174 08:53:50 INFO - PROCESS | 1927 | |13;stanford-crypto-sha256-iterative;66;68;64;78;90;64;76;83;81;86 08:53:50 INFO - PROCESS | 1927 | __end_tp_report 08:53:50 INFO - PROCESS | 1927 | __start_cc_report 08:53:50 INFO - PROCESS | 1927 | _x_x_mozilla_cycle_collect,2070 08:53:50 INFO - PROCESS | 1927 | __end_cc_report 08:53:50 INFO - PROCESS | 1927 | __startTimestamp1486140830945__endTimestamp 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | ------- Summary: start ------- 08:53:50 INFO - PROCESS | 1927 | Number of tests: 14 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#0] ai-astar Cycles:10 Average:113.10 Median:113.00 stddev:0.74 (0.7%) stddev-sans-first:0.71 08:53:50 INFO - PROCESS | 1927 | Values: 114.0 113.0 113.0 114.0 112.0 114.0 112.0 113.0 113.0 113.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#1] audio-beat-detection Cycles:10 Average:118.70 Median:117.00 stddev:13.75 (11.8%) stddev-sans-first:13.26 08:53:50 INFO - PROCESS | 1927 | Values: 135.0 109.0 109.0 147.0 113.0 108.0 121.0 109.0 128.0 108.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#2] audio-dft Cycles:10 Average:192.60 Median:185.50 stddev:24.89 (13.4%) stddev-sans-first:26.32 08:53:50 INFO - PROCESS | 1927 | Values: 187.0 179.0 184.0 185.0 186.0 190.0 263.0 184.0 184.0 184.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#3] audio-fft Cycles:10 Average:74.30 Median:72.00 stddev:14.55 (20.2%) stddev-sans-first:15.28 08:53:50 INFO - PROCESS | 1927 | Values: 80.0 58.0 64.0 91.0 59.0 62.0 75.0 84.0 101.0 69.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#4] audio-oscillator Cycles:10 Average:85.80 Median:78.00 stddev:15.63 (20.0%) stddev-sans-first:16.47 08:53:50 INFO - PROCESS | 1927 | Values: 91.0 71.0 113.0 104.0 99.0 73.0 85.0 68.0 71.0 83.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.00 stddev:1.62 (1.8%) stddev-sans-first:1.58 08:53:50 INFO - PROCESS | 1927 | Values: 89.0 86.0 88.0 86.0 88.0 85.0 90.0 86.0 88.0 86.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 08:53:50 INFO - PROCESS | 1927 | Values: 94.0 94.0 94.0 95.0 95.0 95.0 94.0 95.0 94.0 94.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.50 stddev:1.25 (1.4%) stddev-sans-first:1.27 08:53:50 INFO - PROCESS | 1927 | Values: 87.0 86.0 86.0 86.0 87.0 87.0 87.0 85.0 83.0 86.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:60.00 stddev:1.29 (2.1%) stddev-sans-first:1.17 08:53:50 INFO - PROCESS | 1927 | Values: 61.0 60.0 58.0 57.0 60.0 60.0 60.0 59.0 58.0 58.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:73.50 stddev:3.75 (5.1%) stddev-sans-first:2.98 08:53:50 INFO - PROCESS | 1927 | Values: 66.0 79.0 75.0 76.0 77.0 72.0 72.0 70.0 72.0 72.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:87.00 stddev:11.05 (12.7%) stddev-sans-first:11.41 08:53:50 INFO - PROCESS | 1927 | Values: 86.0 87.0 97.0 110.0 116.0 85.0 87.0 90.0 87.0 87.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#11] stanford-crypto-ccm Cycles:10 Average:155.20 Median:152.00 stddev:17.06 (11.2%) stddev-sans-first:17.30 08:53:50 INFO - PROCESS | 1927 | Values: 141.0 142.0 144.0 151.0 154.0 147.0 147.0 153.0 187.0 186.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.80 Median:173.00 stddev:18.41 (10.6%) stddev-sans-first:19.41 08:53:50 INFO - PROCESS | 1927 | Values: 172.0 169.0 173.0 170.0 173.0 230.0 174.0 172.0 171.0 174.0 08:53:50 INFO - PROCESS | 1927 | 08:53:50 INFO - PROCESS | 1927 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.60 Median:79.50 stddev:9.57 (12.0%) stddev-sans-first:9.50 08:53:50 INFO - PROCESS | 1927 | Values: 66.0 68.0 64.0 78.0 90.0 64.0 76.0 83.0 81.0 86.0 08:53:50 INFO - PROCESS | 1927 | -------- Summary: end -------- 08:53:50 INFO - PROCESS | 1927 | 08:53:51 INFO - TEST-INFO | 1927: exit 0 08:53:52 INFO - TEST-OK | kraken | took 60448ms 08:53:52 INFO - SUITE-END | took 1178s 08:53:52 INFO - Completed test suite (00:19:38) 08:53:52 INFO - javascript benchmark 08:53:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7144.90782780553, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3287.605487341518, 3302.7688061835674, 3307.9999999999995, 3338.8831390901323, 3366.949495757564, 5457.2359869618285, 5565.499105080542, 5665.461781130791, 5683.119116536938, 5717.342505611856, 4533.0, 5574.275344205258, 5617.101263797793, 5687.0, 5688.886222275552, 5032.899342013157, 5054.1155297822825, 5089.821856235032, 5118.59051275897, 5152.613553983448, 5206.713630750307, 5266.157414813631, 5278.234655974881, 5288.762005709752, 5289.365276166865, 5057.519535644108, 5062.291279220912, 5094.108531007059, 5101.897962040738, 5129.17933130701, 5111.361079865017, 5124.820631277925, 5156.639035267533, 5183.714895680736, 5237.816676416345, 5102.770375333119, 5124.0776660201145, 5131.050755610224, 5175.120229560984, 5188.766505507261, 5428.158635411518, 5655.208270842086, 5665.3201615806365, 5675.233843431167, 5683.886322273573, 5939.46544810969, 5958.612690175125, 6104.769568543321, 6145.201123853915, 6160.815175544741, 5321.642981039849, 6303.590266632654, 6316.873662526724, 6318.2733985591785, 6326.87346253077, 5758.971205143947, 5773.538116950675, 5779.248697669318, 5780.104083867007, 5782.392848750821, 6172.105044768573, 6230.127782110508, 6236.844078897973, 6275.96862015687, 6307.4954003679595, 5977.312609049928, 6087.57386982907, 6105.755769769248, 6111.755529778759, 6134.110553969738, 6175.311672372589, 6242.719077641517, 6247.187865577446, 6248.156498872594, 6260.499160067184, 5970.731317090741, 6108.3280839107665, 6161.969190154021, 6173.228346456693, 6174.950258456073, 6025.758969641165, 6128.142060111588, 6142.170806941006, 6147.385261473817, 6154.5384096192965, 4910.435299940481, 4916.336294600254, 4917.852464426073, 4933.062718083553, 4961.454240033593, 4419.911601768011, 4426.070525189682, 4426.291793313118, 4439.4228750262255, 4442.97811503356, 5507.0637991541535, 5549.0, 5553.666779993213, 5600.775968961278, 5679.914801277984, 6248.593841400295, 6282.434580887742, 6299.6535190564955, 6325.810225693236, 6345.524085693592, 5605.271314729141, 5682.119271512922, 5704.857378565569, 5725.51333136688, 5735.598508104393, 5173.586113111018, 5408.107662235766, 5410.0, 5413.593980451482, 5421.539169170585, 6154.0, 6216.844578885473, 6285.434310911857, 6303.684815759286, 6355.968220158869, 6007.669578173242, 6018.969905150621, 6312.5265605078885, 6320.557560970872, 6333.904991425225, 6222.751089956351, 6239.438450539473, 6271.874562508633, 6301.558890877778, 6318.968405157945, 6145.72344244519, 6151.9234134027265, 6156.045812899008, 6156.661383623944, 6161.229846269216, 5903.970480147572, 5936.287645482397, 5957.702114894325, 6224.408681175281, 6229.283632382334, 5967.343592204941, 6007.519398448201, 6027.0, 6097.695115244132, 6125.663088529996, 6046.727897244723, 6062.302835173835, 6068.696565171812, 6083.361247069171, 6083.996140636746, 4336.937450324691, 5655.0, 5865.354810970704, 5892.970535147297, 5899.793507227227, 5787.016207244695, 5919.911201331897, 5989.460948514655, 6027.246594175728, 6074.969625151846, 5908.202392676933, 5926.288845338587, 5934.228550288608, 5938.495227905676, 5945.405459454193], "value": 5633.194982652638, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [442.3011641606262, 459.3270858192747, 510.5660188839487, 521.384765976148, 530.9964167723002, 29637.0, 39777.20445591086, 41168.79415602918, 41272.17455650885, 43517.56482435175, 42448.36327455091, 42709.35935960955, 42727.3590896136, 42780.35829462553, 42884.356734649, 9270.304727145458, 9477.715668529921, 9478.81042379152, 9498.81002379952, 9512.096350846694, 8027.237412445837, 8243.05204901435, 11024.283421577611, 11291.887081129165, 11302.660920172408, 11294.435278236097, 11385.487653055632, 11387.145964052688, 11398.145139114558, 11418.31490110592, 11265.774684506305, 11327.546898124065, 11352.659420217407, 11358.943205284004, 11368.772624547546, 7971.760847174594, 8188.918110818875, 10903.400312982783, 11174.944125279322, 11189.552417903356, 40229.79885100556, 40477.595224047975, 40502.18995620101, 40566.985825354604, 40594.59405405938, 3835.884923452301, 3903.043754280191, 3982.4424580558743, 4016.136530645925, 4126.381042843567, 29976.8501157495, 30168.0, 30856.53715194274, 30861.228469288224, 30863.382732345453, 3048.4969979953503, 3173.2066983254185, 3217.1635374802718, 3254.9674503255137, 3283.622383425889, 437468.0, 446941.7652911747, 447203.76398118126, 447819.0, 457668.0, 54464.727676362156, 65425.34574654288, 66170.0, 66240.66879665571, 66244.0, 57255.71372143113, 59686.70156649189, 59736.0, 59788.40211597915, 59816.102758458655, 14502.564923052325, 16164.75752863708, 16497.91751041237, 16530.58673533171, 16782.993020418813, 12383.876161238271, 12484.50061997528, 12498.812517812241, 12504.812427813407, 12511.812322814983, 18339.769243370913, 21008.0, 21055.789442105688, 21074.157033718788, 21093.0, 8949.642014319485, 8955.194032537103, 8968.10318968098, 9139.405938613969, 9174.357794954289, 857.1899554920665, 863.9524826134498, 889.3107841422949, 892.4734406700078, 903.2818908967255, 29822.850885745866, 29886.551701724493, 29970.25074373158, 30313.393732125234, 30488.0, 1032.8708911386077, 1042.7549525861416, 1075.1076606416655, 1084.5661735305941, 1173.9962332205841, 14252.786208206886, 15520.456784012506, 15576.376944922076, 15577.376904923904, 15646.9217653911, 518591.0, 542345.0, 577004.0, 583684.0, 585073.0, 59372.703136484044, 59971.40171589631, 60050.699746500984, 60140.0, 60182.0, 1389.451166789133, 1424.508544552128, 1446.067286600158, 1451.6588601678598, 1452.5134080083078, 29236.26909327199, 29285.560716588843, 29287.12138635844, 30078.54882176813, 30098.097057088322, 51822.22266666078, 52191.0, 52240.7387963073, 52470.0, 52484.0, 424620.0, 426116.0, 426361.0, 427620.0, 491446.0, 40862.257727474636, 41252.58747412488, 42054.0, 42059.7897010513, 42188.94527636834, 46088.0, 47021.294680579136, 47287.0, 47353.816154595035, 47475.762621186674, 37416.0, 37669.434958475096, 38114.0, 38615.2276954465, 39110.6088939107, 36556.0, 37566.0, 38198.64490145377, 38853.22293554169, 38934.026649333995, 36181.81909090543, 36889.815550923144, 37455.0, 37652.81173594115, 37781.43327850029, 31354.0, 32555.0, 32908.1772955678, 34027.65972340245, 34193.487097693054, 22021.559568808854, 24495.877520612285, 24606.630900536864, 24623.13819016326, 24990.75009249957], "value": 20307.67953436368, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.96679298863097, 392.24492851261317, 397.5408403294196, 402.06519841368817, 424.44441123283167, 14232.335946559358, 14916.0, 17930.37243696469, 20629.484262893435, 21264.0, 19886.0, 20618.9690515474, 20726.689099663516, 21298.787012129873, 21394.57210855782, 5160.716160611165, 5228.189630607252, 5319.281896943921, 5399.028174928511, 5407.215953686713, 3119.5788568543185, 5224.425313215544, 5304.283921670583, 5312.362516498026, 5320.441353657869, 3875.147467557147, 5692.5161361284345, 5922.970385148068, 5950.613210141349, 6081.695915204245, 5764.74058667361, 5900.085486749561, 6048.969755151218, 6056.364081771395, 6056.545759068087, 5806.635440671439, 5806.912896306559, 5810.33181184165, 5817.767289308423, 5834.912476312858, 13362.064655474122, 15581.065136091871, 20982.475438114016, 24806.627900581505, 25460.0, 2610.3213164577155, 2857.7992648233903, 3030.10611869499, 3050.80169788963, 3120.2823350629483, 9981.700548983543, 10052.89947100527, 10342.120919721832, 10482.842757358645, 14807.407703691839, 1535.21703930996, 1774.5829730013436, 1818.6999145141035, 1836.40316897009, 1838.3106335124328, 134999.0, 139276.0, 139923.0, 140784.29607851896, 145577.0, 11294.152938529644, 11729.894536912012, 11734.178607497393, 11755.647330580096, 11932.88067119335, 15711.921440392725, 15747.68504629901, 15772.60568485797, 15774.05355678663, 15774.76337854933, 8149.0, 8453.915460845436, 8478.279346255636, 8584.957075214708, 8612.397132200811, 8155.143709910492, 8157.959210203911, 8163.55100469468, 8332.458390204618, 8346.624401901929, 3982.283189026003, 4658.813647454132, 4745.383100197021, 4757.95242047582, 4839.443464001614, 1811.7191835265555, 1921.4523860699633, 1968.3701215610868, 2005.455799034735, 2027.7049794084612, 386.4300157268007, 414.8299197329081, 423.11568821163917, 432.8852853993694, 1030.0069365777244, 9389.577469013911, 11468.31190128595, 11676.182667213214, 11917.284962902253, 11953.342566158772, 438.717027517256, 446.3438745044779, 452.58136223993336, 455.3897776978864, 470.1067970855402, 5961.642301461926, 5985.16207730918, 6013.488853447506, 6106.755729770848, 6114.205153330127, 674029.0, 688218.0, 695239.0, 706659.0, 708440.0, 132582.93543284963, 144028.0, 151850.0, 153101.0, 155064.1897296427, 451.56649616368, 459.61622045592304, 461.4600916927203, 462.110437407995, 464.04406921740645, 10475.842862357224, 11239.213255072393, 18383.26466941361, 20123.899380503004, 20141.99290035463, 53205.73397132989, 53533.929321414136, 53964.460355395946, 54428.0, 54644.0, 71762.0, 72242.63878680574, 79786.0, 80694.19305807102, 81844.0, 8134.227248411392, 8252.504849709037, 8326.791830204293, 8645.654173832976, 8685.826283474169, 9453.621855125717, 12845.42195601219, 12986.935065324613, 13327.400266988203, 13644.863551364358, 11019.173561982723, 11053.557857685602, 11488.48301826403, 11570.88429115698, 11600.767984640092, 11070.114390848552, 11224.989750922456, 11440.771184576428, 11453.255538389978, 11745.47145378443, 11462.885371146182, 11714.414279285831, 11733.530658773894, 11796.174267801514, 11902.285862848257, 8084.272415482635, 8121.187881211831, 8412.158784121539, 8550.401471897156, 8551.007147498325, 11150.498227579616, 11226.831597525881, 11483.655490335303, 11490.77018459643, 11577.189596728149], "value": 8813.86325808581, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.9857101428988, 1443.5820001497502, 1451.731429685508, 1456.97814532782, 1457.8323492798324, 8335.666573337066, 8704.912950870481, 8726.083761205076, 8739.737807865775, 8753.387262891592, 8270.297024752888, 8500.192481714228, 8501.44740591861, 8614.138586141382, 8622.741317760461, 2201.119552179125, 2234.128689810975, 2238.4963383238724, 2240.5854916840353, 2252.087904398721, 2168.8915554222303, 2193.9232126875563, 2198.0, 2198.7251593550805, 2214.501737109146, 2243.4054975431523, 2248.8875556222206, 2271.5684020036224, 2284.1548627007946, 2286.8399212055165, 2234.54191890662, 2241.1595651630637, 2257.24382331919, 2260.525289689159, 2265.5129147233424, 1944.708293755948, 2233.251860626692, 2299.057386471555, 2304.135949019118, 2307.7692230776956, 8453.788655283606, 8524.8721269181, 8529.616167272488, 8536.146385361477, 8555.0, 4950.405951285833, 5110.2590124431745, 5115.693058416507, 5117.0, 5118.10433174193, 3065.5401689746495, 3143.7956532825524, 3270.8855190068234, 3313.072339744875, 3332.6000879894327, 1195.910306726999, 1207.601491507809, 1215.392303848076, 1253.260576260002, 1255.2970336611527, 10216.642417515426, 10476.214283928659, 10495.685129446128, 11070.944645276802, 11099.167562432769, 4198.349255865346, 4318.35224716295, 4320.503142138694, 4357.324614684729, 4371.431713877176, 8925.196732293995, 8930.910690893137, 8933.419327743675, 8933.46399216049, 8936.374453788172, 4436.889077773017, 4619.191641462798, 4803.063402636452, 5000.67495612784, 5015.724135172528, 4320.4383430153875, 4441.467023957081, 4452.109578084396, 4452.443444569429, 4454.0, 3454.740894432877, 3583.6595523425235, 3632.4369722693023, 3672.485851980725, 3679.3377192105145, 1118.7986162490668, 1128.2779021426293, 1129.6272230164027, 1138.4478527913955, 1139.88601139887, 884.7611144990816, 912.6303846942, 913.8126684029758, 933.4599154020773, 945.9337846350827, 5693.402192769782, 5758.136279558016, 5815.127730840408, 5964.669206788985, 6077.392260773887, 754.3550264523863, 911.7264820553808, 930.8696782450462, 945.9054094590485, 961.7355227312405, 2798.916032519028, 3707.14735610811, 3814.5422549293708, 3849.51706149532, 3860.652541271299, 10030.498475076218, 10253.487325633838, 10313.742156445998, 10324.0, 10328.0, 9499.28755343352, 9939.850902236472, 9952.900470995342, 10256.897431025594, 10271.640492582874, 7694.153643099255, 7921.841563168819, 7925.603719813987, 7954.721584744622, 7957.005374328209, 5659.26429564154, 5702.45826646468, 5736.254286942671, 5904.911426328695, 6007.12896629995, 9338.859917101385, 9440.716778496657, 9443.622255109993, 9468.195203407511, 9468.905310946802, 8323.916760832315, 8495.787605309917, 8945.552722363986, 8975.955120224357, 9114.13415725505, 7163.462740294603, 7450.0, 7464.813379665552, 7544.245575442412, 7545.132309784445, 7936.84126317482, 8073.515589064675, 8149.022117345957, 8156.714514991947, 8185.590720464072, 7312.012878261579, 7315.30504602062, 7326.3040011198855, 7338.449616278907, 7393.966659168772, 7077.079979602619, 7098.219195888551, 7149.249328820399, 7204.0, 7242.927570724225, 6380.872382552416, 6381.234251889803, 6430.099786030053, 6435.646039467874, 6487.9026814598765, 4961.7271050092595, 4979.203327467588, 5035.194368900959, 5085.313482679939, 5270.367555893317, 5088.074357244525, 5105.463926287686, 5120.155174396183, 5144.0, 5222.817201397933], "value": 4384.518296937427, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.95894972078213, 358.92642008388265, 368.1587572397073, 374.2795119395165, 374.3019269063198, 5325.893482130355, 5397.703126328055, 5397.784088636455, 5448.275862068966, 5469.781208751645, 4332.458442694663, 4727.574518293353, 5086.135356989312, 5337.412884582703, 5499.7250137493165, 3138.686131386866, 3557.679808817206, 3876.554196267423, 4076.5515793262716, 4079.1841631673637, 4140.358244472111, 4158.8544400945975, 4163.812628431728, 4167.979160104211, 4168.62482376586, 3112.4242015227255, 3865.5747867734526, 4121.7733024683785, 4134.627883490485, 4148.2947898857265, 3037.8936737214312, 3402.5066365377006, 4070.310821009278, 4099.995501102219, 4100.425940368351, 3530.6292839252023, 3532.0463474861644, 3535.434330507132, 3538.3630946429635, 3547.3596980800653, 4606.585407313358, 5260.237820330194, 5270.341207349082, 5292.629515933886, 5323.387810401815, 4487.730736155841, 4611.792469338854, 4669.159551280768, 4670.229412146991, 4682.719036857766, 4790.544898234697, 4979.701217926936, 5220.564344805171, 5656.688882111483, 5734.971325143348, 3752.5496940367057, 3891.3384724596885, 3919.8236079376497, 3928.4107383892647, 3936.2717897189114, 4859.441164266119, 4956.430010548761, 5923.5853490255695, 5970.402959703995, 5994.880102397928, 3323.5180898769545, 3940.4680368149934, 3958.5051868516434, 3962.1679447316387, 3989.940150897739, 4002.6991227851063, 4010.2380547695843, 4018.2767101921936, 4031.657309128698, 4036.798160091984, 4341.39220509129, 4671.182543054952, 4685.81256749733, 4688.390509233778, 4691.390119284471, 5101.438841727407, 5664.603477756517, 5673.035583950738, 5698.629589076697, 5743.1385292206505, 1159.478234794329, 1270.8943219399182, 1303.5046682260681, 1308.613958882132, 1320.320035181882, 758.5638258001671, 797.4935915693576, 881.453498830729, 893.6693423433371, 895.230102112183, 4386.71486353386, 4438.359176290102, 4721.362616046858, 5075.467075956972, 5088.542031217208, 4902.754862256873, 5007.546302311914, 5011.571702064894, 5257.763400646903, 5608.766071464272, 4495.685302028892, 4598.586127248564, 5048.47576211894, 5084.847454576368, 5186.662866913639, 2381.7499162588024, 2519.924402267935, 2543.6391530531077, 2709.5588602474745, 2906.593076969226, 4998.9500104999215, 5240.895182096337, 5267.077516706375, 5739.62692424991, 5749.885002300014, 5437.809676661377, 5520.558355331564, 5748.091297917373, 5785.363610002896, 5793.275840519935, 4780.976095119502, 5695.430456954271, 5766.0, 5788.797392091341, 5834.27071616048, 4854.271859221004, 4905.0569658204795, 5158.97420512895, 5257.553107985864, 5484.862878428071, 4882.5605695487575, 5023.924641130313, 5063.544281014579, 5516.641418307797, 5557.972210138923, 5373.274607928036, 5545.390007099135, 5548.944510554843, 5593.944060559343, 5788.395222150133, 3393.8303084845165, 3444.207832198558, 3734.3091528067393, 3743.2300097926795, 3750.7562008469363, 5171.741412929263, 5608.579356548356, 5843.357230704534, 5879.647221166743, 5886.793962211302, 5668.671448838079, 5705.543556515552, 5710.371859095413, 5721.313442386774, 5742.684152371659, 5429.638107423283, 5694.288213973254, 5708.743106560131, 5747.626404283708, 5753.90675771602, 5109.437961824122, 5535.948169847716, 5555.638883472561, 5595.860103497283, 5605.383407825218, 4781.724518704698, 5015.22264049073, 5675.858103547444, 5699.458551437607, 5714.342850572071, 4888.584470320063, 4910.8772280693265, 5469.453054694499, 5539.338198540502, 5600.171913183816], "value": 4051.626332482858, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.7420391557083, 731.6780616528727, 769.2230846844689, 803.5339503088209, 827.8758186272061, 14254.358553865075, 14935.551933441988, 14956.925215373907, 14996.100233985955, 15001.099934003954, 13710.17738935662, 13824.239666818321, 13837.238951857644, 13842.169469831817, 13847.0, 881.9073997230263, 884.0717246890755, 904.4256896870502, 908.6637943960744, 920.9401388909699, 864.9697260595881, 897.2507955856868, 900.9234215091725, 901.8602116671893, 902.5848109869468, 935.5462600638684, 937.2876613773548, 939.3471537281592, 939.3518472254157, 939.5537119868043, 931.7158266728645, 936.676846487964, 937.142514599141, 937.329809186435, 939.1594522902033, 695.6313154028373, 695.7321431248955, 698.266819839172, 698.9545679530814, 700.1702981966387, 14500.854991450056, 14836.184009879453, 14905.105693658415, 15258.542243732594, 15524.379024838992, 13782.310884455737, 13949.651258718512, 14069.788953165711, 14080.366383512766, 14088.225147616877, 11697.3566453845, 11762.17664763467, 11785.0, 11901.583444579399, 11908.973705513788, 3231.0791424444155, 3582.5521809773777, 3676.356637588438, 3701.407774756053, 3715.0, 95829.0, 97805.0219497803, 98363.03273934481, 102316.99999999926, 103830.44254336116, 9212.171738224244, 10554.788904221872, 10594.205434592282, 10717.410542420086, 11017.504212310465, 20948.581028379347, 21926.561468770535, 21951.0, 21985.78014219869, 22043.228487002878, 7324.6337683115635, 7413.0, 7459.403247740276, 7572.129205141479, 7690.077190737147, 4138.0689344897155, 4138.937915931264, 4142.06803469217, 4144.668426525931, 4195.8741237762915, 2904.4917139500503, 3523.1544429337287, 3550.1302180965827, 3573.10672331917, 3583.032581203113, 616.4513582911212, 620.8385819686823, 631.2897989761518, 639.0861068671751, 643.9581427207216, 654.6072356586105, 670.3263220463404, 681.2676372899153, 697.4873468001015, 723.392350425645, 9790.21678265737, 10032.69901902944, 10115.848262276071, 10211.438370889673, 10283.53724082418, 667.3760034367882, 682.8634273145391, 696.2689176364796, 702.7505235641376, 706.6431452116751, 4560.635149188057, 4889.242167464049, 4890.559849613552, 4893.657443978958, 4904.779284932187, 76933.6153319241, 103044.96955030503, 104196.0, 104203.4789826046, 105511.41732874014, 72691.0, 78979.60510197413, 82994.58502707448, 86089.56955215309, 88096.55951720329, 79451.60274198592, 80849.78725319124, 80944.19055809366, 81024.59487702642, 82462.58768706238, 9405.50663425903, 9519.904800951901, 9567.521623918637, 9589.760255993657, 9689.757756056155, 38139.13034785073, 41432.3785143217, 50862.49137508726, 53224.0, 53608.19587706266, 64484.0, 67258.32741672521, 67460.0, 68923.0, 69604.6519767398, 12046.98003298769, 12873.098883078095, 12951.481940722266, 13166.40751166219, 13344.655344655344, 14698.706025879208, 14720.852791471949, 14747.631309217355, 14753.778693319395, 14771.852281477048, 11414.48634811452, 12079.27524348542, 12234.0, 12278.938605306916, 14303.213323266904, 11776.293422394341, 11868.169228154291, 11902.523899044287, 12034.819477708017, 12244.0, 12160.939195303967, 12342.074344424384, 13190.030317916366, 13539.79690304666, 13901.860981390057, 11298.83051754208, 11340.432978350884, 11428.085753139563, 11439.199256052329, 12055.51777928873, 9862.852057219005, 10097.343672661253, 11289.784080886095, 11333.659990200307, 11375.20373573875], "value": 7426.759485681268, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1486.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 114.0, 112.0, 114.0, 112.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 109.0, 109.0, 147.0, 113.0, 108.0, 121.0, 109.0, 128.0, 108.0], "value": 118.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 179.0, 184.0, 185.0, 186.0, 190.0, 263.0, 184.0, 184.0, 184.0], "value": 192.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 64.0, 91.0, 59.0, 62.0, 75.0, 84.0, 101.0, 69.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 113.0, 104.0, 99.0, 73.0, 85.0, 68.0, 71.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 86.0, 88.0, 85.0, 90.0, 86.0, 88.0, 86.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 86.0, 87.0, 87.0, 87.0, 85.0, 83.0, 86.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 58.0, 57.0, 60.0, 60.0, 60.0, 59.0, 58.0, 58.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 79.0, 75.0, 76.0, 77.0, 72.0, 72.0, 70.0, 72.0, 72.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 97.0, 110.0, 116.0, 85.0, 87.0, 90.0, 87.0, 87.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 142.0, 144.0, 151.0, 154.0, 147.0, 147.0, 153.0, 187.0, 186.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 173.0, 170.0, 173.0, 230.0, 174.0, 172.0, 171.0, 174.0], "value": 177.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 64.0, 78.0, 90.0, 64.0, 76.0, 83.0, 81.0, 86.0], "value": 75.6, "unit": "score"}]}]} 08:53:52 INFO - Return code: 0 08:53:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:53:52 ERROR - # TBPL SUCCESS # 08:53:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:53:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:53:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:53:52 INFO - 2017-02-03 08:53:52.257 screenresolution[1931:20750] starting screenresolution argv=screenresolution get 08:53:52 INFO - 2017-02-03 08:53:52.260 screenresolution[1931:20750] Display 0: 1600x1200x32@60 08:53:52 INFO - 2017-02-03 08:53:52.266 screenresolution[1932:20753] starting screenresolution argv=screenresolution list 08:53:52 INFO - Available Modes on Display 0 08:53:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:53:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:53:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:53:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:53:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:53:52 INFO - Intel Iris: 08:53:52 INFO - Chipset Model: Intel Iris 08:53:52 INFO - Type: GPU 08:53:52 INFO - Bus: Built-In 08:53:52 INFO - VRAM (Dynamic, Max): 1536 MB 08:53:52 INFO - Vendor: Intel (0x8086) 08:53:52 INFO - Device ID: 0x0a2e 08:53:52 INFO - Revision ID: 0x0009 08:53:52 INFO - Displays: 08:53:52 INFO - Display: 08:53:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:53:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:53:52 INFO - Display Serial Number: 1600x1200 60 08:53:52 INFO - Main Display: Yes 08:53:52 INFO - Mirror: Off 08:53:52 INFO - Online: Yes 08:53:52 INFO - Rotation: Supported 08:53:52 INFO - Return code: 0 08:53:52 INFO - Running post-action listener: _resource_record_post_action 08:53:52 INFO - [mozharness: 2017-02-03 16:53:52.518186Z] Finished run-tests step (success) 08:53:52 INFO - Running post-run listener: _resource_record_post_run 08:53:52 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 118784; Write bytes: 550805504; Read time: 3; Write time: 1654 08:53:52 INFO - TinderboxPrint: CPU usage
53.7% 08:53:52 INFO - TinderboxPrint: I/O read bytes / time
118,784 / 3 08:53:52 INFO - TinderboxPrint: I/O write bytes / time
550,805,504 / 1,654 08:53:52 INFO - TinderboxPrint: CPU idle
2,224.3 (46.3%) 08:53:52 INFO - TinderboxPrint: CPU system
961.7 (20.0%) 08:53:52 INFO - TinderboxPrint: CPU user
1,619.8 (33.7%) 08:53:52 INFO - TinderboxPrint: Swap in / out
251,383,808 / 0 08:53:52 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 252774912; Write bytes: 252813312; Read time: 18267; Write time: 551 08:53:52 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 16384; Write bytes: 296452096; Read time: 1; Write time: 1092 08:53:52 INFO - Running post-run listener: _upload_blobber_files 08:53:52 INFO - Blob upload gear active. 08:53:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:53:52 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 08:53:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:53:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:53:53 INFO - (blobuploader) - INFO - Open directory for files ... 08:53:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:53:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:53:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:54 INFO - (blobuploader) - INFO - Done attempting. 08:53:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:53:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:53:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:55 INFO - (blobuploader) - INFO - Done attempting. 08:53:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:53:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:53:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:56 INFO - (blobuploader) - INFO - Done attempting. 08:53:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:53:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:53:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:53:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:53:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:53:59 INFO - (blobuploader) - INFO - Done attempting. 08:53:59 INFO - (blobuploader) - INFO - Iteration through files over. 08:53:59 INFO - Return code: 0 08:53:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:53:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:53:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/260d60baf50fac745bb1d076a32e5f979af4d44e219444dcef9af7d3aaac7015f62b274690bd72e56a19fcb64cfde3f7c379dbb990bac9b3e12b0ef961f4a973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbd46f0424bd8b9d333f322ed5d12b31ba545bf687a9ae352b4255430df9132fb20d1748fcab660acdd18c26194d8d52d1c057a684a2aba31e9b408e5719270d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c292ec4108d1d9f0bed9d038855e04e8c5e49f713c6e11205c295fcd4727b5f7a389e33adf1733564825bc5a0a45e05be18ea698208ce67158dd974a8721f9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49000af67427d9ab47c2377b9c2363caf8c603e8b5a51e39309b7493045b31173e28ae755275e31ec182987ddd897b3b26150cea4dc1899203db6193f0ee52f8"} 08:53:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:53:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:53:59 INFO - Contents: 08:53:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/260d60baf50fac745bb1d076a32e5f979af4d44e219444dcef9af7d3aaac7015f62b274690bd72e56a19fcb64cfde3f7c379dbb990bac9b3e12b0ef961f4a973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbd46f0424bd8b9d333f322ed5d12b31ba545bf687a9ae352b4255430df9132fb20d1748fcab660acdd18c26194d8d52d1c057a684a2aba31e9b408e5719270d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c292ec4108d1d9f0bed9d038855e04e8c5e49f713c6e11205c295fcd4727b5f7a389e33adf1733564825bc5a0a45e05be18ea698208ce67158dd974a8721f9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49000af67427d9ab47c2377b9c2363caf8c603e8b5a51e39309b7493045b31173e28ae755275e31ec182987ddd897b3b26150cea4dc1899203db6193f0ee52f8"} 08:53:59 INFO - Running post-run listener: copy_logs_to_upload_dir 08:53:59 INFO - Copying logs to upload dir... 08:53:59 INFO - mkdir: /builds/slave/test/build/upload/logs 08:53:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.031019 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-03 08:53:59.260858) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 08:53:59.265549) ========= 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.EBMLJ2cn35/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.NeGsuXU46y/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/260d60baf50fac745bb1d076a32e5f979af4d44e219444dcef9af7d3aaac7015f62b274690bd72e56a19fcb64cfde3f7c379dbb990bac9b3e12b0ef961f4a973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbd46f0424bd8b9d333f322ed5d12b31ba545bf687a9ae352b4255430df9132fb20d1748fcab660acdd18c26194d8d52d1c057a684a2aba31e9b408e5719270d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c292ec4108d1d9f0bed9d038855e04e8c5e49f713c6e11205c295fcd4727b5f7a389e33adf1733564825bc5a0a45e05be18ea698208ce67158dd974a8721f9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49000af67427d9ab47c2377b9c2363caf8c603e8b5a51e39309b7493045b31173e28ae755275e31ec182987ddd897b3b26150cea4dc1899203db6193f0ee52f8"} build_url:https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011350 build_url: 'https://queue.taskcluster.net/v1/task/I0ufmORDTv-aoat1lYcPUw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/260d60baf50fac745bb1d076a32e5f979af4d44e219444dcef9af7d3aaac7015f62b274690bd72e56a19fcb64cfde3f7c379dbb990bac9b3e12b0ef961f4a973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbd46f0424bd8b9d333f322ed5d12b31ba545bf687a9ae352b4255430df9132fb20d1748fcab660acdd18c26194d8d52d1c057a684a2aba31e9b408e5719270d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c292ec4108d1d9f0bed9d038855e04e8c5e49f713c6e11205c295fcd4727b5f7a389e33adf1733564825bc5a0a45e05be18ea698208ce67158dd974a8721f9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49000af67427d9ab47c2377b9c2363caf8c603e8b5a51e39309b7493045b31173e28ae755275e31ec182987ddd897b3b26150cea4dc1899203db6193f0ee52f8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 08:53:59.302447) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:53:59.302726) ========= 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.EBMLJ2cn35/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.NeGsuXU46y/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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.005282 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 08:53:59.350544) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 08:53:59.350810) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 08:53:59.351074) ========= ========= Total master_lag: 0.20 =========