builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0027 starttime: 1486410341.0 results: success (0) buildid: 20170206101454 builduid: ce72679dd94a49a78e279689de5f532b revision: 05fae22830c0365b8173be4a2584ff0375b547a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:41.003821) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:41.004262) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:41.004531) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:41.041458) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:41.041776) ========= 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.1fwzRUltnc/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.LXSlnzDlO3/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-06 11:45:41-- 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% 157M=0s 2017-02-06 11:45:41 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123101 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:41.189075) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:41.189387) ========= 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.1fwzRUltnc/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.LXSlnzDlO3/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.028592 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:41.239056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-06 11:45:41.239395) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 05fae22830c0365b8173be4a2584ff0375b547a1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 05fae22830c0365b8173be4a2584ff0375b547a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1fwzRUltnc/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.LXSlnzDlO3/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-06 11:45:41,341 truncating revision to first 12 chars 2017-02-06 11:45:41,341 Setting DEBUG logging. 2017-02-06 11:45:41,341 attempt 1/10 2017-02-06 11:45:41,341 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/05fae22830c0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-06 11:45:43,237 unpacking tar archive at: mozilla-aurora-05fae22830c0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.163715 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-06 11:45:43.426840) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:43.427225) ========= script_repo_revision: 05fae22830c0365b8173be4a2584ff0375b547a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:43.427665) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:43.427972) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 11:45:43.448117) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-02-06 11:45:43.448584) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1fwzRUltnc/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.LXSlnzDlO3/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 11:45:43 INFO - MultiFileLogger online at 20170206 11:45:43 in /builds/slave/test 11:45:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 11:45:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:45:43 INFO - {'append_to_log': False, 11:45:43 INFO - 'base_work_dir': '/builds/slave/test', 11:45:43 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:45:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:45:43 INFO - 'branch': 'Mozilla-Aurora', 11:45:43 INFO - 'buildbot_json_path': 'buildprops.json', 11:45:43 INFO - 'config_files': ('talos/mac_config.py',), 11:45:43 INFO - 'default_actions': ('clobber', 11:45:43 INFO - 'read-buildbot-config', 11:45:43 INFO - 'download-and-extract', 11:45:43 INFO - 'populate-webroot', 11:45:43 INFO - 'create-virtualenv', 11:45:43 INFO - 'install', 11:45:43 INFO - 'run-tests'), 11:45:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:45:43 INFO - 'download_minidump_stackwalk': True, 11:45:43 INFO - 'download_symbols': 'ondemand', 11:45:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:45:43 INFO - 'tooltool.py': '/tools/tooltool.py', 11:45:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:45:43 INFO - '/tools/misc-python/virtualenv.py')}, 11:45:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:45:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:45:43 INFO - 'installer_path': 'installer.exe', 11:45:43 INFO - 'log_level': 'info', 11:45:43 INFO - 'log_name': 'talos', 11:45:43 INFO - 'log_to_console': True, 11:45:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:45:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:45:43 INFO - 'opt_config_files': (), 11:45:43 INFO - 'pip_index': False, 11:45:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:45:43 INFO - 'cmd': ('bash', 11:45:43 INFO - '-c', 11:45:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:45:43 INFO - 'enabled': True, 11:45:43 INFO - 'halt_on_failure': False, 11:45:43 INFO - 'name': 'check_screen_resolution'},), 11:45:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:45:43 INFO - 'cmd': ('bash', 11:45:43 INFO - '-c', 11:45:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:45:43 INFO - 'enabled': True, 11:45:43 INFO - 'halt_on_failure': False, 11:45:43 INFO - 'name': 'check_screen_resolution'},), 11:45:43 INFO - 'run_cmd_checks_enabled': True, 11:45:43 INFO - 'sps_profile': False, 11:45:43 INFO - 'sps_profile_interval': 0, 11:45:43 INFO - 'suite': 'dromaeojs', 11:45:43 INFO - 'system_bits': '32', 11:45:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:45:43 INFO - 'title': 't-yosemite-r7-0027', 11:45:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:45:43 INFO - 'use_talos_json': True, 11:45:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:45:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:45:43 INFO - 'work_dir': 'build'} 11:45:43 INFO - [mozharness: 2017-02-06 19:45:43.662274Z] Running clobber step. 11:45:43 INFO - Running pre-action listener: _resource_record_pre_action 11:45:43 INFO - Running main action method: clobber 11:45:43 INFO - rmtree: /builds/slave/test/build 11:45:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:45:46 INFO - Running post-action listener: _resource_record_post_action 11:45:46 INFO - [mozharness: 2017-02-06 19:45:46.219940Z] Finished clobber step (success) 11:45:46 INFO - [mozharness: 2017-02-06 19:45:46.220203Z] Running read-buildbot-config step. 11:45:46 INFO - Running pre-action listener: _resource_record_pre_action 11:45:46 INFO - Running main action method: read_buildbot_config 11:45:46 INFO - Using buildbot properties: 11:45:46 INFO - { 11:45:46 INFO - "project": "", 11:45:46 INFO - "product": "firefox", 11:45:46 INFO - "script_repo_revision": "production", 11:45:46 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 11:45:46 INFO - "repository": "", 11:45:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 11:45:46 INFO - "buildid": "20170206101454", 11:45:46 INFO - "pgo_build": "False", 11:45:46 INFO - "basedir": "/builds/slave/test", 11:45:46 INFO - "buildnumber": 7, 11:45:46 INFO - "slavename": "t-yosemite-r7-0027", 11:45:46 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:45:46 INFO - "platform": "yosemite_r7", 11:45:46 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:45:46 INFO - "branch": "mozilla-aurora", 11:45:46 INFO - "repo_path": "releases/mozilla-aurora", 11:45:46 INFO - "revision": "05fae22830c0365b8173be4a2584ff0375b547a1", 11:45:46 INFO - "stage_platform": "macosx64", 11:45:46 INFO - "builduid": "ce72679dd94a49a78e279689de5f532b", 11:45:46 INFO - "slavebuilddir": "test" 11:45:46 INFO - } 11:45:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 11:45:46 INFO - Running post-action listener: _resource_record_post_action 11:45:46 INFO - [mozharness: 2017-02-06 19:45:46.226142Z] Finished read-buildbot-config step (success) 11:45:46 INFO - [mozharness: 2017-02-06 19:45:46.226369Z] Running download-and-extract step. 11:45:46 INFO - Running pre-action listener: _resource_record_pre_action 11:45:46 INFO - Running main action method: download_and_extract 11:45:46 INFO - mkdir: /builds/slave/test/build/tests 11:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:45:46 INFO - trying https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 11:45:46 INFO - Downloading https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 11:45:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 11:45:46 INFO - Downloaded 1199 bytes. 11:45:46 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 11:45:46 INFO - Using the following test package requirements: 11:45:46 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 11:45:46 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:45:46 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 11:45:46 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:45:46 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 11:45:46 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:45:46 INFO - u'jsshell-mac.zip'], 11:45:46 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:45:46 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 11:45:46 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 11:45:46 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:45:46 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 11:45:46 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:45:46 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 11:45:46 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:45:46 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 11:45:46 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 11:45:46 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 11:45:46 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:45:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 11:45:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:45:46 INFO - Fetch https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 11:45:47 INFO - Expected file size: 16356103 11:45:47 INFO - Obtained file size: 16356103 11:45:54 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 11:45:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 11:45:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:45:54 INFO - Fetch https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 11:45:55 INFO - Expected file size: 16356103 11:45:55 INFO - Obtained file size: 16356103 11:46:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 11:46:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:46:01 INFO - Fetch https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 11:46:03 INFO - Expected file size: 14403485 11:46:03 INFO - Obtained file size: 14403485 11:46:03 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')]} 11:46:03 INFO - trying https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 11:46:03 INFO - Downloading https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 11:46:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 11:46:05 INFO - Downloaded 73831103 bytes. 11:46:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 11:46:05 INFO - mkdir: /builds/slave/test/properties 11:46:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:46:05 INFO - Writing to file /builds/slave/test/properties/build_url 11:46:05 INFO - Contents: 11:46:05 INFO - build_url:https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 11:46:06 INFO - Running post-action listener: _resource_record_post_action 11:46:06 INFO - Running post-action listener: set_extra_try_arguments 11:46:06 INFO - [mozharness: 2017-02-06 19:46:06.545438Z] Finished download-and-extract step (success) 11:46:06 INFO - [mozharness: 2017-02-06 19:46:06.545586Z] Running populate-webroot step. 11:46:06 INFO - Running pre-action listener: _resource_record_pre_action 11:46:06 INFO - Running main action method: populate_webroot 11:46:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:46:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:46:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:46:06 INFO - '--tppagecycles', 11:46:06 INFO - '7'], 11:46:06 INFO - 'tests': ['tsvgm']}}, 11:46:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:46:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:46:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:46:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:46:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:46:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:46:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:46:06 INFO - 'talos_options': ['--disable-e10s'], 11:46:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:46:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:46:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:46:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:46:06 INFO - 'talos_options': ['--disable-e10s'], 11:46:06 INFO - 'tests': ['damp', 'tps']}, 11:46:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:46:06 INFO - 'tests': ['damp', 'tps']}, 11:46:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:46:06 INFO - 'tests': ['dromaeo_dom']}, 11:46:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:46:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:46:06 INFO - 'tests': ['basic_compositor_video']}, 11:46:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:46:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:46:06 INFO - 'tests': ['a11yr', 11:46:06 INFO - 'ts_paint', 11:46:06 INFO - 'tpaint', 11:46:06 INFO - 'sessionrestore', 11:46:06 INFO - 'sessionrestore_no_auto_restore', 11:46:06 INFO - 'tabpaint']}, 11:46:06 INFO - 'other-e10s': {'tests': ['a11yr', 11:46:06 INFO - 'ts_paint', 11:46:06 INFO - 'tpaint', 11:46:06 INFO - 'sessionrestore', 11:46:06 INFO - 'sessionrestore_no_auto_restore', 11:46:06 INFO - 'tabpaint']}, 11:46:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:46:06 INFO - 'ts_paint', 11:46:06 INFO - 'tpaint', 11:46:06 INFO - 'sessionrestore', 11:46:06 INFO - 'sessionrestore_no_auto_restore', 11:46:06 INFO - 'tabpaint']}, 11:46:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:46:06 INFO - 'ts_paint', 11:46:06 INFO - 'tpaint', 11:46:06 INFO - 'sessionrestore', 11:46:06 INFO - 'sessionrestore_no_auto_restore', 11:46:06 INFO - 'tabpaint']}, 11:46:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:46:06 INFO - 'tests': ['a11yr', 11:46:06 INFO - 'ts_paint', 11:46:06 INFO - 'tpaint', 11:46:06 INFO - 'sessionrestore', 11:46:06 INFO - 'sessionrestore_no_auto_restore', 11:46:06 INFO - 'tabpaint']}, 11:46:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:46:06 INFO - 'tests': ['a11yr', 11:46:06 INFO - 'ts_paint', 11:46:06 INFO - 'tpaint', 11:46:06 INFO - 'sessionrestore', 11:46:06 INFO - 'sessionrestore_no_auto_restore', 11:46:06 INFO - 'tabpaint']}, 11:46:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:46:06 INFO - 'tests': ['tsvgx', 11:46:06 INFO - 'tsvgr_opacity', 11:46:06 INFO - 'tart', 11:46:06 INFO - 'tscrollx', 11:46:06 INFO - 'cart', 11:46:06 INFO - 'tsvg_static']}, 11:46:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:46:06 INFO - 'tsvgr_opacity', 11:46:06 INFO - 'tart', 11:46:06 INFO - 'tscrollx', 11:46:06 INFO - 'cart', 11:46:06 INFO - 'tsvg_static']}, 11:46:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:46:06 INFO - 'talos_options': ['--disable-e10s'], 11:46:06 INFO - 'tests': ['tp5o']}, 11:46:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:46:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:46:06 INFO - 'talos_options': ['--disable-e10s', 11:46:06 INFO - '--xperf_path', 11:46:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:46:06 INFO - 'tests': ['tp5n']}, 11:46:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:46:06 INFO - 'talos_options': ['--xperf_path', 11:46:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:46:06 INFO - 'tests': ['tp5n']}}, 11:46:06 INFO - 'talos.zip': {'path': '', 11:46:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:46:06 INFO - Running post-action listener: _resource_record_post_action 11:46:06 INFO - [mozharness: 2017-02-06 19:46:06.564274Z] Finished populate-webroot step (success) 11:46:06 INFO - [mozharness: 2017-02-06 19:46:06.564414Z] Running create-virtualenv step. 11:46:06 INFO - Running pre-action listener: _resource_record_pre_action 11:46:06 INFO - Running main action method: create_virtualenv 11:46:06 INFO - Creating virtualenv /builds/slave/test/build/venv 11:46:06 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 11:46:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:46:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:46:06 INFO - Using real prefix '/tools/python27' 11:46:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:46:07 INFO - Installing distribute.............................................................................................................................................................................................done. 11:46:10 INFO - Installing pip.................done. 11:46:10 INFO - Return code: 0 11:46:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:46:10 INFO - Reading from file tmpfile_stdout 11:46:10 INFO - Output received: 11:46:10 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) 11:46:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:10 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 0x10a4a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae834a4a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283d30>, '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 0x10a1aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a278be8>, '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.1fwzRUltnc/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.LXSlnzDlO3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 11:46:10 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 11:46:10 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 11:46:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1fwzRUltnc/Render', 11:46:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:10 INFO - 'HOME': '/Users/cltbld', 11:46:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:10 INFO - 'LOGNAME': 'cltbld', 11:46:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:10 INFO - 'MOZ_NO_REMOTE': '1', 11:46:10 INFO - 'NO_EM_RESTART': '1', 11:46:10 INFO - 'PAGER': '/bin/cat', 11:46:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:10 INFO - 'PWD': '/builds/slave/test', 11:46:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:10 INFO - 'SHELL': '/bin/bash', 11:46:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LXSlnzDlO3/Listeners', 11:46:10 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 11:46:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:10 INFO - 'USER': 'cltbld', 11:46:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:10 INFO - 'XPC_FLAGS': '0x0', 11:46:10 INFO - 'XPC_SERVICE_NAME': '0', 11:46:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:10 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 11:46:10 INFO - Cleaning up... 11:46:10 INFO - Return code: 0 11:46:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:10 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 0x10a4a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae834a4a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283d30>, '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 0x10a1aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a278be8>, '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.1fwzRUltnc/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.LXSlnzDlO3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 11:46:10 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 11:46:10 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 11:46:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1fwzRUltnc/Render', 11:46:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:10 INFO - 'HOME': '/Users/cltbld', 11:46:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:10 INFO - 'LOGNAME': 'cltbld', 11:46:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:10 INFO - 'MOZ_NO_REMOTE': '1', 11:46:10 INFO - 'NO_EM_RESTART': '1', 11:46:10 INFO - 'PAGER': '/bin/cat', 11:46:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:10 INFO - 'PWD': '/builds/slave/test', 11:46:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:10 INFO - 'SHELL': '/bin/bash', 11:46:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LXSlnzDlO3/Listeners', 11:46:10 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 11:46:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:10 INFO - 'USER': 'cltbld', 11:46:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:10 INFO - 'XPC_FLAGS': '0x0', 11:46:10 INFO - 'XPC_SERVICE_NAME': '0', 11:46:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:11 INFO - Downloading/unpacking psutil>=3.1.1 11:46:11 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 11:46:11 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 11:46:11 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 11:46:11 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 11:46:11 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 11:46:11 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 11:46:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:46:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:46:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:46:14 INFO - Installing collected packages: psutil 11:46:14 INFO - Running setup.py install for psutil 11:46:14 INFO - building 'psutil._psutil_osx' extension 11:46:14 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 11:46:15 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 11:46:15 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 11:46:15 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 11:46:15 INFO - building 'psutil._psutil_posix' extension 11:46:15 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 11:46:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:46:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:46:15 INFO - ^ 11:46:15 INFO - 1 warning generated. 11:46:15 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 11:46:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:46:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:46:15 INFO - Successfully installed psutil 11:46:15 INFO - Cleaning up... 11:46:15 INFO - Return code: 0 11:46:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:46:15 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')]} 11:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:15 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')]} 11:46:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:15 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 0x10a4a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae834a4a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283d30>, '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 0x10a1aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a278be8>, '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.1fwzRUltnc/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.LXSlnzDlO3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 11:46:15 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 11:46:15 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 11:46:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1fwzRUltnc/Render', 11:46:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:15 INFO - 'HOME': '/Users/cltbld', 11:46:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:15 INFO - 'LOGNAME': 'cltbld', 11:46:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:15 INFO - 'MOZ_NO_REMOTE': '1', 11:46:15 INFO - 'NO_EM_RESTART': '1', 11:46:15 INFO - 'PAGER': '/bin/cat', 11:46:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:15 INFO - 'PWD': '/builds/slave/test', 11:46:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:15 INFO - 'SHELL': '/bin/bash', 11:46:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LXSlnzDlO3/Listeners', 11:46:15 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 11:46:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:15 INFO - 'USER': 'cltbld', 11:46:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:15 INFO - 'XPC_FLAGS': '0x0', 11:46:15 INFO - 'XPC_SERVICE_NAME': '0', 11:46:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:46:16 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 11:46:16 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 11:46:16 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 11:46:16 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 11:46:16 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 11:46:16 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 11:46:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:46:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:46:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:46:18 INFO - Installing collected packages: mozsystemmonitor 11:46:18 INFO - Running setup.py install for mozsystemmonitor 11:46:18 INFO - Successfully installed mozsystemmonitor 11:46:18 INFO - Cleaning up... 11:46:19 INFO - Return code: 0 11:46:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:46:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:19 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 0x10a4a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae834a4a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283d30>, '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 0x10a1aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a278be8>, '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.1fwzRUltnc/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.LXSlnzDlO3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 11:46:19 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 11:46:19 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 11:46:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1fwzRUltnc/Render', 11:46:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:19 INFO - 'HOME': '/Users/cltbld', 11:46:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:19 INFO - 'LOGNAME': 'cltbld', 11:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:19 INFO - 'MOZ_NO_REMOTE': '1', 11:46:19 INFO - 'NO_EM_RESTART': '1', 11:46:19 INFO - 'PAGER': '/bin/cat', 11:46:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:19 INFO - 'PWD': '/builds/slave/test', 11:46:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:19 INFO - 'SHELL': '/bin/bash', 11:46:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LXSlnzDlO3/Listeners', 11:46:19 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 11:46:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:19 INFO - 'USER': 'cltbld', 11:46:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:19 INFO - 'XPC_FLAGS': '0x0', 11:46:19 INFO - 'XPC_SERVICE_NAME': '0', 11:46:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:19 INFO - Downloading/unpacking jsonschema==2.5.1 11:46:19 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 11:46:19 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 11:46:19 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 11:46:19 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 11:46:19 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 11:46:19 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 11:46:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:46:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:46:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:46:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:46:22 INFO - Downloading functools32-3.2.3-2.tar.gz 11:46:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:46:22 INFO - warning: no files found matching '*.txt' 11:46:22 INFO - no previously-included directories found matching 'build' 11:46:22 INFO - no previously-included directories found matching 'dist' 11:46:22 INFO - no previously-included directories found matching '.git*' 11:46:22 INFO - Installing collected packages: jsonschema, functools32 11:46:22 INFO - Running setup.py install for functools32 11:46:22 INFO - warning: no files found matching '*.txt' 11:46:22 INFO - no previously-included directories found matching 'build' 11:46:22 INFO - no previously-included directories found matching 'dist' 11:46:22 INFO - no previously-included directories found matching '.git*' 11:46:22 INFO - Successfully installed jsonschema functools32 11:46:22 INFO - Cleaning up... 11:46:22 INFO - Return code: 0 11:46:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:22 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 0x10a4a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae834a4a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283d30>, '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 0x10a1aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a278be8>, '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.1fwzRUltnc/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.LXSlnzDlO3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 11:46:22 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 11:46:22 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 11:46:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1fwzRUltnc/Render', 11:46:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:22 INFO - 'HOME': '/Users/cltbld', 11:46:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:22 INFO - 'LOGNAME': 'cltbld', 11:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:22 INFO - 'MOZ_NO_REMOTE': '1', 11:46:22 INFO - 'NO_EM_RESTART': '1', 11:46:22 INFO - 'PAGER': '/bin/cat', 11:46:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:22 INFO - 'PWD': '/builds/slave/test', 11:46:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:22 INFO - 'SHELL': '/bin/bash', 11:46:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LXSlnzDlO3/Listeners', 11:46:22 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 11:46:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:22 INFO - 'USER': 'cltbld', 11:46:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:22 INFO - 'XPC_FLAGS': '0x0', 11:46:22 INFO - 'XPC_SERVICE_NAME': '0', 11:46:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:46:22 INFO - Cleaning up... 11:46:22 INFO - Return code: 0 11:46:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:22 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 0x10a4a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae834a4a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283d30>, '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 0x10a1aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a278be8>, '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.1fwzRUltnc/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.LXSlnzDlO3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 11:46:22 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 11:46:22 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 11:46:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1fwzRUltnc/Render', 11:46:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:22 INFO - 'HOME': '/Users/cltbld', 11:46:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:22 INFO - 'LOGNAME': 'cltbld', 11:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:22 INFO - 'MOZ_NO_REMOTE': '1', 11:46:22 INFO - 'NO_EM_RESTART': '1', 11:46:22 INFO - 'PAGER': '/bin/cat', 11:46:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:22 INFO - 'PWD': '/builds/slave/test', 11:46:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:22 INFO - 'SHELL': '/bin/bash', 11:46:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LXSlnzDlO3/Listeners', 11:46:22 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 11:46:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:22 INFO - 'USER': 'cltbld', 11:46:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:22 INFO - 'XPC_FLAGS': '0x0', 11:46:22 INFO - 'XPC_SERVICE_NAME': '0', 11:46:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:23 INFO - Downloading/unpacking blobuploader==1.2.4 11:46:23 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 11:46:23 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 11:46:23 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 11:46:23 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 11:46:23 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 11:46:23 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 11:46:25 INFO - Downloading blobuploader-1.2.4.tar.gz 11:46:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:46:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:46:25 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 11:46:25 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 11:46:25 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 11:46:25 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 11:46:25 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 11:46:25 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 11:46:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:46:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:46: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 11:46: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 11:46: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 11:46: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 11:46: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 11:46: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 11:46:27 INFO - Downloading docopt-0.6.1.tar.gz 11:46:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:46:27 INFO - Installing collected packages: blobuploader, requests, docopt 11:46:27 INFO - Running setup.py install for blobuploader 11:46:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:46:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:46:27 INFO - Running setup.py install for requests 11:46:27 INFO - Running setup.py install for docopt 11:46:27 INFO - Successfully installed blobuploader requests docopt 11:46:27 INFO - Cleaning up... 11:46:28 INFO - Return code: 0 11:46:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:28 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 0x10a4a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae834a4a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283d30>, '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 0x10a1aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a278be8>, '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.1fwzRUltnc/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.LXSlnzDlO3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 11:46:28 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 11:46:28 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 11:46:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1fwzRUltnc/Render', 11:46:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:28 INFO - 'HOME': '/Users/cltbld', 11:46:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:28 INFO - 'LOGNAME': 'cltbld', 11:46:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:28 INFO - 'MOZ_NO_REMOTE': '1', 11:46:28 INFO - 'NO_EM_RESTART': '1', 11:46:28 INFO - 'PAGER': '/bin/cat', 11:46:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:28 INFO - 'PWD': '/builds/slave/test', 11:46:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:28 INFO - 'SHELL': '/bin/bash', 11:46:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LXSlnzDlO3/Listeners', 11:46:28 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 11:46:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:28 INFO - 'USER': 'cltbld', 11:46:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:28 INFO - 'XPC_FLAGS': '0x0', 11:46:28 INFO - 'XPC_SERVICE_NAME': '0', 11:46:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:46:28 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-_19F6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:46:28 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-FRV1jQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:46:28 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-3rBMaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:46:28 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-YDUfrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:46:28 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-NDW5YP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:46:28 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-lHtZCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:46:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:46:28 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-lNmY2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:46:29 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-b40tMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:46:29 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-BIJ1rL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:46:29 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ftsjLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:46:29 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-OcJW9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:46:29 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-VkWOg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:46:29 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-21Otsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:46:29 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-DWXuti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:29 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-a29ll3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:46:29 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-zOgWNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:46:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:46:29 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-CsfRbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:46:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:46:30 INFO - Running setup.py install for manifestparser 11:46:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:46:30 INFO - Running setup.py install for mozcrash 11:46:30 INFO - Running setup.py install for mozdebug 11:46:30 INFO - Running setup.py install for mozdevice 11:46:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:46:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:46:30 INFO - Running setup.py install for mozfile 11:46:30 INFO - Running setup.py install for mozhttpd 11:46:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:46:30 INFO - Running setup.py install for mozinfo 11:46:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:46:31 INFO - Running setup.py install for mozInstall 11:46:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:46:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:46:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:46:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:46:31 INFO - Running setup.py install for mozleak 11:46:31 INFO - Running setup.py install for mozlog 11:46:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:46:31 INFO - Running setup.py install for moznetwork 11:46:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:46:31 INFO - Running setup.py install for mozprocess 11:46:31 INFO - Running setup.py install for mozprofile 11:46:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:46:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:46:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:46:32 INFO - Running setup.py install for mozrunner 11:46:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:46:32 INFO - Running setup.py install for mozscreenshot 11:46:32 INFO - Running setup.py install for moztest 11:46:32 INFO - Running setup.py install for mozversion 11:46:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:46:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:46:32 INFO - Cleaning up... 11:46:32 INFO - Return code: 0 11:46:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:46:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:32 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 0x10a4a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae834a4a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283d30>, '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 0x10a1aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a278be8>, '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.1fwzRUltnc/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.LXSlnzDlO3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 11:46:32 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 11:46:32 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 11:46:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1fwzRUltnc/Render', 11:46:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:32 INFO - 'HOME': '/Users/cltbld', 11:46:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:32 INFO - 'LOGNAME': 'cltbld', 11:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:32 INFO - 'MOZ_NO_REMOTE': '1', 11:46:32 INFO - 'NO_EM_RESTART': '1', 11:46:32 INFO - 'PAGER': '/bin/cat', 11:46:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:32 INFO - 'PWD': '/builds/slave/test', 11:46:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:32 INFO - 'SHELL': '/bin/bash', 11:46:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LXSlnzDlO3/Listeners', 11:46:32 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 11:46:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:32 INFO - 'USER': 'cltbld', 11:46:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:32 INFO - 'XPC_FLAGS': '0x0', 11:46:32 INFO - 'XPC_SERVICE_NAME': '0', 11:46:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:46:32 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Lsi0Ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:46:33 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)) 11:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:46:33 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-TFty6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:46:33 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)) 11:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:46:33 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-NFNOtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:46:33 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)) 11:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:46:33 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-IqTG9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:46:33 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)) 11:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:46:33 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-9khKRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:46:33 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)) 11:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:46:33 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ybYUdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:46:33 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)) 11:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:46:33 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-E6xCSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:46:33 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)) 11:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:46:33 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-nU9siF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:46:33 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)) 11:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:46:33 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Path2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:46:33 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)) 11:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:46:33 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-pbJk3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:46:33 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)) 11:46:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:46:33 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-i1wdOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:46:34 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)) 11:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:46:34 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-nBlS1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:46:34 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)) 11:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:46:34 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-dh6UbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:46:34 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)) 11:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:46:34 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-l7mJbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:46:34 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)) 11:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:34 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-1q4iVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:34 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)) 11:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:46:34 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-1j0gmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:46:34 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)) 11:46:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:46:34 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-TEJ6qU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:46:34 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)) 11:46:34 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)) 11:46:34 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)) 11:46:34 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)) 11:46:34 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)) 11:46:34 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)) 11:46:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:46: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 11:46: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 11:46: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 11:46: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 11:46: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 11:46: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 11:46:37 INFO - Downloading blessings-1.6.tar.gz 11:46:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:46:37 INFO - Installing collected packages: blessings 11:46:37 INFO - Running setup.py install for blessings 11:46:37 INFO - Successfully installed blessings 11:46:37 INFO - Cleaning up... 11:46:37 INFO - Return code: 0 11:46:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:46:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:46:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:46:37 INFO - Reading from file tmpfile_stdout 11:46:37 INFO - Current package versions: 11:46:37 INFO - blessings == 1.6 11:46:37 INFO - blobuploader == 1.2.4 11:46:37 INFO - docopt == 0.6.1 11:46:37 INFO - functools32 == 3.2.3-2 11:46:37 INFO - jsonschema == 2.5.1 11:46:37 INFO - manifestparser == 1.1 11:46:37 INFO - mozInstall == 1.12 11:46:37 INFO - mozcrash == 1.0 11:46:37 INFO - mozdebug == 0.1 11:46:37 INFO - mozdevice == 0.49 11:46:37 INFO - mozfile == 1.2 11:46:37 INFO - mozhttpd == 0.7 11:46:37 INFO - mozinfo == 0.9 11:46:37 INFO - mozleak == 0.1 11:46:37 INFO - mozlog == 3.4 11:46:37 INFO - moznetwork == 0.27 11:46:37 INFO - mozprocess == 0.24 11:46:37 INFO - mozprofile == 0.28 11:46:37 INFO - mozrunner == 6.13 11:46:37 INFO - mozscreenshot == 0.1 11:46:37 INFO - mozsystemmonitor == 0.3 11:46:37 INFO - moztest == 0.8 11:46:37 INFO - mozversion == 1.4 11:46:37 INFO - psutil == 3.1.1 11:46:37 INFO - requests == 1.2.3 11:46:37 INFO - wsgiref == 0.1.2 11:46:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:38 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 0x10a4a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae834a4a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283d30>, '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 0x10a1aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a278be8>, '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.1fwzRUltnc/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.LXSlnzDlO3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 11:46:38 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 11:46:38 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 11:46:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1fwzRUltnc/Render', 11:46:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:38 INFO - 'HOME': '/Users/cltbld', 11:46:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:38 INFO - 'LOGNAME': 'cltbld', 11:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:38 INFO - 'MOZ_NO_REMOTE': '1', 11:46:38 INFO - 'NO_EM_RESTART': '1', 11:46:38 INFO - 'PAGER': '/bin/cat', 11:46:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:38 INFO - 'PWD': '/builds/slave/test', 11:46:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:38 INFO - 'SHELL': '/bin/bash', 11:46:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LXSlnzDlO3/Listeners', 11:46:38 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 11:46:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:38 INFO - 'USER': 'cltbld', 11:46:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:38 INFO - 'XPC_FLAGS': '0x0', 11:46:38 INFO - 'XPC_SERVICE_NAME': '0', 11:46:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:38 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)) 11:46:38 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)) 11:46:38 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)) 11:46:38 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)) 11:46:38 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)) 11:46:38 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)) 11:46:38 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)) 11:46:38 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)) 11:46:38 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)) 11:46:38 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)) 11:46:38 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)) 11:46:38 INFO - Cleaning up... 11:46:39 INFO - Return code: 0 11:46:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae834a4a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a283d30>, '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 0x10a1aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a278be8>, '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.1fwzRUltnc/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.LXSlnzDlO3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 11:46:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1fwzRUltnc/Render', 11:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:39 INFO - 'HOME': '/Users/cltbld', 11:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:39 INFO - 'LOGNAME': 'cltbld', 11:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:39 INFO - 'MOZ_NO_REMOTE': '1', 11:46:39 INFO - 'NO_EM_RESTART': '1', 11:46:39 INFO - 'PAGER': '/bin/cat', 11:46:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:39 INFO - 'PWD': '/builds/slave/test', 11:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:39 INFO - 'SHELL': '/bin/bash', 11:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LXSlnzDlO3/Listeners', 11:46:39 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 11:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:39 INFO - 'USER': 'cltbld', 11:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:39 INFO - 'XPC_FLAGS': '0x0', 11:46:39 INFO - 'XPC_SERVICE_NAME': '0', 11:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:46:39 INFO - Cleaning up... 11:46:39 INFO - Return code: 0 11:46:39 INFO - Running post-action listener: _resource_record_post_action 11:46:39 INFO - Running post-action listener: _start_resource_monitoring 11:46:39 INFO - Starting resource monitoring. 11:46:39 INFO - [mozharness: 2017-02-06 19:46:39.711308Z] Finished create-virtualenv step (success) 11:46:39 INFO - [mozharness: 2017-02-06 19:46:39.712236Z] Running install step. 11:46:39 INFO - Running pre-action listener: _resource_record_pre_action 11:46:39 INFO - Running main action method: install 11:46:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:46:39 INFO - Reading from file tmpfile_stdout 11:46:39 INFO - Detecting whether we're running mozinstall >=1.0... 11:46:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:46:40 INFO - Reading from file tmpfile_stdout 11:46:40 INFO - Output received: 11:46:40 INFO - Usage: mozinstall [options] installer 11:46:40 INFO - Options: 11:46:40 INFO - -h, --help show this help message and exit 11:46:40 INFO - -d DEST, --destination=DEST 11:46:40 INFO - Directory to install application into. [default: 11:46:40 INFO - "/builds/slave/test"] 11:46:40 INFO - --app=APP Application being installed. [default: firefox] 11:46:40 INFO - mkdir: /builds/slave/test/build/application 11:46:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:46:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 11:47:00 INFO - Reading from file tmpfile_stdout 11:47:00 INFO - Output received: 11:47:00 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:47:00 INFO - Running post-action listener: _resource_record_post_action 11:47:00 INFO - [mozharness: 2017-02-06 19:47:00.208731Z] Finished install step (success) 11:47:00 INFO - [mozharness: 2017-02-06 19:47:00.208898Z] Running run-tests step. 11:47:00 INFO - Running pre-action listener: _resource_record_pre_action 11:47:00 INFO - Running main action method: run_tests 11:47:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:47:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:47:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:47:00 INFO - 2017-02-06 11:47:00.223 screenresolution[1623:7255] starting screenresolution argv=screenresolution get 11:47:00 INFO - 2017-02-06 11:47:00.225 screenresolution[1623:7255] Display 0: 1600x1200x32@60 11:47:00 INFO - 2017-02-06 11:47:00.231 screenresolution[1624:7258] starting screenresolution argv=screenresolution list 11:47:00 INFO - Available Modes on Display 0 11:47:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:47:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:47:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:47:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:47:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:47:00 INFO - Intel Iris: 11:47:00 INFO - Chipset Model: Intel Iris 11:47:00 INFO - Type: GPU 11:47:00 INFO - Bus: Built-In 11:47:00 INFO - VRAM (Dynamic, Max): 1536 MB 11:47:00 INFO - Vendor: Intel (0x8086) 11:47:00 INFO - Device ID: 0x0a2e 11:47:00 INFO - Revision ID: 0x0009 11:47:00 INFO - Displays: 11:47:00 INFO - Display: 11:47:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:47:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:47:00 INFO - Display Serial Number: 1600x1200 60 11:47:00 INFO - Main Display: Yes 11:47:00 INFO - Mirror: Off 11:47:00 INFO - Online: Yes 11:47:00 INFO - Rotation: Supported 11:47:00 INFO - Return code: 0 11:47:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:47:00 INFO - Python 2.7.3 11:47:00 INFO - Return code: 0 11:47:00 INFO - grabbing minidump binary from tooltool 11:47:00 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')]} 11:47:00 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 0x10a283d30>, '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 0x10a1aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a278be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:47:00 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 11:47:00 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 11:47:00 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 11:47:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:47:00 INFO - Return code: 0 11:47:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:47:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:47:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:47:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:47:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:47:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--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 11:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0027 --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 11:47:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1fwzRUltnc/Render', 11:47:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:47:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:47:00 INFO - 'HOME': '/Users/cltbld', 11:47:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:47:00 INFO - 'LOGNAME': 'cltbld', 11:47:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:47:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:00 INFO - 'MOZ_NO_REMOTE': '1', 11:47:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:47:00 INFO - 'NO_EM_RESTART': '1', 11:47:00 INFO - 'PAGER': '/bin/cat', 11:47:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:00 INFO - 'PWD': '/builds/slave/test', 11:47:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:47:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:47:00 INFO - 'SHELL': '/bin/bash', 11:47:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LXSlnzDlO3/Listeners', 11:47:00 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 11:47:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:47:00 INFO - 'USER': 'cltbld', 11:47:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:47:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:00 INFO - 'XPC_FLAGS': '0x0', 11:47:00 INFO - 'XPC_SERVICE_NAME': '0', 11:47:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:47:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--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 11:47:00 INFO - mozversion application_buildid: 20170206101454 11:47:00 INFO - mozversion application_changeset: 05fae22830c0365b8173be4a2584ff0375b547a1 11:47:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:47:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:47:00 INFO - mozversion application_name: Firefox 11:47:00 INFO - mozversion application_remotingname: firefox-dev 11:47:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:47:00 INFO - mozversion application_vendor: Mozilla 11:47:00 INFO - mozversion application_version: 53.0a2 11:47:00 INFO - mozversion platform_buildid: 20170206101454 11:47:00 INFO - mozversion platform_changeset: 05fae22830c0365b8173be4a2584ff0375b547a1 11:47:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:47:00 INFO - mozversion platform_version: 53.0a2 11:47:00 INFO - using testdate: 1486410420 11:47:00 INFO - actual date: 1486410420 11:47:00 INFO - starting webserver on 'localhost:49238' 11:47:00 INFO - SUITE-START | Running 2 tests 11:47:00 INFO - TEST-START | dromaeo_css 11:47:00 INFO - operating with platform_type : mac_ 11:47:00 INFO - Initialising browser for dromaeo_css test... 11:47:00 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpnfYJ8X/profile http://localhost:49238/getInfo.html) 11:47:03 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 11:47:03 INFO - PROCESS | 1634 | colorDepth:24 11:47:03 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 11:47:03 INFO - PROCESS | 1634 | __metrics 11:47:03 INFO - PROCESS | 1634 | 1486410423371 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 11:47:03 INFO - PROCESS | 1634 | 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" 11:47:03 INFO - PROCESS | 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:47:04 INFO - TEST-INFO | 1634: exit 0 11:47:04 INFO - Browser initialized. 11:47:04 INFO - Running cycle 1/1 for dromaeo_css test... 11:47:04 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpnfYJ8X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:47:16 INFO - PROCESS | 1636 | RSS: Main: 212709376 11:47:16 INFO - PROCESS | 1636 | 11:50:20 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 11:50:22 INFO - PROCESS | 1636 | RSS: Main: 375078912 11:50:22 INFO - PROCESS | 1636 | 11:52:05 INFO - PROCESS | 1636 | ************************* 11:52:05 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 11:52:05 INFO - PROCESS | 1636 | 11:52:05 ERROR - PROCESS | 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:52:05 INFO - PROCESS | 1636 | Full stack: 11:52:05 INFO - PROCESS | 1636 | ************************* 11:53:25 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 11:53:25 INFO - PROCESS | 1636 | RSS: Main: 373428224 11:53:25 INFO - PROCESS | 1636 | 11:56:28 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 11:56:28 INFO - PROCESS | 1636 | RSS: Main: 335622144 11:56:28 INFO - PROCESS | 1636 | 11:59:31 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 11:59:32 INFO - PROCESS | 1636 | RSS: Main: 326135808 11:59:32 INFO - PROCESS | 1636 | 12:02:35 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 12:02:36 INFO - PROCESS | 1636 | RSS: Main: 317333504 12:02:36 INFO - PROCESS | 1636 | 12:05:39 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 12:05:39 INFO - PROCESS | 1636 | RSS: Main: 451182592 12:05:39 INFO - PROCESS | 1636 | 12:05:39 INFO - PROCESS | 1636 | __start_tp_report 12:05:39 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 12:05:39 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 12:05:39 INFO - PROCESS | 1636 | |i|pagename|runs| 12:05:39 INFO - PROCESS | 1636 | |0;dojo.html;3362.4451965425706;3366.5791776027995;3393.457046872501;3394.473856552234;3452.6719961603653;5764.538836893049;5842.561807864411;5872.295324561053;5884.146798714184;5893.145493903381;5713.542916566675;5791.536677065835;5886;5889.970550147254;5893.675847828367;5226.216067589873;5302.257683924254;5305.814296499623;5319.654222475546;5349.919751203735;5023.070731914608;5184.274201611777;5234;5370.516653501184;5374.7850085996515;4658.697184683002;5135.768890399922;5326.786928522854;5336.653117547347;5344.35867695877;5277.102892508283;5297.47025297471;5321.574274058066;5342.358916929974;5400.162974738882;5248.12620139173;5258.237555554423;5292.788288468457;5306.310179676656;5314.123169676998;5882;5904.7342869570975;5986.640801551877;6002.729877155539;6027.60820546663;6145.801568694106;6174.197354343952;6174.320824709278;6192.102145188967;6254.34329395416;6264.404881536247;6270.216222972129;6337.6831158442355;6347.523935704794;6362.363763623647;5859.53123750099;5931.881362372815;5932.614380065282;5955.761769529214;5984;6265.906011409833;6298.212723409574;6315.55791094619;6353.491720662336;6365.395287447685;6276.372362763687;6277.591956522811;6290.937090629127;6292.182016337938;6304.306526282106;6340.524460665469;6346.333634968354;6347;6364;6372.203474565679;6127.250613123855;6201.81394558164;6219.875602488016;6283.937160628427;6317.936820631735;6286.685665716787;6311.589746666452;6340.429361357407;6343.143675603735;6361.840953976188;5044.268581055726;5057.696538207646;5062.367204099488;5069.518395752398;5086.109930762102;4410.294352903584;4434.401355816924;4507.5041745408635;4537.387452693844;4541.341505481687;5866.061430171152;5873.177755114288;5913.142594323799;5928.170056192136;6012.398760123952;6201.976673848854;6272.4452946670835;6321.304656487782;6345.49236061114;6352.396522330371;5795.304563452329;5804;5807.390224026501;5838.2702162229725;5989.341172471025;5556.083246264321;5581.302337207849;5591.440855914458;5593.07714227148;5598.160275958558;6365.490760739037;6402.775902843378;6403.999999999907;6414.743410263537;6422.068800024063;6262.53031022666;6295.842603934755;6306.716197771203;6322.747090116344;6323.336049714714;6227.844303892439;6291.02489114188;6316.115743795965;6323.20959880015;6375.6174629522375;6299.4015568522755;6304.590201636879;6306.527010474141;6308.84227894288;6327.936720632735;6271.278802937721;6332.746690132527;6355.491560675043;6356.555041147075;6356.555041147075;6028.57799953999;6111.895622426936;6123.08153776937;6124.265088189446;6218.284897236876;6148.231471066117;6221.657808820558;6235.064740288993;6253.062040693932;6260.843478913063;6106.72519736604;6120.632762034292;6121.84695382619;6128.295246046583;6131.417515336036;6035.305939816977;6110.541709371903;6125.632462052291;6201.410865967726;6230.719617617308;5943.3759455258305;5965.403459654;6011.609245399035;6040.818775436744;6048.971674815204 12:05:39 INFO - PROCESS | 1636 | |1;ext.html;448.77561219390304;466.98365557205506;495.2744229703484;498.62104800351744;507.2518035897052;25032.87483562582;40377.596224037785;42654;42831.78584107083;42849.57150428495;44045.55954440455;44459;44515.554844451544;44537;44541.55458445415;9173.86239206414;9369.812603747921;9388.43669379836;9396.671116510923;9403.57683904226;8434.831303373929;8701.129887011311;11162.609308674198;11494.655160345203;11503.712407189803;11243.662690119269;11298.548058077668;11304.152188585847;11318.094552435785;11326.830097548544;8413.200745929127;11352.262102963323;11412.429378531082;11423.828642570368;11432.42837858112;8501.022382426037;10649.520771565221;10935.070519005814;10966.396848173346;11196.94401527987;42016.78991605053;42562;42622.573774262484;42630.7210783677;42761.930951726456;3995;4021.9195616087513;4034.616711412411;4043.1509383029597;4312.6765492587865;29285.56071658927;30248.848755756302;30455;31387.686123138705;32105.678943210503;3183.522471629251;3206.5831441912637;3247.220667039917;3267.787593806395;3281.9015429537153;459405;460448;461097;461327.9999999966;462270;65127.67436162884;65420.67289663521;65577.34422655808;65581;65687.67156164188;69724.30275697178;69734.3026569738;69762;69953.65023174851;69957.65021174861;16572.58568535796;17218.139093045298;17261.482155535356;17275.87706799054;17290.827091728923;11857.762844743229;12119.091068169922;13145.73708525824;13182.868171318356;13197.076204665582;16300.673986520203;18060.458186254433;18249.813762105685;18385.08074596265;18761.81238187628;7322.450816188701;7433.516821406592;7487.288707572772;7540.396768258526;7598;892.6563273139888;908.1690253418049;912.0833562269876;915.8534634458455;940.6848705683536;25102.553660644386;26652.80062397197;29684.851575741985;30693;31535;1094.9726256843483;1107.7891735635421;1114.4260705736551;1134.886511348875;1170.087331881134;15374.461893833663;15690.68618627621;15772.60568485797;15970.600734981486;16029.772713666964;601143;603182;603493.9825300846;603767.8492860236;604423;62336.688316558124;62444.751104977644;62460;62476.375236247055;62544.749105018556;1442.985570144285;1454.7454195515743;1470.7793830925448;1484.4062375050066;1488.5385530485585;28807.711922881343;28924.42151156923;29810;30491.54262686017;30562.541561876144;49990;52941;52944;53068.469315306356;53109;566783;571184;573433;573540;574492;25236.62145067789;25248.242552723448;25571.616425754;25587.488250235263;25631.743682562937;42458.57541424546;44643.55356446524;44834.77582612066;45172.548274516834;45196;35335.293294134484;35828.10429739173;36038.099047524025;36106.27787444289;36927;36197.09507262339;36219.27561448704;36521.452178216816;37034.62965370419;37303.25393492169;35406.645933540334;35907;36060.45909311415;36198.8190059048;36318.455223172205;29836.955706551034;32278;32286.35427291394;32719.672803272613;33164.50253246155;23136.537269254182;24058.51882962366;24101;24346.756532435156;24510 12:05:39 INFO - PROCESS | 1636 | |2;jquery.html;365.55402409060946;368.78241837315977;387.90496328399547;400.6677796327212;418.4580967646897;15211.46759863405;17052.658946821073;20209.797902020975;21073.47316317093;21472.463188420297;20489.795102048975;20759.065842037107;21228.89385553074;21328.36014919551;21564.02961866716;5787.884242315162;5969.164316995624;6095.451409373156;6105.297890742577;6117.69411529424;4869.9269510957365;6095.420935011167;6122.387761223864;6147.692615369236;6150.477209437203;5794.478496935275;6074.362191969846;6087.056506241517;6088.45203931646;6095.756169753205;5816.563757718167;5951.375105613892;5977.790777322794;5982.850428739294;6004.249468816398;5086.821961231376;5123.951249006835;5254.632175747699;5254.894902101936;5257.973710131463;25908.093216737325;25947;25957.74042259572;26207.737922620912;26328.47343053128;3066.23344163959;3130.4991201407665;3145.1665308693214;3278.393497203025;3303.1907182740383;14554.636134096732;14606.780898286534;14777.300610429724;14821.925890370587;14827.851721482863;1746.81658425866;1825.8995755233457;1855.5917698106396;1857.1271502393854;1907.8092190780949;124720;138541;139660;144386;145561;8855.33584981129;11627.76744465106;11634.905716971027;11650.30098194111;11989.820152697717;14015.789763153562;14368.281585920662;14372.281385930663;14382.784258236135;14389.64025899361;7762.7671169865;9229.16937475617;10267.127294179929;11262.099032077414;11365.204435689588;8795.296376289882;9059.728208153765;9143.679971200976;9159.038300978435;9243.70588117664;8759.60581773809;10708.1433485321;10781.676549703521;10852.782944341068;10924.890751092546;4098.43847595253;4122.56713045132;4158.8336466540995;4192.979035104805;4214.831406743757;862.2929198057533;971.028971028971;979.779549601334;1007.3502590828874;1027.8509616105623;18206.36277730273;21117;21268.25561105354;21347.201831437964;21469;986.487026746088;997.7156510394502;1031.7111208861531;1035.7100011996654;1036.5076588620345;10310.896891030994;10399.48002599882;10646.73383165427;10651.254412191227;10667.306625069346;672188;680466;701400;706315;706948;115888;139643.90534141794;142817.28591357183;145533;150365.24817376063;972.1396564040733;986.713852982627;1052.1845569683403;1058.9629362972414;1066.3495267886583;19150.329738459088;20123.899380503004;20368.98155092269;20393.776373417048;20518.69221961642;61055;62730;63530.047049293375;64078.038829418525;64439;88552;90366.54816725875;92163.53918230634;92221;94412.58381124151;6629.701663425061;7576.810579735551;7678.500897441649;8163.551004694798;8783.253423458822;12651.683707907376;13038.087333886537;13050.216986980811;13495.055346125677;13647.044706870423;11097.944510277719;11484.540618375171;11635.592754253561;11653.41732913368;11756;9111.908880911105;11056.557737690402;11305.208635395773;11455.427228638702;11601;8110.391720621095;10725;11371.260868043883;11845.52617895309;11861.40692965331;7216.242294558996;8427.747167584983;8551.529665868436;8598.570071496526;8750.737477875675;8528.701495447629;11153.275037122561;11482.253653512495;11736.354500502213;11739.88260117422 12:05:39 INFO - PROCESS | 1636 | |3;mootools.html;1418.241240936099;1443.2422977936583;1444;1468.192494128229;1470.764677651576;8074.757757267276;8517.403781735282;8535.78660533487;8542.658293668246;8544.27373673237;6554.639494827795;7375.373093287077;7689.500182488135;8232.012158540969;8245.711400100998;2160.600288946546;2197.7582465928735;2198.0438509248447;2201.2295696506253;2204.1073365287084;2324.4421338878715;2346.4133966508375;2356.8703721295324;2361.3978435498925;2362.6574146748712;2035.39955713065;2114.101506859586;2114.9259775907844;2248.8875556222206;2332.0555175154004;2320.7331156916916;2342.508073304608;2345.366750977235;2351.5649604822993;2353.5057637895893;2291.7937385635205;2371.1819422299277;2372.632242002492;2374.1215750172373;2379.3932547200525;8448;8529.658813647447;8531.616077276476;8533.402661813676;8550.957245213734;5103.438621751604;5271.683698978035;5274.894502109974;5281.947180528184;5288.894222115536;3321.0368992992003;3394;3517.876874309387;3571.8928432147077;3573.26748016656;1242.3974372429363;1268.6638040919072;1432.4485073246772;1767.45208985215;1851.879627824187;9047.276217902618;9828.508574571315;10871.075958543453;10871.130309575216;10878.510467029002;3986.920261594752;4286.849960251377;4444.688871779009;4486.955130448654;4549.886252843706;8685.08806575313;8707.434016789015;8721;8723.563821808884;8726.432781869158;5155.175171972466;5161.458046905021;5162.819301324435;5171.2501687255135;5172.793088276427;4650.09323181983;4739.763011849463;4765.568790470925;4765.666403351732;4845.224764037737;3678.0620941659704;3707.1844194277214;3750.7562008469363;3826.521684789401;3839.5793556384315;1151.9251248668809;1154.9595764148214;1159.9178432870604;1170.9180357375071;1175.3829239649253;735.4373903963473;758.9279018493235;775.6155262855775;796.2475460689708;829.9791256754105;5315.867103322448;5400.297961265089;5524.861878452991;5750.424957504216;5798.391168927202;926.6154545863526;943.118184497497;953.4612943686794;999.5102399824034;1044.9111825494765;3613.403788374889;3711.31340701971;3904.6876249899938;3922.7450215735885;3989.2420440116866;8487.236148746642;9818.75453113678;10125.949370253102;10151.796964060677;10172.440515771703;7650.770476885702;9999.150072243941;10072.848907266396;10077.143442807443;10109.34289271195;7799.571023593643;8013.719519816895;8015.7595272141925;8019.679212831538;8031.397645176635;5308.973455132699;5436.7281635918835;5549.278593782782;5744.770209191585;5927.188748253045;9036.23192028685;9444;9460.716178514656;9465.716028519155;9468.715938521855;9296.39573427725;9430.717078487656;9439.575219115019;9447.385919915183;9560.28297877648;7160.606166660884;7255.637218138966;7258.129024517092;7298.233685463162;7344.008558844701;7925.920740792519;7928.167542407964;7931.087924888711;7951.4433989622485;7958.323542498721;7068.681909314196;7140.857462805926;7209.315115064061;7286.890696639449;7312.268773122645;6928.861422771617;7025.473089518205;7053.964730176316;7061.258567850302;7188.820279492846;5510.779568817203;6333.619982801047;6489.091527186103;6492.772752953813;6523.9347606525225;4927.457979622167;4983.302337672656;5047.268146118866;5066.214736715815;5132.461091585479;4949.9257511138085;5011.348524691913;5095.541401273755;5140.974295128501;5229.6339256251695 12:05:39 INFO - PROCESS | 1636 | |4;prototype.html;353.0855085328997;362.7370156636439;363.4094596281044;374.7021118211022;374.93063783200125;5072.351485767132;5275.709835959021;5345.89308213836;5396.379416367114;5401.513863752267;5092.465291144423;5166.405863325719;5257.631965762398;5312.362516498026;5320.494553017466;4145.461090058288;4182.53992060873;4191.685623578229;4226.746395216297;4239.59723826236;3065.0345141280536;4004.279229738646;4184.100418410041;4206.221848957937;4235.703500754948;4125.195586860564;4164.021454958082;4173.874783756492;4176.540580536139;4189.183109293689;4127.669786417079;4135.9660084978605;4151.709380343377;4158.771267580282;4169.7081204315555;3666.6699997000132;3676.8161591920566;3710.4619830124752;3731.421629647409;3746.3631182699005;4230.725002874803;4538.231678676005;5205.947940520622;5209.453007434241;5278.498542638443;4168.187203495319;4780.354652121954;4803.951960480385;4806.639502037361;4816.060868130716;5093.872653183663;5548.335499350179;5645.463680950304;5709.257796486471;5718.828435146952;3434.845431955568;3818.2554401891643;4077;4118.691098167649;4139.544650088488;5299.708516031616;5823.621464604829;5826.446487583673;5883.911741323884;5923.949166179421;4210.884115709339;4215.437954928081;4219.38818871262;4222.577742225814;4225.894352641147;3586.8385922633543;3730.1908574341423;3975.3241948868185;4159.91680166395;4195.853145139906;3883.009832492696;4529.9547004530195;4663.927296721773;4708.340832283483;4811.975940120277;5613.719314034363;5640.915386269209;5647.961707379243;5660.037793575102;5687.51656109227;1196.7785959597502;1313.3498918035634;1330.6872884872048;1353.6345186799508;1377.0223141569597;864.9135086491301;944.7071407863584;973.4889183178888;975.3808677595158;988.4415305352596;4647.86056418308;4759.524047595282;4945.8021679132435;4958.677685950402;4961.6526843121355;4925.7783399747095;5340.65011394985;5450.91823622646;5464.453554644504;5503.477169668875;4581;4827.348307978448;4974.328465657162;4986.326845875833;5028.094942910311;2470.8146888983397;2614.914810353682;2635.667434320602;2645.949389223329;2910.54886492594;4996.975015124901;5088.643794934319;5719.742611582489;5777.740001699934;5903.527717782567;5054.762384856401;5394.352677678704;5494.2582751328055;5663.292088488939;5743.587693168879;4939.172506172739;5002.99940011999;5148.485151484747;5709.314882214161;5776.56294504769;5003.724050367278;5222.328854766438;5225.066725311659;5358.544523715372;5537.390887002507;4559.908801823879;4772.403449568804;5265.6050796189675;5515.538382328606;5773.393793651774;5033.521815427529;5201.869953251199;5767.952376906305;5862.3844496328975;5936.5844390892225;3294.40700673881;3870.148567315186;3905.668018218483;3911.8239679213934;3924.7448915820382;4877.9024419512125;5431.2667789847865;5638.2670252866865;5697.287839020122;5747.339056008613;4900.975495122501;5173.896522069463;5445.542080778655;5454.645448045864;5488.353493951879;5138.588912887035;5325.707086110301;5570.108782594765;5586.441355864381;5642.489092354027;5310.2831117798605;5388.892222155457;5483.8080667176455;5483.890322193613;5516.359841619306;4832.130216561053;5066.341375621146;5568.610197286151;5604.663720176803;5605.859853503532;4514.57111574413;4816.566509014206;5378.682222855269;5444.02007638613;5462.453754624506 12:05:39 INFO - PROCESS | 1636 | |5;yui.html;714.1786945013231;764.816444053427;814.2630919018288;821.6507984106761;838.4256784102892;15327.233638318081;15879.603009924758;15910.204489775493;15920.04479731214;15932.522024339274;14482;14560.563183104498;14565.563033108998;14575.979681422305;14581.489647862327;682.4779044031295;700.8773464643667;701.7614011236178;844.995775021124;852.7569642651846;872.5026734761157;878.0648609231148;891.933105017123;892.0232345581578;892.5492626223748;934.9813003739955;941.2187884056251;945.5886689290146;945.8250223708636;946.4557879219442;723.8660847743184;745.2845268542206;939.8449255872739;940.8118376324728;940.9905900940971;550.1664977559001;551.3301338873288;553.6456667732655;670.8457054877357;693.542262107007;14723.779143312859;14963.925180374028;15056.096634201873;15060.54818355451;15064.924675376553;13109.47562097515;13522.526711565146;13956.651083722887;13966.790498142536;14175.078619889675;12110.273383596925;12387.566435174725;12429.440675169639;12431.502739890393;12628.621141365773;4315.741055536678;4339.284018137004;4348.326009468537;4450.198964186445;4477.888052798674;90493.09506904913;102178.48910755473;102295;103681;106914;10517.9474102629;10674.78650426987;10948.233623646269;10953.671389858317;11078.723031924104;20928.581428371348;21338.35984920455;21784;21786.782132178792;21907.67138492924;7535.171131175566;7596.316331530189;7596.354309883612;7869.803254918673;7882.5270483771155;4212;4220.53574106848;4293.291606884892;4332.870013899588;4413.117376524644;3436.055084851686;3489.371913055674;3536.0806190390686;3536.0806190390686;3538.929221415609;635.8823617630753;644.7227692092446;646.053531576243;659.8944168932948;659.9637019963852;641.666333506574;646.5732616473106;647.2815175155573;650.8503044299742;669.7990602819135;9736.95131524338;9740.902590974;9775.90224097764;9835.45904975219;10055.849162262572;627.9560430769893;641.2785616181795;641.323403808986;668.2815972829228;688.2188715807495;4630.212863813093;4757.643176761757;4767.69010014348;4770.785314660849;4795.640326975421;84893.72659410114;85045.14954850372;85545;85735.57132214424;87602;88040.11959880448;89604;90178;90637;93001.0699893006;77216.61391693006;78308.60845695736;79335;79762;81149.59425202836;9190.862137068083;9226.907730922605;9382.624695012122;9383.108604682544;9405.905940940504;50560;50613.74693126658;51035.2344714837;51112.48887511077;52976.47023529869;58550.12174817296;61257.69371153116;61355.386446136756;64254.39364015937;69610;13131.277779721824;13158.065725352993;13331.933340333624;13356.065075444625;13477.663058423617;13696.52062177819;13830.446782128602;14904.329305181076;14925.626859328604;15086.396544138111;13169.407376667881;13539.59381218565;13908.443662253396;13915.860841391861;14168;10455.477226138815;13688.863111368759;13764.518241861888;13822.101563398346;14030.719385612434;13036.543720969721;13182.868171318163;13435;13447.193168409927;14018.859811401755;10745.24783265164;10810.18923580719;11045;11370.431478426211;11495.252808567417;8917.687880924395;10715.303505272132;10843.945780271048;10846.240763146505;10919.290246133976 12:05:39 INFO - PROCESS | 1636 | __end_tp_report 12:05:39 INFO - PROCESS | 1636 | __start_cc_report 12:05:39 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,1829 12:05:39 INFO - PROCESS | 1636 | __end_cc_report 12:05:39 INFO - PROCESS | 1636 | __startTimestamp1486411539571__endTimestamp 12:05:39 INFO - PROCESS | 1636 | 12:05:39 INFO - PROCESS | 1636 | ------- Summary: start ------- 12:05:39 INFO - PROCESS | 1636 | Number of tests: 6 12:05:39 INFO - PROCESS | 1636 | 12:05:39 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5841.58 Median:6106.73 stddev:644.49 (10.6%) stddev-sans-first:616.43 12:05:39 INFO - PROCESS | 1636 | Values: 3362.4 3366.6 3393.5 3394.5 3452.7 5764.5 5842.6 5872.3 5884.1 5893.1 5713.5 5791.5 5886.0 5890.0 5893.7 5226.2 5302.3 5305.8 5319.7 5349.9 5023.1 5184.3 5234.0 5370.5 5374.8 4658.7 5135.8 5326.8 5336.7 5344.4 5277.1 5297.5 5321.6 5342.4 5400.2 5248.1 5258.2 5292.8 5306.3 5314.1 5882.0 5904.7 5986.6 6002.7 6027.6 6145.8 6174.2 6174.3 6192.1 6254.3 6264.4 6270.2 6337.7 6347.5 6362.4 5859.5 5931.9 5932.6 5955.8 5984.0 6265.9 6298.2 6315.6 6353.5 6365.4 6276.4 6277.6 6290.9 6292.2 6304.3 6340.5 6346.3 6347.0 6364.0 6372.2 6127.3 6201.8 6219.9 6283.9 6317.9 6286.7 6311.6 6340.4 6343.1 6361.8 5044.3 5057.7 5062.4 5069.5 5086.1 4410.3 4434.4 4507.5 4537.4 4541.3 5866.1 5873.2 5913.1 5928.2 6012.4 6202.0 6272.4 6321.3 6345.5 6352.4 5795.3 5804.0 5807.4 5838.3 5989.3 5556.1 5581.3 5591.4 5593.1 5598.2 6365.5 6402.8 6404.0 6414.7 6422.1 6262.5 6295.8 6306.7 6322.7 6323.3 6227.8 6291.0 6316.1 6323.2 6375.6 6299.4 6304.6 6306.5 6308.8 6327.9 6271.3 6332.7 6355.5 6356.6 6356.6 6028.6 6111.9 6123.1 6124.3 6218.3 6148.2 6221.7 6235.1 6253.1 6260.8 6106.7 6120.6 6121.8 6128.3 6131.4 6035.3 6110.5 6125.6 6201.4 6230.7 5943.4 5965.4 6011.6 6040.8 6049.0 12:05:39 INFO - PROCESS | 1636 | 12:05:39 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:68616.03 Median:36209.05 stddev:146418.77 (404.4%) stddev-sans-first:146740.06 12:05:39 INFO - PROCESS | 1636 | Values: 448.8 467.0 495.3 498.6 507.3 25032.9 40377.6 42654.0 42831.8 42849.6 44045.6 44459.0 44515.6 44537.0 44541.6 9173.9 9369.8 9388.4 9396.7 9403.6 8434.8 8701.1 11162.6 11494.7 11503.7 11243.7 11298.5 11304.2 11318.1 11326.8 8413.2 11352.3 11412.4 11423.8 11432.4 8501.0 10649.5 10935.1 10966.4 11196.9 42016.8 42562.0 42622.6 42630.7 42761.9 3995.0 4021.9 4034.6 4043.2 4312.7 29285.6 30248.8 30455.0 31387.7 32105.7 3183.5 3206.6 3247.2 3267.8 3281.9 459405.0 460448.0 461097.0 461328.0 462270.0 65127.7 65420.7 65577.3 65581.0 65687.7 69724.3 69734.3 69762.0 69953.7 69957.7 16572.6 17218.1 17261.5 17275.9 17290.8 11857.8 12119.1 13145.7 13182.9 13197.1 16300.7 18060.5 18249.8 18385.1 18761.8 7322.5 7433.5 7487.3 7540.4 7598.0 892.7 908.2 912.1 915.9 940.7 25102.6 26652.8 29684.9 30693.0 31535.0 1095.0 1107.8 1114.4 1134.9 1170.1 15374.5 15690.7 15772.6 15970.6 16029.8 601143.0 603182.0 603494.0 603767.8 604423.0 62336.7 62444.8 62460.0 62476.4 62544.7 1443.0 1454.7 1470.8 1484.4 1488.5 28807.7 28924.4 29810.0 30491.5 30562.5 49990.0 52941.0 52944.0 53068.5 53109.0 566783.0 571184.0 573433.0 573540.0 574492.0 25236.6 25248.2 25571.6 25587.5 25631.7 42458.6 44643.6 44834.8 45172.5 45196.0 35335.3 35828.1 36038.1 36106.3 36927.0 36197.1 36219.3 36521.5 37034.6 37303.3 35406.6 35907.0 36060.5 36198.8 36318.5 29837.0 32278.0 32286.4 32719.7 33164.5 23136.5 24058.5 24101.0 24346.8 24510.0 12:05:39 INFO - PROCESS | 1636 | 12:05:39 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:39659.72 Median:23736.06 stddev:115768.69 (487.7%) stddev-sans-first:116055.85 12:05:39 INFO - PROCESS | 1636 | Values: 365.6 368.8 387.9 400.7 418.5 15211.5 17052.7 20209.8 21073.5 21472.5 20489.8 20759.1 21228.9 21328.4 21564.0 5787.9 5969.2 6095.5 6105.3 6117.7 4869.9 6095.4 6122.4 6147.7 6150.5 5794.5 6074.4 6087.1 6088.5 6095.8 5816.6 5951.4 5977.8 5982.9 6004.2 5086.8 5124.0 5254.6 5254.9 5258.0 25908.1 25947.0 25957.7 26207.7 26328.5 3066.2 3130.5 3145.2 3278.4 3303.2 14554.6 14606.8 14777.3 14821.9 14827.9 1746.8 1825.9 1855.6 1857.1 1907.8 124720.0 138541.0 139660.0 144386.0 145561.0 8855.3 11627.8 11634.9 11650.3 11989.8 14015.8 14368.3 14372.3 14382.8 14389.6 7762.8 9229.2 10267.1 11262.1 11365.2 8795.3 9059.7 9143.7 9159.0 9243.7 8759.6 10708.1 10781.7 10852.8 10924.9 4098.4 4122.6 4158.8 4193.0 4214.8 862.3 971.0 979.8 1007.4 1027.9 18206.4 21117.0 21268.3 21347.2 21469.0 986.5 997.7 1031.7 1035.7 1036.5 10310.9 10399.5 10646.7 10651.3 10667.3 672188.0 680466.0 701400.0 706315.0 706948.0 115888.0 139643.9 142817.3 145533.0 150365.2 972.1 986.7 1052.2 1059.0 1066.3 19150.3 20123.9 20369.0 20393.8 20518.7 61055.0 62730.0 63530.0 64078.0 64439.0 88552.0 90366.5 92163.5 92221.0 94412.6 6629.7 7576.8 7678.5 8163.6 8783.3 12651.7 13038.1 13050.2 13495.1 13647.0 11097.9 11484.5 11635.6 11653.4 11756.0 9111.9 11056.6 11305.2 11455.4 11601.0 8110.4 10725.0 11371.3 11845.5 11861.4 7216.2 8427.7 8551.5 8598.6 8750.7 8528.7 11153.3 11482.3 11736.4 11739.9 12:05:39 INFO - PROCESS | 1636 | 12:05:39 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5404.85 Median:5250.66 stddev:2906.85 (55.4%) stddev-sans-first:2899.56 12:05:39 INFO - PROCESS | 1636 | Values: 1418.2 1443.2 1444.0 1468.2 1470.8 8074.8 8517.4 8535.8 8542.7 8544.3 6554.6 7375.4 7689.5 8232.0 8245.7 2160.6 2197.8 2198.0 2201.2 2204.1 2324.4 2346.4 2356.9 2361.4 2362.7 2035.4 2114.1 2114.9 2248.9 2332.1 2320.7 2342.5 2345.4 2351.6 2353.5 2291.8 2371.2 2372.6 2374.1 2379.4 8448.0 8529.7 8531.6 8533.4 8551.0 5103.4 5271.7 5274.9 5281.9 5288.9 3321.0 3394.0 3517.9 3571.9 3573.3 1242.4 1268.7 1432.4 1767.5 1851.9 9047.3 9828.5 10871.1 10871.1 10878.5 3986.9 4286.8 4444.7 4487.0 4549.9 8685.1 8707.4 8721.0 8723.6 8726.4 5155.2 5161.5 5162.8 5171.3 5172.8 4650.1 4739.8 4765.6 4765.7 4845.2 3678.1 3707.2 3750.8 3826.5 3839.6 1151.9 1155.0 1159.9 1170.9 1175.4 735.4 758.9 775.6 796.2 830.0 5315.9 5400.3 5524.9 5750.4 5798.4 926.6 943.1 953.5 999.5 1044.9 3613.4 3711.3 3904.7 3922.7 3989.2 8487.2 9818.8 10125.9 10151.8 10172.4 7650.8 9999.2 10072.8 10077.1 10109.3 7799.6 8013.7 8015.8 8019.7 8031.4 5309.0 5436.7 5549.3 5744.8 5927.2 9036.2 9444.0 9460.7 9465.7 9468.7 9296.4 9430.7 9439.6 9447.4 9560.3 7160.6 7255.6 7258.1 7298.2 7344.0 7925.9 7928.2 7931.1 7951.4 7958.3 7068.7 7140.9 7209.3 7286.9 7312.3 6928.9 7025.5 7054.0 7061.3 7188.8 5510.8 6333.6 6489.1 6492.8 6523.9 4927.5 4983.3 5047.3 5066.2 5132.5 4949.9 5011.3 5095.5 5141.0 5229.6 12:05:39 INFO - PROCESS | 1636 | 12:05:39 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4509.17 Median:4999.99 stddev:1331.30 (26.6%) stddev-sans-first:1297.98 12:05:39 INFO - PROCESS | 1636 | Values: 353.1 362.7 363.4 374.7 374.9 5072.4 5275.7 5345.9 5396.4 5401.5 5092.5 5166.4 5257.6 5312.4 5320.5 4145.5 4182.5 4191.7 4226.7 4239.6 3065.0 4004.3 4184.1 4206.2 4235.7 4125.2 4164.0 4173.9 4176.5 4189.2 4127.7 4136.0 4151.7 4158.8 4169.7 3666.7 3676.8 3710.5 3731.4 3746.4 4230.7 4538.2 5205.9 5209.5 5278.5 4168.2 4780.4 4804.0 4806.6 4816.1 5093.9 5548.3 5645.5 5709.3 5718.8 3434.8 3818.3 4077.0 4118.7 4139.5 5299.7 5823.6 5826.4 5883.9 5923.9 4210.9 4215.4 4219.4 4222.6 4225.9 3586.8 3730.2 3975.3 4159.9 4195.9 3883.0 4530.0 4663.9 4708.3 4812.0 5613.7 5640.9 5648.0 5660.0 5687.5 1196.8 1313.3 1330.7 1353.6 1377.0 864.9 944.7 973.5 975.4 988.4 4647.9 4759.5 4945.8 4958.7 4961.7 4925.8 5340.7 5450.9 5464.5 5503.5 4581.0 4827.3 4974.3 4986.3 5028.1 2470.8 2614.9 2635.7 2645.9 2910.5 4997.0 5088.6 5719.7 5777.7 5903.5 5054.8 5394.4 5494.3 5663.3 5743.6 4939.2 5003.0 5148.5 5709.3 5776.6 5003.7 5222.3 5225.1 5358.5 5537.4 4559.9 4772.4 5265.6 5515.5 5773.4 5033.5 5201.9 5768.0 5862.4 5936.6 3294.4 3870.1 3905.7 3911.8 3924.7 4877.9 5431.3 5638.3 5697.3 5747.3 4901.0 5173.9 5445.5 5454.6 5488.4 5138.6 5325.7 5570.1 5586.4 5642.5 5310.3 5388.9 5483.8 5483.9 5516.4 4832.1 5066.3 5568.6 5604.7 5605.9 4514.6 4816.6 5378.7 5444.0 5462.5 12:05:39 INFO - PROCESS | 1636 | 12:05:39 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19851.14 Median:4762.67 stddev:27742.89 (582.5%) stddev-sans-first:27783.51 12:05:39 INFO - PROCESS | 1636 | Values: 714.2 764.8 814.3 821.7 838.4 15327.2 15879.6 15910.2 15920.0 15932.5 14482.0 14560.6 14565.6 14576.0 14581.5 682.5 700.9 701.8 845.0 852.8 872.5 878.1 891.9 892.0 892.5 935.0 941.2 945.6 945.8 946.5 723.9 745.3 939.8 940.8 941.0 550.2 551.3 553.6 670.8 693.5 14723.8 14963.9 15056.1 15060.5 15064.9 13109.5 13522.5 13956.7 13966.8 14175.1 12110.3 12387.6 12429.4 12431.5 12628.6 4315.7 4339.3 4348.3 4450.2 4477.9 90493.1 102178.5 102295.0 103681.0 106914.0 10517.9 10674.8 10948.2 10953.7 11078.7 20928.6 21338.4 21784.0 21786.8 21907.7 7535.2 7596.3 7596.4 7869.8 7882.5 4212.0 4220.5 4293.3 4332.9 4413.1 3436.1 3489.4 3536.1 3536.1 3538.9 635.9 644.7 646.1 659.9 660.0 641.7 646.6 647.3 650.9 669.8 9737.0 9740.9 9775.9 9835.5 10055.8 628.0 641.3 641.3 668.3 688.2 4630.2 4757.6 4767.7 4770.8 4795.6 84893.7 85045.1 85545.0 85735.6 87602.0 88040.1 89604.0 90178.0 90637.0 93001.1 77216.6 78308.6 79335.0 79762.0 81149.6 9190.9 9226.9 9382.6 9383.1 9405.9 50560.0 50613.7 51035.2 51112.5 52976.5 58550.1 61257.7 61355.4 64254.4 69610.0 13131.3 13158.1 13331.9 13356.1 13477.7 13696.5 13830.4 14904.3 14925.6 15086.4 13169.4 13539.6 13908.4 13915.9 14168.0 10455.5 13688.9 13764.5 13822.1 14030.7 13036.5 13182.9 13435.0 13447.2 14018.9 10745.2 10810.2 11045.0 11370.4 11495.3 8917.7 10715.3 10843.9 10846.2 10919.3 12:05:39 INFO - PROCESS | 1636 | -------- Summary: end -------- 12:05:39 INFO - PROCESS | 1636 | 12:05:40 INFO - TEST-INFO | 1636: exit 0 12:05:40 INFO - TEST-OK | dromaeo_css | took 1119438ms 12:05:40 INFO - TEST-START | kraken 12:05:40 INFO - operating with platform_type : mac_ 12:05:40 INFO - Initialising browser for kraken test... 12:05:40 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpU4LL_O/profile http://localhost:49238/getInfo.html) 12:05:42 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 12:05:42 INFO - PROCESS | 1647 | colorDepth:24 12:05:42 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 12:05:42 INFO - PROCESS | 1647 | __metrics 12:05:42 INFO - PROCESS | 1647 | 1486411542499 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 12:05:42 INFO - PROCESS | 1647 | 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" 12:05:42 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:05:43 INFO - TEST-INFO | 1647: exit 0 12:05:43 INFO - Browser initialized. 12:05:43 INFO - Running cycle 1/1 for kraken test... 12:05:43 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpU4LL_O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:05:55 INFO - PROCESS | 1648 | RSS: Main: 213479424 12:05:55 INFO - PROCESS | 1648 | 12:05:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 12:05:58 INFO - PROCESS | 1648 | RSS: Main: 266493952 12:05:58 INFO - PROCESS | 1648 | 12:06:01 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 12:06:02 INFO - PROCESS | 1648 | RSS: Main: 275636224 12:06:02 INFO - PROCESS | 1648 | 12:06:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 12:06:05 INFO - PROCESS | 1648 | RSS: Main: 373051392 12:06:05 INFO - PROCESS | 1648 | 12:06:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 12:06:08 INFO - PROCESS | 1648 | RSS: Main: 276766720 12:06:08 INFO - PROCESS | 1648 | 12:06:10 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:06:11 INFO - PROCESS | 1648 | RSS: Main: 278425600 12:06:11 INFO - PROCESS | 1648 | 12:06:13 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 12:06:14 INFO - PROCESS | 1648 | RSS: Main: 318279680 12:06:14 INFO - PROCESS | 1648 | 12:06:17 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 12:06:18 INFO - PROCESS | 1648 | RSS: Main: 319569920 12:06:18 INFO - PROCESS | 1648 | 12:06:20 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 12:06:21 INFO - PROCESS | 1648 | RSS: Main: 315854848 12:06:21 INFO - PROCESS | 1648 | 12:06:23 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:06:23 INFO - PROCESS | 1648 | RSS: Main: 294637568 12:06:23 INFO - PROCESS | 1648 | 12:06:25 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:06:26 INFO - PROCESS | 1648 | RSS: Main: 295727104 12:06:26 INFO - PROCESS | 1648 | 12:06:28 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:06:29 INFO - PROCESS | 1648 | RSS: Main: 298950656 12:06:29 INFO - PROCESS | 1648 | 12:06:31 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:06:32 INFO - PROCESS | 1648 | RSS: Main: 316289024 12:06:32 INFO - PROCESS | 1648 | 12:06:36 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:06:37 INFO - PROCESS | 1648 | RSS: Main: 298070016 12:06:37 INFO - PROCESS | 1648 | 12:06:38 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 12:06:39 INFO - PROCESS | 1648 | RSS: Main: 345784320 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | __start_tp_report 12:06:39 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 12:06:39 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 12:06:39 INFO - PROCESS | 1648 | |i|pagename|runs| 12:06:39 INFO - PROCESS | 1648 | |0;ai-astar;116;115;114;114;115;115;114;115;114;115 12:06:39 INFO - PROCESS | 1648 | |1;audio-beat-detection;165;139;105;142;111;109;107;108;129;108 12:06:39 INFO - PROCESS | 1648 | |2;audio-dft;185;182;183;182;183;184;183;183;186;181 12:06:39 INFO - PROCESS | 1648 | |3;audio-fft;79;60;74;112;86;57;58;58;74;59 12:06:39 INFO - PROCESS | 1648 | |4;audio-oscillator;91;70;86;69;68;71;81;67;68;81 12:06:39 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;91;87;90;88;91;88;91;88;90;86 12:06:39 INFO - PROCESS | 1648 | |6;imaging-darkroom;98;98;99;99;98;98;100;98;97;98 12:06:39 INFO - PROCESS | 1648 | |7;imaging-desaturate;86;86;87;86;85;87;88;86;85;87 12:06:39 INFO - PROCESS | 1648 | |8;json-parse-financial;57;54;55;54;55;58;57;57;57;57 12:06:39 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;72;77;69;73;73;69;69;69;70;69 12:06:39 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;84;84;84;84;84;84;84;83;84;85 12:06:39 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;139;136;138;139;135;141;154;143;182;155 12:06:39 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;168;169;168;362;362;356;365;225;166;178 12:06:39 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;67;67;66;66;65;66;64;62;71;79 12:06:39 INFO - PROCESS | 1648 | __end_tp_report 12:06:39 INFO - PROCESS | 1648 | __start_cc_report 12:06:39 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1960 12:06:39 INFO - PROCESS | 1648 | __end_cc_report 12:06:39 INFO - PROCESS | 1648 | __startTimestamp1486411599207__endTimestamp 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | ------- Summary: start ------- 12:06:39 INFO - PROCESS | 1648 | Number of tests: 14 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:0.67 (0.6%) stddev-sans-first:0.53 12:06:39 INFO - PROCESS | 1648 | Values: 116.0 115.0 114.0 114.0 115.0 115.0 114.0 115.0 114.0 115.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:122.30 Median:120.00 stddev:20.50 (17.1%) stddev-sans-first:14.82 12:06:39 INFO - PROCESS | 1648 | Values: 165.0 139.0 105.0 142.0 111.0 109.0 107.0 108.0 129.0 108.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.00 stddev:1.48 (0.8%) stddev-sans-first:1.41 12:06:39 INFO - PROCESS | 1648 | Values: 185.0 182.0 183.0 182.0 183.0 184.0 183.0 183.0 186.0 181.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:71.70 Median:67.00 stddev:17.58 (26.2%) stddev-sans-first:18.45 12:06:39 INFO - PROCESS | 1648 | Values: 79.0 60.0 74.0 112.0 86.0 57.0 58.0 58.0 74.0 59.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:76.00 stddev:8.74 (11.5%) stddev-sans-first:7.16 12:06:39 INFO - PROCESS | 1648 | Values: 91.0 70.0 86.0 69.0 68.0 71.0 81.0 67.0 68.0 81.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:90.00 stddev:1.83 (2.0%) stddev-sans-first:1.79 12:06:39 INFO - PROCESS | 1648 | Values: 91.0 87.0 90.0 88.0 91.0 88.0 91.0 88.0 90.0 86.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:98.00 stddev:0.82 (0.8%) stddev-sans-first:0.87 12:06:39 INFO - PROCESS | 1648 | Values: 98.0 98.0 99.0 99.0 98.0 98.0 100.0 98.0 97.0 98.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:86.30 Median:86.50 stddev:0.95 (1.1%) stddev-sans-first:1.00 12:06:39 INFO - PROCESS | 1648 | Values: 86.0 86.0 87.0 86.0 85.0 87.0 88.0 86.0 85.0 87.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:57.00 stddev:1.45 (2.5%) stddev-sans-first:1.50 12:06:39 INFO - PROCESS | 1648 | Values: 57.0 54.0 55.0 54.0 55.0 58.0 57.0 57.0 57.0 57.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:2.71 (3.8%) stddev-sans-first:2.85 12:06:39 INFO - PROCESS | 1648 | Values: 72.0 77.0 69.0 73.0 73.0 69.0 69.0 69.0 70.0 69.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:0.47 (0.6%) stddev-sans-first:0.50 12:06:39 INFO - PROCESS | 1648 | Values: 84.0 84.0 84.0 84.0 84.0 84.0 84.0 83.0 84.0 85.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:142.00 stddev:14.37 (10.1%) stddev-sans-first:15.00 12:06:39 INFO - PROCESS | 1648 | Values: 139.0 136.0 138.0 139.0 135.0 141.0 154.0 143.0 182.0 155.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:251.90 Median:290.50 stddev:95.68 (32.9%) stddev-sans-first:96.54 12:06:39 INFO - PROCESS | 1648 | Values: 168.0 169.0 168.0 362.0 362.0 356.0 365.0 225.0 166.0 178.0 12:06:39 INFO - PROCESS | 1648 | 12:06:39 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:66.50 stddev:4.72 (7.1%) stddev-sans-first:5.00 12:06:39 INFO - PROCESS | 1648 | Values: 67.0 67.0 66.0 66.0 65.0 66.0 64.0 62.0 71.0 79.0 12:06:39 INFO - PROCESS | 1648 | -------- Summary: end -------- 12:06:39 INFO - PROCESS | 1648 | 12:06:40 INFO - TEST-INFO | 1648: exit 0 12:06:40 INFO - TEST-OK | kraken | took 59846ms 12:06:40 INFO - SUITE-END | took 1179s 12:06:40 INFO - Completed test suite (00:19:39) 12:06:40 INFO - javascript benchmark 12:06:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3362.4451965425706, 3366.5791776027995, 3393.457046872501, 3394.473856552234, 3452.6719961603653, 5764.538836893049, 5842.561807864411, 5872.295324561053, 5884.146798714184, 5893.145493903381, 5713.542916566675, 5791.536677065835, 5886.0, 5889.970550147254, 5893.675847828367, 5226.216067589873, 5302.257683924254, 5305.814296499623, 5319.654222475546, 5349.919751203735, 5023.070731914608, 5184.274201611777, 5234.0, 5370.516653501184, 5374.7850085996515, 4658.697184683002, 5135.768890399922, 5326.786928522854, 5336.653117547347, 5344.35867695877, 5277.102892508283, 5297.47025297471, 5321.574274058066, 5342.358916929974, 5400.162974738882, 5248.12620139173, 5258.237555554423, 5292.788288468457, 5306.310179676656, 5314.123169676998, 5882.0, 5904.7342869570975, 5986.640801551877, 6002.729877155539, 6027.60820546663, 6145.801568694106, 6174.197354343952, 6174.320824709278, 6192.102145188967, 6254.34329395416, 6264.404881536247, 6270.216222972129, 6337.6831158442355, 6347.523935704794, 6362.363763623647, 5859.53123750099, 5931.881362372815, 5932.614380065282, 5955.761769529214, 5984.0, 6265.906011409833, 6298.212723409574, 6315.55791094619, 6353.491720662336, 6365.395287447685, 6276.372362763687, 6277.591956522811, 6290.937090629127, 6292.182016337938, 6304.306526282106, 6340.524460665469, 6346.333634968354, 6347.0, 6364.0, 6372.203474565679, 6127.250613123855, 6201.81394558164, 6219.875602488016, 6283.937160628427, 6317.936820631735, 6286.685665716787, 6311.589746666452, 6340.429361357407, 6343.143675603735, 6361.840953976188, 5044.268581055726, 5057.696538207646, 5062.367204099488, 5069.518395752398, 5086.109930762102, 4410.294352903584, 4434.401355816924, 4507.5041745408635, 4537.387452693844, 4541.341505481687, 5866.061430171152, 5873.177755114288, 5913.142594323799, 5928.170056192136, 6012.398760123952, 6201.976673848854, 6272.4452946670835, 6321.304656487782, 6345.49236061114, 6352.396522330371, 5795.304563452329, 5804.0, 5807.390224026501, 5838.2702162229725, 5989.341172471025, 5556.083246264321, 5581.302337207849, 5591.440855914458, 5593.07714227148, 5598.160275958558, 6365.490760739037, 6402.775902843378, 6403.999999999907, 6414.743410263537, 6422.068800024063, 6262.53031022666, 6295.842603934755, 6306.716197771203, 6322.747090116344, 6323.336049714714, 6227.844303892439, 6291.02489114188, 6316.115743795965, 6323.20959880015, 6375.6174629522375, 6299.4015568522755, 6304.590201636879, 6306.527010474141, 6308.84227894288, 6327.936720632735, 6271.278802937721, 6332.746690132527, 6355.491560675043, 6356.555041147075, 6356.555041147075, 6028.57799953999, 6111.895622426936, 6123.08153776937, 6124.265088189446, 6218.284897236876, 6148.231471066117, 6221.657808820558, 6235.064740288993, 6253.062040693932, 6260.843478913063, 6106.72519736604, 6120.632762034292, 6121.84695382619, 6128.295246046583, 6131.417515336036, 6035.305939816977, 6110.541709371903, 6125.632462052291, 6201.410865967726, 6230.719617617308, 5943.3759455258305, 5965.403459654, 6011.609245399035, 6040.818775436744, 6048.971674815204], "value": 5799.401252076001, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.77561219390304, 466.98365557205506, 495.2744229703484, 498.62104800351744, 507.2518035897052, 25032.87483562582, 40377.596224037785, 42654.0, 42831.78584107083, 42849.57150428495, 44045.55954440455, 44459.0, 44515.554844451544, 44537.0, 44541.55458445415, 9173.86239206414, 9369.812603747921, 9388.43669379836, 9396.671116510923, 9403.57683904226, 8434.831303373929, 8701.129887011311, 11162.609308674198, 11494.655160345203, 11503.712407189803, 11243.662690119269, 11298.548058077668, 11304.152188585847, 11318.094552435785, 11326.830097548544, 8413.200745929127, 11352.262102963323, 11412.429378531082, 11423.828642570368, 11432.42837858112, 8501.022382426037, 10649.520771565221, 10935.070519005814, 10966.396848173346, 11196.94401527987, 42016.78991605053, 42562.0, 42622.573774262484, 42630.7210783677, 42761.930951726456, 3995.0, 4021.9195616087513, 4034.616711412411, 4043.1509383029597, 4312.6765492587865, 29285.56071658927, 30248.848755756302, 30455.0, 31387.686123138705, 32105.678943210503, 3183.522471629251, 3206.5831441912637, 3247.220667039917, 3267.787593806395, 3281.9015429537153, 459405.0, 460448.0, 461097.0, 461327.9999999966, 462270.0, 65127.67436162884, 65420.67289663521, 65577.34422655808, 65581.0, 65687.67156164188, 69724.30275697178, 69734.3026569738, 69762.0, 69953.65023174851, 69957.65021174861, 16572.58568535796, 17218.139093045298, 17261.482155535356, 17275.87706799054, 17290.827091728923, 11857.762844743229, 12119.091068169922, 13145.73708525824, 13182.868171318356, 13197.076204665582, 16300.673986520203, 18060.458186254433, 18249.813762105685, 18385.08074596265, 18761.81238187628, 7322.450816188701, 7433.516821406592, 7487.288707572772, 7540.396768258526, 7598.0, 892.6563273139888, 908.1690253418049, 912.0833562269876, 915.8534634458455, 940.6848705683536, 25102.553660644386, 26652.80062397197, 29684.851575741985, 30693.0, 31535.0, 1094.9726256843483, 1107.7891735635421, 1114.4260705736551, 1134.886511348875, 1170.087331881134, 15374.461893833663, 15690.68618627621, 15772.60568485797, 15970.600734981486, 16029.772713666964, 601143.0, 603182.0, 603493.9825300846, 603767.8492860236, 604423.0, 62336.688316558124, 62444.751104977644, 62460.0, 62476.375236247055, 62544.749105018556, 1442.985570144285, 1454.7454195515743, 1470.7793830925448, 1484.4062375050066, 1488.5385530485585, 28807.711922881343, 28924.42151156923, 29810.0, 30491.54262686017, 30562.541561876144, 49990.0, 52941.0, 52944.0, 53068.469315306356, 53109.0, 566783.0, 571184.0, 573433.0, 573540.0, 574492.0, 25236.62145067789, 25248.242552723448, 25571.616425754, 25587.488250235263, 25631.743682562937, 42458.57541424546, 44643.55356446524, 44834.77582612066, 45172.548274516834, 45196.0, 35335.293294134484, 35828.10429739173, 36038.099047524025, 36106.27787444289, 36927.0, 36197.09507262339, 36219.27561448704, 36521.452178216816, 37034.62965370419, 37303.25393492169, 35406.645933540334, 35907.0, 36060.45909311415, 36198.8190059048, 36318.455223172205, 29836.955706551034, 32278.0, 32286.35427291394, 32719.672803272613, 33164.50253246155, 23136.537269254182, 24058.51882962366, 24101.0, 24346.756532435156, 24510.0], "value": 20226.969400976435, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.55402409060946, 368.78241837315977, 387.90496328399547, 400.6677796327212, 418.4580967646897, 15211.46759863405, 17052.658946821073, 20209.797902020975, 21073.47316317093, 21472.463188420297, 20489.795102048975, 20759.065842037107, 21228.89385553074, 21328.36014919551, 21564.02961866716, 5787.884242315162, 5969.164316995624, 6095.451409373156, 6105.297890742577, 6117.69411529424, 4869.9269510957365, 6095.420935011167, 6122.387761223864, 6147.692615369236, 6150.477209437203, 5794.478496935275, 6074.362191969846, 6087.056506241517, 6088.45203931646, 6095.756169753205, 5816.563757718167, 5951.375105613892, 5977.790777322794, 5982.850428739294, 6004.249468816398, 5086.821961231376, 5123.951249006835, 5254.632175747699, 5254.894902101936, 5257.973710131463, 25908.093216737325, 25947.0, 25957.74042259572, 26207.737922620912, 26328.47343053128, 3066.23344163959, 3130.4991201407665, 3145.1665308693214, 3278.393497203025, 3303.1907182740383, 14554.636134096732, 14606.780898286534, 14777.300610429724, 14821.925890370587, 14827.851721482863, 1746.81658425866, 1825.8995755233457, 1855.5917698106396, 1857.1271502393854, 1907.8092190780949, 124720.0, 138541.0, 139660.0, 144386.0, 145561.0, 8855.33584981129, 11627.76744465106, 11634.905716971027, 11650.30098194111, 11989.820152697717, 14015.789763153562, 14368.281585920662, 14372.281385930663, 14382.784258236135, 14389.64025899361, 7762.7671169865, 9229.16937475617, 10267.127294179929, 11262.099032077414, 11365.204435689588, 8795.296376289882, 9059.728208153765, 9143.679971200976, 9159.038300978435, 9243.70588117664, 8759.60581773809, 10708.1433485321, 10781.676549703521, 10852.782944341068, 10924.890751092546, 4098.43847595253, 4122.56713045132, 4158.8336466540995, 4192.979035104805, 4214.831406743757, 862.2929198057533, 971.028971028971, 979.779549601334, 1007.3502590828874, 1027.8509616105623, 18206.36277730273, 21117.0, 21268.25561105354, 21347.201831437964, 21469.0, 986.487026746088, 997.7156510394502, 1031.7111208861531, 1035.7100011996654, 1036.5076588620345, 10310.896891030994, 10399.48002599882, 10646.73383165427, 10651.254412191227, 10667.306625069346, 672188.0, 680466.0, 701400.0, 706315.0, 706948.0, 115888.0, 139643.90534141794, 142817.28591357183, 145533.0, 150365.24817376063, 972.1396564040733, 986.713852982627, 1052.1845569683403, 1058.9629362972414, 1066.3495267886583, 19150.329738459088, 20123.899380503004, 20368.98155092269, 20393.776373417048, 20518.69221961642, 61055.0, 62730.0, 63530.047049293375, 64078.038829418525, 64439.0, 88552.0, 90366.54816725875, 92163.53918230634, 92221.0, 94412.58381124151, 6629.701663425061, 7576.810579735551, 7678.500897441649, 8163.551004694798, 8783.253423458822, 12651.683707907376, 13038.087333886537, 13050.216986980811, 13495.055346125677, 13647.044706870423, 11097.944510277719, 11484.540618375171, 11635.592754253561, 11653.41732913368, 11756.0, 9111.908880911105, 11056.557737690402, 11305.208635395773, 11455.427228638702, 11601.0, 8110.391720621095, 10725.0, 11371.260868043883, 11845.52617895309, 11861.40692965331, 7216.242294558996, 8427.747167584983, 8551.529665868436, 8598.570071496526, 8750.737477875675, 8528.701495447629, 11153.275037122561, 11482.253653512495, 11736.354500502213, 11739.88260117422], "value": 10521.88606067466, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.241240936099, 1443.2422977936583, 1444.0, 1468.192494128229, 1470.764677651576, 8074.757757267276, 8517.403781735282, 8535.78660533487, 8542.658293668246, 8544.27373673237, 6554.639494827795, 7375.373093287077, 7689.500182488135, 8232.012158540969, 8245.711400100998, 2160.600288946546, 2197.7582465928735, 2198.0438509248447, 2201.2295696506253, 2204.1073365287084, 2324.4421338878715, 2346.4133966508375, 2356.8703721295324, 2361.3978435498925, 2362.6574146748712, 2035.39955713065, 2114.101506859586, 2114.9259775907844, 2248.8875556222206, 2332.0555175154004, 2320.7331156916916, 2342.508073304608, 2345.366750977235, 2351.5649604822993, 2353.5057637895893, 2291.7937385635205, 2371.1819422299277, 2372.632242002492, 2374.1215750172373, 2379.3932547200525, 8448.0, 8529.658813647447, 8531.616077276476, 8533.402661813676, 8550.957245213734, 5103.438621751604, 5271.683698978035, 5274.894502109974, 5281.947180528184, 5288.894222115536, 3321.0368992992003, 3394.0, 3517.876874309387, 3571.8928432147077, 3573.26748016656, 1242.3974372429363, 1268.6638040919072, 1432.4485073246772, 1767.45208985215, 1851.879627824187, 9047.276217902618, 9828.508574571315, 10871.075958543453, 10871.130309575216, 10878.510467029002, 3986.920261594752, 4286.849960251377, 4444.688871779009, 4486.955130448654, 4549.886252843706, 8685.08806575313, 8707.434016789015, 8721.0, 8723.563821808884, 8726.432781869158, 5155.175171972466, 5161.458046905021, 5162.819301324435, 5171.2501687255135, 5172.793088276427, 4650.09323181983, 4739.763011849463, 4765.568790470925, 4765.666403351732, 4845.224764037737, 3678.0620941659704, 3707.1844194277214, 3750.7562008469363, 3826.521684789401, 3839.5793556384315, 1151.9251248668809, 1154.9595764148214, 1159.9178432870604, 1170.9180357375071, 1175.3829239649253, 735.4373903963473, 758.9279018493235, 775.6155262855775, 796.2475460689708, 829.9791256754105, 5315.867103322448, 5400.297961265089, 5524.861878452991, 5750.424957504216, 5798.391168927202, 926.6154545863526, 943.118184497497, 953.4612943686794, 999.5102399824034, 1044.9111825494765, 3613.403788374889, 3711.31340701971, 3904.6876249899938, 3922.7450215735885, 3989.2420440116866, 8487.236148746642, 9818.75453113678, 10125.949370253102, 10151.796964060677, 10172.440515771703, 7650.770476885702, 9999.150072243941, 10072.848907266396, 10077.143442807443, 10109.34289271195, 7799.571023593643, 8013.719519816895, 8015.7595272141925, 8019.679212831538, 8031.397645176635, 5308.973455132699, 5436.7281635918835, 5549.278593782782, 5744.770209191585, 5927.188748253045, 9036.23192028685, 9444.0, 9460.716178514656, 9465.716028519155, 9468.715938521855, 9296.39573427725, 9430.717078487656, 9439.575219115019, 9447.385919915183, 9560.28297877648, 7160.606166660884, 7255.637218138966, 7258.129024517092, 7298.233685463162, 7344.008558844701, 7925.920740792519, 7928.167542407964, 7931.087924888711, 7951.4433989622485, 7958.323542498721, 7068.681909314196, 7140.857462805926, 7209.315115064061, 7286.890696639449, 7312.268773122645, 6928.861422771617, 7025.473089518205, 7053.964730176316, 7061.258567850302, 7188.820279492846, 5510.779568817203, 6333.619982801047, 6489.091527186103, 6492.772752953813, 6523.9347606525225, 4927.457979622167, 4983.302337672656, 5047.268146118866, 5066.214736715815, 5132.461091585479, 4949.9257511138085, 5011.348524691913, 5095.541401273755, 5140.974295128501, 5229.6339256251695], "value": 4410.320010193901, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.0855085328997, 362.7370156636439, 363.4094596281044, 374.7021118211022, 374.93063783200125, 5072.351485767132, 5275.709835959021, 5345.89308213836, 5396.379416367114, 5401.513863752267, 5092.465291144423, 5166.405863325719, 5257.631965762398, 5312.362516498026, 5320.494553017466, 4145.461090058288, 4182.53992060873, 4191.685623578229, 4226.746395216297, 4239.59723826236, 3065.0345141280536, 4004.279229738646, 4184.100418410041, 4206.221848957937, 4235.703500754948, 4125.195586860564, 4164.021454958082, 4173.874783756492, 4176.540580536139, 4189.183109293689, 4127.669786417079, 4135.9660084978605, 4151.709380343377, 4158.771267580282, 4169.7081204315555, 3666.6699997000132, 3676.8161591920566, 3710.4619830124752, 3731.421629647409, 3746.3631182699005, 4230.725002874803, 4538.231678676005, 5205.947940520622, 5209.453007434241, 5278.498542638443, 4168.187203495319, 4780.354652121954, 4803.951960480385, 4806.639502037361, 4816.060868130716, 5093.872653183663, 5548.335499350179, 5645.463680950304, 5709.257796486471, 5718.828435146952, 3434.845431955568, 3818.2554401891643, 4077.0, 4118.691098167649, 4139.544650088488, 5299.708516031616, 5823.621464604829, 5826.446487583673, 5883.911741323884, 5923.949166179421, 4210.884115709339, 4215.437954928081, 4219.38818871262, 4222.577742225814, 4225.894352641147, 3586.8385922633543, 3730.1908574341423, 3975.3241948868185, 4159.91680166395, 4195.853145139906, 3883.009832492696, 4529.9547004530195, 4663.927296721773, 4708.340832283483, 4811.975940120277, 5613.719314034363, 5640.915386269209, 5647.961707379243, 5660.037793575102, 5687.51656109227, 1196.7785959597502, 1313.3498918035634, 1330.6872884872048, 1353.6345186799508, 1377.0223141569597, 864.9135086491301, 944.7071407863584, 973.4889183178888, 975.3808677595158, 988.4415305352596, 4647.86056418308, 4759.524047595282, 4945.8021679132435, 4958.677685950402, 4961.6526843121355, 4925.7783399747095, 5340.65011394985, 5450.91823622646, 5464.453554644504, 5503.477169668875, 4581.0, 4827.348307978448, 4974.328465657162, 4986.326845875833, 5028.094942910311, 2470.8146888983397, 2614.914810353682, 2635.667434320602, 2645.949389223329, 2910.54886492594, 4996.975015124901, 5088.643794934319, 5719.742611582489, 5777.740001699934, 5903.527717782567, 5054.762384856401, 5394.352677678704, 5494.2582751328055, 5663.292088488939, 5743.587693168879, 4939.172506172739, 5002.99940011999, 5148.485151484747, 5709.314882214161, 5776.56294504769, 5003.724050367278, 5222.328854766438, 5225.066725311659, 5358.544523715372, 5537.390887002507, 4559.908801823879, 4772.403449568804, 5265.6050796189675, 5515.538382328606, 5773.393793651774, 5033.521815427529, 5201.869953251199, 5767.952376906305, 5862.3844496328975, 5936.5844390892225, 3294.40700673881, 3870.148567315186, 3905.668018218483, 3911.8239679213934, 3924.7448915820382, 4877.9024419512125, 5431.2667789847865, 5638.2670252866865, 5697.287839020122, 5747.339056008613, 4900.975495122501, 5173.896522069463, 5445.542080778655, 5454.645448045864, 5488.353493951879, 5138.588912887035, 5325.707086110301, 5570.108782594765, 5586.441355864381, 5642.489092354027, 5310.2831117798605, 5388.892222155457, 5483.8080667176455, 5483.890322193613, 5516.359841619306, 4832.130216561053, 5066.341375621146, 5568.610197286151, 5604.663720176803, 5605.859853503532, 4514.57111574413, 4816.566509014206, 5378.682222855269, 5444.02007638613, 5462.453754624506], "value": 4102.555219977916, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.1786945013231, 764.816444053427, 814.2630919018288, 821.6507984106761, 838.4256784102892, 15327.233638318081, 15879.603009924758, 15910.204489775493, 15920.04479731214, 15932.522024339274, 14482.0, 14560.563183104498, 14565.563033108998, 14575.979681422305, 14581.489647862327, 682.4779044031295, 700.8773464643667, 701.7614011236178, 844.995775021124, 852.7569642651846, 872.5026734761157, 878.0648609231148, 891.933105017123, 892.0232345581578, 892.5492626223748, 934.9813003739955, 941.2187884056251, 945.5886689290146, 945.8250223708636, 946.4557879219442, 723.8660847743184, 745.2845268542206, 939.8449255872739, 940.8118376324728, 940.9905900940971, 550.1664977559001, 551.3301338873288, 553.6456667732655, 670.8457054877357, 693.542262107007, 14723.779143312859, 14963.925180374028, 15056.096634201873, 15060.54818355451, 15064.924675376553, 13109.47562097515, 13522.526711565146, 13956.651083722887, 13966.790498142536, 14175.078619889675, 12110.273383596925, 12387.566435174725, 12429.440675169639, 12431.502739890393, 12628.621141365773, 4315.741055536678, 4339.284018137004, 4348.326009468537, 4450.198964186445, 4477.888052798674, 90493.09506904913, 102178.48910755473, 102295.0, 103681.0, 106914.0, 10517.9474102629, 10674.78650426987, 10948.233623646269, 10953.671389858317, 11078.723031924104, 20928.581428371348, 21338.35984920455, 21784.0, 21786.782132178792, 21907.67138492924, 7535.171131175566, 7596.316331530189, 7596.354309883612, 7869.803254918673, 7882.5270483771155, 4212.0, 4220.53574106848, 4293.291606884892, 4332.870013899588, 4413.117376524644, 3436.055084851686, 3489.371913055674, 3536.0806190390686, 3536.0806190390686, 3538.929221415609, 635.8823617630753, 644.7227692092446, 646.053531576243, 659.8944168932948, 659.9637019963852, 641.666333506574, 646.5732616473106, 647.2815175155573, 650.8503044299742, 669.7990602819135, 9736.95131524338, 9740.902590974, 9775.90224097764, 9835.45904975219, 10055.849162262572, 627.9560430769893, 641.2785616181795, 641.323403808986, 668.2815972829228, 688.2188715807495, 4630.212863813093, 4757.643176761757, 4767.69010014348, 4770.785314660849, 4795.640326975421, 84893.72659410114, 85045.14954850372, 85545.0, 85735.57132214424, 87602.0, 88040.11959880448, 89604.0, 90178.0, 90637.0, 93001.0699893006, 77216.61391693006, 78308.60845695736, 79335.0, 79762.0, 81149.59425202836, 9190.862137068083, 9226.907730922605, 9382.624695012122, 9383.108604682544, 9405.905940940504, 50560.0, 50613.74693126658, 51035.2344714837, 51112.48887511077, 52976.47023529869, 58550.12174817296, 61257.69371153116, 61355.386446136756, 64254.39364015937, 69610.0, 13131.277779721824, 13158.065725352993, 13331.933340333624, 13356.065075444625, 13477.663058423617, 13696.52062177819, 13830.446782128602, 14904.329305181076, 14925.626859328604, 15086.396544138111, 13169.407376667881, 13539.59381218565, 13908.443662253396, 13915.860841391861, 14168.0, 10455.477226138815, 13688.863111368759, 13764.518241861888, 13822.101563398346, 14030.719385612434, 13036.543720969721, 13182.868171318163, 13435.0, 13447.193168409927, 14018.859811401755, 10745.24783265164, 10810.18923580719, 11045.0, 11370.431478426211, 11495.252808567417, 8917.687880924395, 10715.303505272132, 10843.945780271048, 10846.240763146505, 10919.290246133976], "value": 7438.904313289345, "unit": "score"}], "name": "dromaeo_css", "value": 7414.496454240854, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 114.0, 114.0, 115.0, 115.0, 114.0, 115.0, 114.0, 115.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 139.0, 105.0, 142.0, 111.0, 109.0, 107.0, 108.0, 129.0, 108.0], "value": 122.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 183.0, 182.0, 183.0, 184.0, 183.0, 183.0, 186.0, 181.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 74.0, 112.0, 86.0, 57.0, 58.0, 58.0, 74.0, 59.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 86.0, 69.0, 68.0, 71.0, 81.0, 67.0, 68.0, 81.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 90.0, 88.0, 91.0, 88.0, 91.0, 88.0, 90.0, 86.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 99.0, 99.0, 98.0, 98.0, 100.0, 98.0, 97.0, 98.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 86.0, 85.0, 87.0, 88.0, 86.0, 85.0, 87.0], "value": 86.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 55.0, 54.0, 55.0, 58.0, 57.0, 57.0, 57.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 77.0, 69.0, 73.0, 73.0, 69.0, 69.0, 69.0, 70.0, 69.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 83.0, 84.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 138.0, 139.0, 135.0, 141.0, 154.0, 143.0, 182.0, 155.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 168.0, 362.0, 362.0, 356.0, 365.0, 225.0, 166.0, 178.0], "value": 251.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 65.0, 66.0, 64.0, 62.0, 71.0, 79.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1517.2, "alertThreshold": 2.0}]} 12:06:40 INFO - Return code: 0 12:06:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:06:40 ERROR - # TBPL SUCCESS # 12:06:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:06:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:06:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:06:40 INFO - 2017-02-06 12:06:40.507 screenresolution[1652:9645] starting screenresolution argv=screenresolution get 12:06:40 INFO - 2017-02-06 12:06:40.510 screenresolution[1652:9645] Display 0: 1600x1200x32@60 12:06:40 INFO - 2017-02-06 12:06:40.516 screenresolution[1653:9648] starting screenresolution argv=screenresolution list 12:06:40 INFO - Available Modes on Display 0 12:06:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:06:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:06:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:06:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:06:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:06:40 INFO - Intel Iris: 12:06:40 INFO - Chipset Model: Intel Iris 12:06:40 INFO - Type: GPU 12:06:40 INFO - Bus: Built-In 12:06:40 INFO - VRAM (Dynamic, Max): 1536 MB 12:06:40 INFO - Vendor: Intel (0x8086) 12:06:40 INFO - Device ID: 0x0a2e 12:06:40 INFO - Revision ID: 0x0009 12:06:40 INFO - Displays: 12:06:40 INFO - Display: 12:06:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:06:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:06:40 INFO - Display Serial Number: 1600x1200 60 12:06:40 INFO - Main Display: Yes 12:06:40 INFO - Mirror: Off 12:06:40 INFO - Online: Yes 12:06:40 INFO - Rotation: Supported 12:06:40 INFO - Return code: 0 12:06:40 INFO - Running post-action listener: _resource_record_post_action 12:06:40 INFO - [mozharness: 2017-02-06 20:06:40.754417Z] Finished run-tests step (success) 12:06:40 INFO - Running post-run listener: _resource_record_post_run 12:06:40 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 39195648; Write bytes: 507141632; Read time: 381; Write time: 1583 12:06:40 INFO - TinderboxPrint: CPU usage
53.6% 12:06:40 INFO - TinderboxPrint: I/O read bytes / time
39,195,648 / 381 12:06:40 INFO - TinderboxPrint: I/O write bytes / time
507,141,632 / 1,583 12:06:40 INFO - TinderboxPrint: CPU idle
2,226.2 (46.4%) 12:06:40 INFO - TinderboxPrint: CPU system
959.6 (20.0%) 12:06:40 INFO - TinderboxPrint: CPU user
1,616.0 (33.7%) 12:06:40 INFO - TinderboxPrint: Swap in / out
246,333,440 / 0 12:06:40 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203278848; Write bytes: 200202240; Read time: 15697; Write time: 418 12:06:40 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 37217280; Write bytes: 296252928; Read time: 360; Write time: 1131 12:06:41 INFO - Running post-run listener: _upload_blobber_files 12:06:41 INFO - Blob upload gear active. 12:06:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:06:41 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 12:06:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:06:41 INFO - (blobuploader) - INFO - Open directory for files ... 12:06:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:06:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:06:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:06:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:06:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:06:42 INFO - (blobuploader) - INFO - Done attempting. 12:06:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:06:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:06:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:06:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:06:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:06:43 INFO - (blobuploader) - INFO - Done attempting. 12:06:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:06:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:06:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:06:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:06:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:06:44 INFO - (blobuploader) - INFO - Done attempting. 12:06:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:06:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:06:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:06:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:06:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:06:47 INFO - (blobuploader) - INFO - Done attempting. 12:06:47 INFO - (blobuploader) - INFO - Iteration through files over. 12:06:47 INFO - Return code: 0 12:06:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:06:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:06:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/162e30c07e9b4429f1e91b61e962ccbebf814f03f0fa2ec8bf15b58501d56d03290a2e76b16c82ddfda7eb86686b825a789a21996f148749bc6982b78f5c1459", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab43769d1e9721f4fe5eeffe84459a5f2e0672e5d98ec32752bff628725915918bc02f32be415abfc1bd2229362c2cb511fdc3ab32fa59948bd873f565d38f2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1e6c793994fcbbd7e8a2ed664a830c1bf878c264959421197ee3fdbb60ffe4c4ce18fc9ecdf376aee8a21188fc23230fe17c9a131a592c54ae12d4fa9f30e21", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acf899734ecf6ab14a2bc9a1f6e59d7fd75bf24506e70a29d84e5f0a1d472d7702dde7e2201201933a60e414a55854c28ef797457a16d9303066c58846ec3b50"} 12:06:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:06:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:06:47 INFO - Contents: 12:06:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/162e30c07e9b4429f1e91b61e962ccbebf814f03f0fa2ec8bf15b58501d56d03290a2e76b16c82ddfda7eb86686b825a789a21996f148749bc6982b78f5c1459", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab43769d1e9721f4fe5eeffe84459a5f2e0672e5d98ec32752bff628725915918bc02f32be415abfc1bd2229362c2cb511fdc3ab32fa59948bd873f565d38f2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1e6c793994fcbbd7e8a2ed664a830c1bf878c264959421197ee3fdbb60ffe4c4ce18fc9ecdf376aee8a21188fc23230fe17c9a131a592c54ae12d4fa9f30e21", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acf899734ecf6ab14a2bc9a1f6e59d7fd75bf24506e70a29d84e5f0a1d472d7702dde7e2201201933a60e414a55854c28ef797457a16d9303066c58846ec3b50"} 12:06:47 INFO - Running post-run listener: copy_logs_to_upload_dir 12:06:47 INFO - Copying logs to upload dir... 12:06:47 INFO - mkdir: /builds/slave/test/build/upload/logs 12:06:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.493534 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-02-06 12:06:47.981641) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 12:06:47.986523) ========= 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.1fwzRUltnc/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.LXSlnzDlO3/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/162e30c07e9b4429f1e91b61e962ccbebf814f03f0fa2ec8bf15b58501d56d03290a2e76b16c82ddfda7eb86686b825a789a21996f148749bc6982b78f5c1459", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab43769d1e9721f4fe5eeffe84459a5f2e0672e5d98ec32752bff628725915918bc02f32be415abfc1bd2229362c2cb511fdc3ab32fa59948bd873f565d38f2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1e6c793994fcbbd7e8a2ed664a830c1bf878c264959421197ee3fdbb60ffe4c4ce18fc9ecdf376aee8a21188fc23230fe17c9a131a592c54ae12d4fa9f30e21", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acf899734ecf6ab14a2bc9a1f6e59d7fd75bf24506e70a29d84e5f0a1d472d7702dde7e2201201933a60e414a55854c28ef797457a16d9303066c58846ec3b50"} build_url:https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010834 build_url: 'https://queue.taskcluster.net/v1/task/HSKNOoL6T-WuNCrPL8HZQw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/162e30c07e9b4429f1e91b61e962ccbebf814f03f0fa2ec8bf15b58501d56d03290a2e76b16c82ddfda7eb86686b825a789a21996f148749bc6982b78f5c1459", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab43769d1e9721f4fe5eeffe84459a5f2e0672e5d98ec32752bff628725915918bc02f32be415abfc1bd2229362c2cb511fdc3ab32fa59948bd873f565d38f2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1e6c793994fcbbd7e8a2ed664a830c1bf878c264959421197ee3fdbb60ffe4c4ce18fc9ecdf376aee8a21188fc23230fe17c9a131a592c54ae12d4fa9f30e21", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acf899734ecf6ab14a2bc9a1f6e59d7fd75bf24506e70a29d84e5f0a1d472d7702dde7e2201201933a60e414a55854c28ef797457a16d9303066c58846ec3b50"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 12:06:48.020541) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 12:06:48.020874) ========= 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.1fwzRUltnc/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.LXSlnzDlO3/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.005068 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 12:06:48.069082) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 12:06:48.069396) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 12:06:48.069719) ========= ========= Total master_lag: 0.17 =========