builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0031 starttime: 1472797247.62 results: success (0) buildid: 20160901220445 builduid: fc3aa56c3e8f40418da4f3c9634e1f8b revision: 54394c32a1068afdbdbd78faa4c41d2591d467bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:47.616509) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:47.618026) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:47.618315) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:47.641740) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:47.642077) ========= 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.hVekxdkAUn/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.w3vt70BBuk/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 --2016-09-01 23:20:47-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-09-01 23:20:47 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142216 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:47.806896) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:47.807237) ========= 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.hVekxdkAUn/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.w3vt70BBuk/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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.021234 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:47.855670) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 23:20:47.856136) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 54394c32a1068afdbdbd78faa4c41d2591d467bc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 54394c32a1068afdbdbd78faa4c41d2591d467bc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hVekxdkAUn/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.w3vt70BBuk/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 2016-09-01 23:20:47,930 truncating revision to first 12 chars 2016-09-01 23:20:47,930 Setting DEBUG logging. 2016-09-01 23:20:47,930 attempt 1/10 2016-09-01 23:20:47,930 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/54394c32a106?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 23:20:49,027 unpacking tar archive at: autoland-54394c32a106/testing/mozharness/ program finished with exit code 0 elapsedTime=1.342963 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 23:20:49.221590) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:49.221926) ========= script_repo_revision: 54394c32a1068afdbdbd78faa4c41d2591d467bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:49.222321) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:49.222612) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 23:20:49.241126) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 23:20:49.241525) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hVekxdkAUn/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.w3vt70BBuk/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 23:20:49 INFO - MultiFileLogger online at 20160901 23:20:49 in /builds/slave/test 23:20:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 23:20:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:20:49 INFO - {'append_to_log': False, 23:20:49 INFO - 'base_work_dir': '/builds/slave/test', 23:20:49 INFO - 'blob_upload_branch': 'Autoland', 23:20:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:20:49 INFO - 'branch': 'Autoland', 23:20:49 INFO - 'buildbot_json_path': 'buildprops.json', 23:20:49 INFO - 'config_files': ('talos/mac_config.py',), 23:20:49 INFO - 'default_actions': ('clobber', 23:20:49 INFO - 'read-buildbot-config', 23:20:49 INFO - 'download-and-extract', 23:20:49 INFO - 'populate-webroot', 23:20:49 INFO - 'create-virtualenv', 23:20:49 INFO - 'install', 23:20:49 INFO - 'run-tests'), 23:20:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:20:49 INFO - 'download_minidump_stackwalk': True, 23:20:49 INFO - 'download_symbols': 'ondemand', 23:20:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:20:49 INFO - 'tooltool.py': '/tools/tooltool.py', 23:20:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:20:49 INFO - '/tools/misc-python/virtualenv.py')}, 23:20:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:20:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:20:49 INFO - 'installer_path': 'installer.exe', 23:20:49 INFO - 'log_level': 'info', 23:20:49 INFO - 'log_name': 'talos', 23:20:49 INFO - 'log_to_console': True, 23:20:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:20:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:20:49 INFO - 'opt_config_files': (), 23:20:49 INFO - 'pip_index': False, 23:20:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:20:49 INFO - 'cmd': ('bash', 23:20:49 INFO - '-c', 23:20:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:20:49 INFO - 'enabled': True, 23:20:49 INFO - 'halt_on_failure': False, 23:20:49 INFO - 'name': 'check_screen_resolution'},), 23:20:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:20:49 INFO - 'cmd': ('bash', 23:20:49 INFO - '-c', 23:20:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:20:49 INFO - 'enabled': True, 23:20:49 INFO - 'halt_on_failure': False, 23:20:49 INFO - 'name': 'check_screen_resolution'},), 23:20:49 INFO - 'run_cmd_checks_enabled': True, 23:20:49 INFO - 'sps_profile': False, 23:20:49 INFO - 'sps_profile_interval': 0, 23:20:49 INFO - 'suite': 'dromaeojs', 23:20:49 INFO - 'system_bits': '32', 23:20:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:20:49 INFO - 'title': 't-yosemite-r7-0031', 23:20:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:20:49 INFO - 'use_talos_json': True, 23:20:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:20:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:20:49 INFO - 'work_dir': 'build'} 23:20:49 INFO - [mozharness: 2016-09-02 06:20:49.433616Z] Running clobber step. 23:20:49 INFO - Running pre-action listener: _resource_record_pre_action 23:20:49 INFO - Running main action method: clobber 23:20:49 INFO - rmtree: /builds/slave/test/build 23:20:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:20:50 INFO - Running post-action listener: _resource_record_post_action 23:20:50 INFO - [mozharness: 2016-09-02 06:20:50.893795Z] Finished clobber step (success) 23:20:50 INFO - [mozharness: 2016-09-02 06:20:50.894099Z] Running read-buildbot-config step. 23:20:50 INFO - Running pre-action listener: _resource_record_pre_action 23:20:50 INFO - Running main action method: read_buildbot_config 23:20:50 INFO - Using buildbot properties: 23:20:50 INFO - { 23:20:50 INFO - "project": "", 23:20:50 INFO - "product": "firefox", 23:20:50 INFO - "script_repo_revision": "production", 23:20:50 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 23:20:50 INFO - "repository": "", 23:20:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 23:20:50 INFO - "buildid": "20160901220445", 23:20:50 INFO - "pgo_build": "False", 23:20:50 INFO - "basedir": "/builds/slave/test", 23:20:50 INFO - "buildnumber": 213, 23:20:50 INFO - "slavename": "t-yosemite-r7-0031", 23:20:50 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 23:20:50 INFO - "platform": "yosemite_r7", 23:20:50 INFO - "scheduler": "tests-autoland-macosx64-talos", 23:20:50 INFO - "branch": "autoland", 23:20:50 INFO - "repo_path": "integration/autoland", 23:20:50 INFO - "revision": "54394c32a1068afdbdbd78faa4c41d2591d467bc", 23:20:50 INFO - "stage_platform": "macosx64", 23:20:50 INFO - "builduid": "fc3aa56c3e8f40418da4f3c9634e1f8b", 23:20:50 INFO - "slavebuilddir": "test" 23:20:50 INFO - } 23:20:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 23:20:50 INFO - Running post-action listener: _resource_record_post_action 23:20:50 INFO - [mozharness: 2016-09-02 06:20:50.899985Z] Finished read-buildbot-config step (success) 23:20:50 INFO - [mozharness: 2016-09-02 06:20:50.900198Z] Running download-and-extract step. 23:20:50 INFO - Running pre-action listener: _resource_record_pre_action 23:20:50 INFO - Running main action method: download_and_extract 23:20:50 INFO - mkdir: /builds/slave/test/build/tests 23:20:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:20:50 INFO - trying https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 23:20:50 INFO - Downloading https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 23:20:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 23:20:52 INFO - Downloaded 1199 bytes. 23:20:52 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 23:20:52 INFO - Using the following test package requirements: 23:20:52 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:20:52 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:20:52 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 23:20:52 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:20:52 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 23:20:52 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:20:52 INFO - u'jsshell-mac.zip'], 23:20:52 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:20:52 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 23:20:52 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:20:52 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:20:52 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 23:20:52 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:20:52 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 23:20:52 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:20:52 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 23:20:52 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:20:52 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 23:20:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:20:52 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')]} 23:20:52 INFO - trying https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:20:52 INFO - Downloading https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:20:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:20:53 INFO - Downloaded 16399342 bytes. 23:20:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 23:21:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:21: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')]} 23:21:00 INFO - trying https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:21:00 INFO - Downloading https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:21:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:21:01 INFO - Downloaded 16399342 bytes. 23:21:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 23:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:21:07 INFO - trying https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 23:21:07 INFO - Downloading https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 23:21:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:21:08 INFO - Downloaded 14413998 bytes. 23:21:08 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 23:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:21:09 INFO - trying https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:21:09 INFO - Downloading https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 23:21:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 23:21:12 INFO - Downloaded 111602212 bytes. 23:21:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:21:12 INFO - mkdir: /builds/slave/test/properties 23:21:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:21:12 INFO - Writing to file /builds/slave/test/properties/build_url 23:21:12 INFO - Contents: 23:21:12 INFO - build_url:https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:21:12 INFO - Running post-action listener: _resource_record_post_action 23:21:12 INFO - Running post-action listener: set_extra_try_arguments 23:21:12 INFO - [mozharness: 2016-09-02 06:21:12.849929Z] Finished download-and-extract step (success) 23:21:12 INFO - [mozharness: 2016-09-02 06:21:12.850028Z] Running populate-webroot step. 23:21:12 INFO - Running pre-action listener: _resource_record_pre_action 23:21:12 INFO - Running main action method: populate_webroot 23:21:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:21:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:21:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:21:12 INFO - '--tppagecycles', 23:21:12 INFO - '7'], 23:21:12 INFO - 'tests': ['tsvgm']}}, 23:21:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:21:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:21:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:21:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:21:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:21:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:21:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:12 INFO - 'talos_options': ['--disable-e10s'], 23:21:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:21:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:21:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:12 INFO - 'talos_options': ['--disable-e10s'], 23:21:12 INFO - 'tests': ['damp', 'tps']}, 23:21:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:12 INFO - 'tests': ['damp', 'tps']}, 23:21:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:21:12 INFO - 'tests': ['dromaeo_dom']}, 23:21:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:21:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:21:12 INFO - 'tests': ['basic_compositor_video']}, 23:21:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:21:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:21:12 INFO - 'tests': ['a11yr', 23:21:12 INFO - 'ts_paint', 23:21:12 INFO - 'tpaint', 23:21:12 INFO - 'sessionrestore', 23:21:12 INFO - 'sessionrestore_no_auto_restore', 23:21:12 INFO - 'tabpaint']}, 23:21:12 INFO - 'other-e10s': {'tests': ['a11yr', 23:21:12 INFO - 'ts_paint', 23:21:12 INFO - 'tpaint', 23:21:12 INFO - 'sessionrestore', 23:21:12 INFO - 'sessionrestore_no_auto_restore', 23:21:12 INFO - 'tabpaint']}, 23:21:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:21:12 INFO - 'ts_paint', 23:21:12 INFO - 'tpaint', 23:21:12 INFO - 'sessionrestore', 23:21:12 INFO - 'sessionrestore_no_auto_restore', 23:21:12 INFO - 'tabpaint']}, 23:21:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:21:12 INFO - 'ts_paint', 23:21:12 INFO - 'tpaint', 23:21:12 INFO - 'sessionrestore', 23:21:12 INFO - 'sessionrestore_no_auto_restore', 23:21:12 INFO - 'tabpaint']}, 23:21:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:21:12 INFO - 'tests': ['a11yr', 23:21:12 INFO - 'ts_paint', 23:21:12 INFO - 'tpaint', 23:21:12 INFO - 'sessionrestore', 23:21:12 INFO - 'sessionrestore_no_auto_restore', 23:21:12 INFO - 'tabpaint']}, 23:21:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:21:12 INFO - 'tests': ['a11yr', 23:21:12 INFO - 'ts_paint', 23:21:12 INFO - 'tpaint', 23:21:12 INFO - 'sessionrestore', 23:21:12 INFO - 'sessionrestore_no_auto_restore', 23:21:12 INFO - 'tabpaint']}, 23:21:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:21:12 INFO - 'tests': ['tsvgx', 23:21:12 INFO - 'tsvgr_opacity', 23:21:12 INFO - 'tart', 23:21:12 INFO - 'tscrollx', 23:21:12 INFO - 'cart']}, 23:21:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:21:12 INFO - 'tsvgr_opacity', 23:21:12 INFO - 'tart', 23:21:12 INFO - 'tscrollx', 23:21:12 INFO - 'cart']}, 23:21:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:12 INFO - 'talos_options': ['--disable-e10s'], 23:21:12 INFO - 'tests': ['tp5o']}, 23:21:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:12 INFO - 'tests': ['tp5o']}, 23:21:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:12 INFO - 'talos_options': ['--disable-e10s', 23:21:12 INFO - '--xperf_path', 23:21:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:21:12 INFO - 'tests': ['tp5n']}, 23:21:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:12 INFO - 'talos_options': ['--xperf_path', 23:21:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:21:12 INFO - 'tests': ['tp5n']}}, 23:21:12 INFO - 'talos.zip': {'path': '', 23:21:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:21:12 INFO - Running post-action listener: _resource_record_post_action 23:21:12 INFO - [mozharness: 2016-09-02 06:21:12.868933Z] Finished populate-webroot step (success) 23:21:12 INFO - [mozharness: 2016-09-02 06:21:12.869074Z] Running create-virtualenv step. 23:21:12 INFO - Running pre-action listener: _resource_record_pre_action 23:21:12 INFO - Running main action method: create_virtualenv 23:21:12 INFO - Creating virtualenv /builds/slave/test/build/venv 23:21:12 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 23:21:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:21:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:21:12 INFO - Using real prefix '/tools/python27' 23:21:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:21:13 INFO - Installing distribute.............................................................................................................................................................................................done. 23:21:16 INFO - Installing pip.................done. 23:21:16 INFO - Return code: 0 23:21:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:21:16 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')]} 23:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:16 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')]} 23:21:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:16 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 0x105489a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0d0d87220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053edd30>, '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 0x105152db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053e5b20>, '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.hVekxdkAUn/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.w3vt70BBuk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 23:21:16 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 23:21:16 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 23:21:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVekxdkAUn/Render', 23:21:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:21:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:21:16 INFO - 'HOME': '/Users/cltbld', 23:21:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:21:16 INFO - 'LOGNAME': 'cltbld', 23:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:16 INFO - 'MOZ_NO_REMOTE': '1', 23:21:16 INFO - 'NO_EM_RESTART': '1', 23:21:16 INFO - 'PAGER': '/bin/cat', 23:21:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:16 INFO - 'PWD': '/builds/slave/test', 23:21:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:21:16 INFO - 'SHELL': '/bin/bash', 23:21:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w3vt70BBuk/Listeners', 23:21:16 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 23:21:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:21:16 INFO - 'USER': 'cltbld', 23:21:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:21:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:16 INFO - 'XPC_FLAGS': '0x0', 23:21:16 INFO - 'XPC_SERVICE_NAME': '0', 23:21:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:21:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:16 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 23:21:16 INFO - Cleaning up... 23:21:16 INFO - Return code: 0 23:21:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:21:16 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')]} 23:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:16 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')]} 23:21:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:16 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 0x105489a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0d0d87220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053edd30>, '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 0x105152db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053e5b20>, '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.hVekxdkAUn/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.w3vt70BBuk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 23:21:16 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 23:21:16 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 23:21:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVekxdkAUn/Render', 23:21:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:21:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:21:16 INFO - 'HOME': '/Users/cltbld', 23:21:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:21:16 INFO - 'LOGNAME': 'cltbld', 23:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:16 INFO - 'MOZ_NO_REMOTE': '1', 23:21:16 INFO - 'NO_EM_RESTART': '1', 23:21:16 INFO - 'PAGER': '/bin/cat', 23:21:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:16 INFO - 'PWD': '/builds/slave/test', 23:21:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:21:16 INFO - 'SHELL': '/bin/bash', 23:21:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w3vt70BBuk/Listeners', 23:21:16 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 23:21:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:21:16 INFO - 'USER': 'cltbld', 23:21:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:21:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:16 INFO - 'XPC_FLAGS': '0x0', 23:21:16 INFO - 'XPC_SERVICE_NAME': '0', 23:21:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:21:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:17 INFO - Downloading/unpacking psutil>=3.1.1 23:21:17 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 23:21:17 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 23:21:17 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 23:21:17 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 23:21:17 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 23:21:17 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 23:21:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:21:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:21:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:21:20 INFO - Installing collected packages: psutil 23:21:20 INFO - Running setup.py install for psutil 23:21:20 INFO - building 'psutil._psutil_osx' extension 23:21:20 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 23:21:21 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 23:21:21 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 23:21:21 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 23:21:21 INFO - building 'psutil._psutil_posix' extension 23:21:21 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 23:21:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:21:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:21:21 INFO - ^ 23:21:21 INFO - 1 warning generated. 23:21:21 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 23:21:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:21:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:21:21 INFO - Successfully installed psutil 23:21:21 INFO - Cleaning up... 23:21:21 INFO - Return code: 0 23:21:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:21:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:21:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105489a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0d0d87220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053edd30>, '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 0x105152db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053e5b20>, '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.hVekxdkAUn/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.w3vt70BBuk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 23:21:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:21:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVekxdkAUn/Render', 23:21:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:21:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:21:21 INFO - 'HOME': '/Users/cltbld', 23:21:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:21:21 INFO - 'LOGNAME': 'cltbld', 23:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:21 INFO - 'MOZ_NO_REMOTE': '1', 23:21:21 INFO - 'NO_EM_RESTART': '1', 23:21:21 INFO - 'PAGER': '/bin/cat', 23:21:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:21 INFO - 'PWD': '/builds/slave/test', 23:21:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:21:21 INFO - 'SHELL': '/bin/bash', 23:21:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w3vt70BBuk/Listeners', 23:21:21 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 23:21:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:21:21 INFO - 'USER': 'cltbld', 23:21:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:21:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:21 INFO - 'XPC_FLAGS': '0x0', 23:21:21 INFO - 'XPC_SERVICE_NAME': '0', 23:21:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:21:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:21: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 23:21: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 23:21: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 23:21: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 23:21: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 23:21: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 23:21:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:21:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:21:24 INFO - Installing collected packages: mozsystemmonitor 23:21:24 INFO - Running setup.py install for mozsystemmonitor 23:21:24 INFO - Successfully installed mozsystemmonitor 23:21:24 INFO - Cleaning up... 23:21:24 INFO - Return code: 0 23:21:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:21:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:24 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 0x105489a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0d0d87220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053edd30>, '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 0x105152db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053e5b20>, '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.hVekxdkAUn/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.w3vt70BBuk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 23:21:24 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 23:21:24 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 23:21:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVekxdkAUn/Render', 23:21:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:21:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:21:24 INFO - 'HOME': '/Users/cltbld', 23:21:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:21:24 INFO - 'LOGNAME': 'cltbld', 23:21:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:24 INFO - 'MOZ_NO_REMOTE': '1', 23:21:24 INFO - 'NO_EM_RESTART': '1', 23:21:24 INFO - 'PAGER': '/bin/cat', 23:21:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:21:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:24 INFO - 'PWD': '/builds/slave/test', 23:21:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:21:24 INFO - 'SHELL': '/bin/bash', 23:21:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w3vt70BBuk/Listeners', 23:21:24 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 23:21:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:21:24 INFO - 'USER': 'cltbld', 23:21:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:21:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:21:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:24 INFO - 'XPC_FLAGS': '0x0', 23:21:24 INFO - 'XPC_SERVICE_NAME': '0', 23:21:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:21:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:24 INFO - Downloading/unpacking jsonschema==2.5.1 23:21:24 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 23:21:24 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 23:21:24 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 23:21:24 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 23:21:24 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 23:21:24 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 23:21:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:21:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:21:27 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 23:21:27 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 23:21:27 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 23:21:27 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 23:21:27 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 23:21:27 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 23:21:27 INFO - Downloading functools32-3.2.3-2.tar.gz 23:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:21:27 INFO - warning: no files found matching '*.txt' 23:21:27 INFO - no previously-included directories found matching 'build' 23:21:27 INFO - no previously-included directories found matching 'dist' 23:21:27 INFO - no previously-included directories found matching '.git*' 23:21:27 INFO - Installing collected packages: jsonschema, functools32 23:21:27 INFO - Running setup.py install for functools32 23:21:27 INFO - warning: no files found matching '*.txt' 23:21:27 INFO - no previously-included directories found matching 'build' 23:21:27 INFO - no previously-included directories found matching 'dist' 23:21:27 INFO - no previously-included directories found matching '.git*' 23:21:27 INFO - Successfully installed jsonschema functools32 23:21:27 INFO - Cleaning up... 23:21:28 INFO - Return code: 0 23:21:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:21: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')]} 23:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21: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')]} 23:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:28 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 0x105489a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0d0d87220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053edd30>, '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 0x105152db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053e5b20>, '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.hVekxdkAUn/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.w3vt70BBuk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 23:21:28 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 23:21:28 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 23:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVekxdkAUn/Render', 23:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:21:28 INFO - 'HOME': '/Users/cltbld', 23:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:21:28 INFO - 'LOGNAME': 'cltbld', 23:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:28 INFO - 'MOZ_NO_REMOTE': '1', 23:21:28 INFO - 'NO_EM_RESTART': '1', 23:21:28 INFO - 'PAGER': '/bin/cat', 23:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:28 INFO - 'PWD': '/builds/slave/test', 23:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:21:28 INFO - 'SHELL': '/bin/bash', 23:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w3vt70BBuk/Listeners', 23:21:28 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 23:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:21:28 INFO - 'USER': 'cltbld', 23:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:28 INFO - 'XPC_FLAGS': '0x0', 23:21:28 INFO - 'XPC_SERVICE_NAME': '0', 23:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:21:28 INFO - Cleaning up... 23:21:28 INFO - Return code: 0 23:21:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:21: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')]} 23:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21: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')]} 23:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:28 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 0x105489a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0d0d87220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053edd30>, '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 0x105152db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053e5b20>, '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.hVekxdkAUn/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.w3vt70BBuk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 23:21:28 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 23:21:28 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 23:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVekxdkAUn/Render', 23:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:21:28 INFO - 'HOME': '/Users/cltbld', 23:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:21:28 INFO - 'LOGNAME': 'cltbld', 23:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:28 INFO - 'MOZ_NO_REMOTE': '1', 23:21:28 INFO - 'NO_EM_RESTART': '1', 23:21:28 INFO - 'PAGER': '/bin/cat', 23:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:28 INFO - 'PWD': '/builds/slave/test', 23:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:21:28 INFO - 'SHELL': '/bin/bash', 23:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w3vt70BBuk/Listeners', 23:21:28 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 23:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:21:28 INFO - 'USER': 'cltbld', 23:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:28 INFO - 'XPC_FLAGS': '0x0', 23:21:28 INFO - 'XPC_SERVICE_NAME': '0', 23:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:28 INFO - Downloading/unpacking blobuploader==1.2.4 23:21:28 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 23:21:28 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 23:21:28 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 23:21:28 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 23:21:28 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 23:21:28 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 23:21:30 INFO - Downloading blobuploader-1.2.4.tar.gz 23:21:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:21:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:21:31 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 23:21:31 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 23:21:31 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 23:21:31 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 23:21:31 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 23:21:31 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 23:21:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:21:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:21:31 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 23:21:31 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 23:21:31 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 23:21:31 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 23:21:31 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 23:21:31 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 23:21:32 INFO - Downloading docopt-0.6.1.tar.gz 23:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:21:32 INFO - Installing collected packages: blobuploader, requests, docopt 23:21:32 INFO - Running setup.py install for blobuploader 23:21:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:21:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:21:32 INFO - Running setup.py install for requests 23:21:32 INFO - Running setup.py install for docopt 23:21:33 INFO - Successfully installed blobuploader requests docopt 23:21:33 INFO - Cleaning up... 23:21:33 INFO - Return code: 0 23:21:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:21:33 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')]} 23:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:33 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')]} 23:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:33 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 0x105489a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0d0d87220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053edd30>, '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 0x105152db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053e5b20>, '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.hVekxdkAUn/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.w3vt70BBuk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 23:21:33 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 23:21:33 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 23:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVekxdkAUn/Render', 23:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:21:33 INFO - 'HOME': '/Users/cltbld', 23:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:21:33 INFO - 'LOGNAME': 'cltbld', 23:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:33 INFO - 'MOZ_NO_REMOTE': '1', 23:21:33 INFO - 'NO_EM_RESTART': '1', 23:21:33 INFO - 'PAGER': '/bin/cat', 23:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:33 INFO - 'PWD': '/builds/slave/test', 23:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:21:33 INFO - 'SHELL': '/bin/bash', 23:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w3vt70BBuk/Listeners', 23:21:33 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 23:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:21:33 INFO - 'USER': 'cltbld', 23:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:33 INFO - 'XPC_FLAGS': '0x0', 23:21:33 INFO - 'XPC_SERVICE_NAME': '0', 23:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:21:33 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-0sq81w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:21:33 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-E3e4hL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:21:33 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-7VmE5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:21:33 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-QMYJMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:21:33 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-u17NdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:21:34 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-BOxWIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:21:34 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-vgcSwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:21:34 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-uV5adk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:21:34 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-ztdgc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:21:34 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-5ibWcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:21:34 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-WR7KNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:21:34 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-ntCiEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:21:34 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-JwoMA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:21:34 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-ZIEuWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:21:34 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-w1svmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:21:35 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-338E12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:21:35 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-bbDVMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:21:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:21:35 INFO - Running setup.py install for manifestparser 23:21:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:21:35 INFO - Running setup.py install for mozcrash 23:21:35 INFO - Running setup.py install for mozdebug 23:21:35 INFO - Running setup.py install for mozdevice 23:21:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:21:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:21:35 INFO - Running setup.py install for mozfile 23:21:36 INFO - Running setup.py install for mozhttpd 23:21:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:21:36 INFO - Running setup.py install for mozinfo 23:21:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:21:36 INFO - Running setup.py install for mozInstall 23:21:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:21:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:21:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:21:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:21:36 INFO - Running setup.py install for mozleak 23:21:36 INFO - Running setup.py install for mozlog 23:21:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:21:36 INFO - Running setup.py install for moznetwork 23:21:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:21:36 INFO - Running setup.py install for mozprocess 23:21:37 INFO - Running setup.py install for mozprofile 23:21:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:21:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:21:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:21:37 INFO - Running setup.py install for mozrunner 23:21:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:21:37 INFO - Running setup.py install for mozscreenshot 23:21:37 INFO - Running setup.py install for moztest 23:21:37 INFO - Running setup.py install for mozversion 23:21:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:21:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:21:37 INFO - Cleaning up... 23:21:37 INFO - Return code: 0 23:21:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:21: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')]} 23:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21: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')]} 23:21:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:37 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 0x105489a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0d0d87220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053edd30>, '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 0x105152db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053e5b20>, '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.hVekxdkAUn/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.w3vt70BBuk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 23:21:37 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 23:21:37 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 23:21:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVekxdkAUn/Render', 23:21:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:21:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:21:37 INFO - 'HOME': '/Users/cltbld', 23:21:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:21:37 INFO - 'LOGNAME': 'cltbld', 23:21:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:37 INFO - 'MOZ_NO_REMOTE': '1', 23:21:37 INFO - 'NO_EM_RESTART': '1', 23:21:37 INFO - 'PAGER': '/bin/cat', 23:21:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:21:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:37 INFO - 'PWD': '/builds/slave/test', 23:21:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:21:37 INFO - 'SHELL': '/bin/bash', 23:21:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w3vt70BBuk/Listeners', 23:21:37 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 23:21:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:21:37 INFO - 'USER': 'cltbld', 23:21:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:21:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:21:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:37 INFO - 'XPC_FLAGS': '0x0', 23:21:37 INFO - 'XPC_SERVICE_NAME': '0', 23:21:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:21:38 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-07LxeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:21:38 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)) 23:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:21:38 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Z2TwRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 23:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:21:38 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-0wiJAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:21:38 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)) 23:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:21:38 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-3mRse1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 23:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:21:38 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Wj0lyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:21:38 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)) 23:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:21:38 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Fd6AaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:21:38 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)) 23:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:21:38 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-f2bfpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:21:38 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)) 23:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:21:38 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-XKIYow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:21:38 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)) 23:21:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:21:38 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-jqARB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:21:39 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)) 23:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:21:39 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-uyyN3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 23:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:21:39 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-Aj3QWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:21:39 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)) 23:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:21:39 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-gMVIsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 23:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:21:39 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-7Tbpqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:21:39 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)) 23:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:21:39 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-pafeZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 23:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:21:39 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-RWG3Ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:21:39 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)) 23:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:21:39 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-gCkhCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 23:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:21:39 INFO - Running setup.py (path:/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/pip-EPOrWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:21:39 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)) 23:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:21:39 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)) 23:21:39 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:21:39 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:21:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:42 INFO - Downloading blessings-1.6.tar.gz 23:21:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:21:42 INFO - Installing collected packages: blessings 23:21:42 INFO - Running setup.py install for blessings 23:21:42 INFO - Successfully installed blessings 23:21:42 INFO - Cleaning up... 23:21:42 INFO - Return code: 0 23:21:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:21:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:21:43 INFO - Reading from file tmpfile_stdout 23:21:43 INFO - Current package versions: 23:21:43 INFO - blessings == 1.6 23:21:43 INFO - blobuploader == 1.2.4 23:21:43 INFO - docopt == 0.6.1 23:21:43 INFO - functools32 == 3.2.3-2 23:21:43 INFO - jsonschema == 2.5.1 23:21:43 INFO - manifestparser == 1.1 23:21:43 INFO - mozInstall == 1.12 23:21:43 INFO - mozcrash == 0.17 23:21:43 INFO - mozdebug == 0.1 23:21:43 INFO - mozdevice == 0.48 23:21:43 INFO - mozfile == 1.2 23:21:43 INFO - mozhttpd == 0.7 23:21:43 INFO - mozinfo == 0.9 23:21:43 INFO - mozleak == 0.1 23:21:43 INFO - mozlog == 3.3 23:21:43 INFO - moznetwork == 0.27 23:21:43 INFO - mozprocess == 0.23 23:21:43 INFO - mozprofile == 0.28 23:21:43 INFO - mozrunner == 6.12 23:21:43 INFO - mozscreenshot == 0.1 23:21:43 INFO - mozsystemmonitor == 0.3 23:21:43 INFO - moztest == 0.7 23:21:43 INFO - mozversion == 1.4 23:21:43 INFO - psutil == 3.1.1 23:21:43 INFO - requests == 1.2.3 23:21:43 INFO - wsgiref == 0.1.2 23:21:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:43 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 0x105489a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0d0d87220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053edd30>, '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 0x105152db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053e5b20>, '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.hVekxdkAUn/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.w3vt70BBuk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 23:21:43 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 23:21:43 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 23:21:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVekxdkAUn/Render', 23:21:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:21:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:21:43 INFO - 'HOME': '/Users/cltbld', 23:21:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:21:43 INFO - 'LOGNAME': 'cltbld', 23:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:43 INFO - 'MOZ_NO_REMOTE': '1', 23:21:43 INFO - 'NO_EM_RESTART': '1', 23:21:43 INFO - 'PAGER': '/bin/cat', 23:21:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:43 INFO - 'PWD': '/builds/slave/test', 23:21:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:21:43 INFO - 'SHELL': '/bin/bash', 23:21:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w3vt70BBuk/Listeners', 23:21:43 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 23:21:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:21:43 INFO - 'USER': 'cltbld', 23:21:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:21:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:43 INFO - 'XPC_FLAGS': '0x0', 23:21:43 INFO - 'XPC_SERVICE_NAME': '0', 23:21:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:43 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)) 23:21:43 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)) 23:21:43 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)) 23:21:43 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)) 23:21:43 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)) 23:21:43 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)) 23:21:43 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)) 23:21:43 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)) 23:21:43 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)) 23:21:43 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)) 23:21:43 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)) 23:21:43 INFO - Cleaning up... 23:21:43 INFO - Return code: 0 23:21:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105489a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10551e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0d0d87220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053edd30>, '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 0x105152db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053e5b20>, '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.hVekxdkAUn/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.w3vt70BBuk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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 23:21:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:21:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVekxdkAUn/Render', 23:21:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:21:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:21:43 INFO - 'HOME': '/Users/cltbld', 23:21:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:21:43 INFO - 'LOGNAME': 'cltbld', 23:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:43 INFO - 'MOZ_NO_REMOTE': '1', 23:21:43 INFO - 'NO_EM_RESTART': '1', 23:21:43 INFO - 'PAGER': '/bin/cat', 23:21:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:43 INFO - 'PWD': '/builds/slave/test', 23:21:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:21:43 INFO - 'SHELL': '/bin/bash', 23:21:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w3vt70BBuk/Listeners', 23:21:43 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 23:21:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:21:43 INFO - 'USER': 'cltbld', 23:21:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:21:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:43 INFO - 'XPC_FLAGS': '0x0', 23:21:43 INFO - 'XPC_SERVICE_NAME': '0', 23:21:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:21:43 INFO - Cleaning up... 23:21:43 INFO - Return code: 0 23:21:43 INFO - Running post-action listener: _resource_record_post_action 23:21:43 INFO - Running post-action listener: _start_resource_monitoring 23:21:43 INFO - Starting resource monitoring. 23:21:43 INFO - [mozharness: 2016-09-02 06:21:43.642347Z] Finished create-virtualenv step (success) 23:21:43 INFO - [mozharness: 2016-09-02 06:21:43.643352Z] Running install step. 23:21:43 INFO - Running pre-action listener: _resource_record_pre_action 23:21:43 INFO - Running main action method: install 23:21:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:21:43 INFO - Reading from file tmpfile_stdout 23:21:43 INFO - Detecting whether we're running mozinstall >=1.0... 23:21:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:21:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:21:44 INFO - Reading from file tmpfile_stdout 23:21:44 INFO - Output received: 23:21:44 INFO - Usage: mozinstall [options] installer 23:21:44 INFO - Options: 23:21:44 INFO - -h, --help show this help message and exit 23:21:44 INFO - -d DEST, --destination=DEST 23:21:44 INFO - Directory to install application into. [default: 23:21:44 INFO - "/builds/slave/test"] 23:21:44 INFO - --app=APP Application being installed. [default: firefox] 23:21:44 INFO - mkdir: /builds/slave/test/build/application 23:21:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:22:18 INFO - Reading from file tmpfile_stdout 23:22:18 INFO - Output received: 23:22:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:22:18 INFO - Running post-action listener: _resource_record_post_action 23:22:18 INFO - [mozharness: 2016-09-02 06:22:18.289318Z] Finished install step (success) 23:22:18 INFO - [mozharness: 2016-09-02 06:22:18.289465Z] Running run-tests step. 23:22:18 INFO - Running pre-action listener: _resource_record_pre_action 23:22:18 INFO - Running main action method: run_tests 23:22:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:22:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:22:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:22:18 INFO - 2016-09-01 23:22:18.304 screenresolution[1920:14189] starting screenresolution argv=screenresolution get 23:22:18 INFO - 2016-09-01 23:22:18.306 screenresolution[1920:14189] Display 0: 1600x1200x32@60 23:22:18 INFO - 2016-09-01 23:22:18.313 screenresolution[1921:14191] starting screenresolution argv=screenresolution list 23:22:18 INFO - Available Modes on Display 0 23:22:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:22:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:22:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:22:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:22:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:22:18 INFO - Intel Iris: 23:22:18 INFO - Chipset Model: Intel Iris 23:22:18 INFO - Type: GPU 23:22:18 INFO - Bus: Built-In 23:22:18 INFO - VRAM (Dynamic, Max): 1536 MB 23:22:18 INFO - Vendor: Intel (0x8086) 23:22:18 INFO - Device ID: 0x0a2e 23:22:18 INFO - Revision ID: 0x0009 23:22:18 INFO - Displays: 23:22:18 INFO - Display: 23:22:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:22:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:22:18 INFO - Display Serial Number: 1600x1200 60 23:22:18 INFO - Main Display: Yes 23:22:18 INFO - Mirror: Off 23:22:18 INFO - Online: Yes 23:22:18 INFO - Rotation: Supported 23:22:18 INFO - Return code: 0 23:22:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:22:18 INFO - Python 2.7.3 23:22:18 INFO - Return code: 0 23:22:18 INFO - grabbing minidump binary from tooltool 23:22:18 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')]} 23:22:18 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 0x1053edd30>, '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 0x105152db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053e5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:22:18 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 23:22:18 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 23:22:18 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 23:22:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:22:18 INFO - Return code: 0 23:22:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:22:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:22:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:22:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:22:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:22:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0031', '--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 23:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0031 --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 23:22:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVekxdkAUn/Render', 23:22:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:22:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:22:18 INFO - 'HOME': '/Users/cltbld', 23:22:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:22:18 INFO - 'LOGNAME': 'cltbld', 23:22:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:22:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:22:18 INFO - 'MOZ_NO_REMOTE': '1', 23:22:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:22:18 INFO - 'NO_EM_RESTART': '1', 23:22:18 INFO - 'PAGER': '/bin/cat', 23:22:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:22:18 INFO - 'PWD': '/builds/slave/test', 23:22:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:22:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:22:18 INFO - 'SHELL': '/bin/bash', 23:22:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w3vt70BBuk/Listeners', 23:22:18 INFO - 'TMPDIR': '/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/', 23:22:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:22:18 INFO - 'USER': 'cltbld', 23:22:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:22:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:22:18 INFO - 'XPC_FLAGS': '0x0', 23:22:18 INFO - 'XPC_SERVICE_NAME': '0', 23:22:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:22:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0031', '--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 23:22:18 INFO - mozversion application_buildid: 20160901220445 23:22:18 INFO - mozversion application_changeset: 54394c32a1068afdbdbd78faa4c41d2591d467bc 23:22:18 INFO - mozversion application_display_name: Nightly 23:22:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:22:18 INFO - mozversion application_name: Firefox 23:22:18 INFO - mozversion application_remotingname: firefox 23:22:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 23:22:18 INFO - mozversion application_vendor: Mozilla 23:22:18 INFO - mozversion application_version: 51.0a1 23:22:18 INFO - mozversion platform_buildid: 20160901220445 23:22:18 INFO - mozversion platform_changeset: 54394c32a1068afdbdbd78faa4c41d2591d467bc 23:22:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 23:22:18 INFO - mozversion platform_version: 51.0a1 23:22:18 INFO - using testdate: 1472797338 23:22:18 INFO - actual date: 1472797338 23:22:18 INFO - starting webserver on 'localhost:52319' 23:22:18 INFO - SUITE-START | Running 2 tests 23:22:18 INFO - TEST-START | dromaeo_css 23:22:18 INFO - operating with platform_type : mac_ 23:22:18 INFO - Initialising browser for dromaeo_css test... 23:22:18 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmppJ9yXL/profile http://localhost:52319/getInfo.html) 23:22:21 INFO - PROCESS | 1931 | __metrics Screen width/height:1600/1200 23:22:21 INFO - PROCESS | 1931 | colorDepth:24 23:22:21 INFO - PROCESS | 1931 | Browser inner width/height: 1024/689 23:22:21 INFO - PROCESS | 1931 | __metrics 23:22:21 INFO - PROCESS | 1931 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:22:21 INFO - PROCESS | 1931 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:22:21 INFO - TEST-INFO | 1931: exit 0 23:22:21 INFO - Browser initialized. 23:22:21 INFO - Running cycle 1/1 for dromaeo_css test... 23:22:21 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmppJ9yXL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:22:33 INFO - PROCESS | 1933 | RSS: Main: 234180608 23:22:33 INFO - PROCESS | 1933 | 23:23:25 INFO - PROCESS | 1933 | 1472797405438 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 23:25:37 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52319/tests/dromaeo/cssquery-ext.html) 23:25:39 INFO - PROCESS | 1933 | RSS: Main: 447504384 23:25:39 INFO - PROCESS | 1933 | 23:28:42 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-ext.html (next: http://localhost:52319/tests/dromaeo/cssquery-jquery.html) 23:28:43 INFO - PROCESS | 1933 | RSS: Main: 372932608 23:28:43 INFO - PROCESS | 1933 | 23:31:45 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52319/tests/dromaeo/cssquery-mootools.html) 23:31:45 INFO - PROCESS | 1933 | RSS: Main: 330997760 23:31:45 INFO - PROCESS | 1933 | 23:34:48 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52319/tests/dromaeo/cssquery-prototype.html) 23:34:48 INFO - PROCESS | 1933 | RSS: Main: 345952256 23:34:48 INFO - PROCESS | 1933 | 23:37:52 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52319/tests/dromaeo/cssquery-yui.html) 23:37:53 INFO - PROCESS | 1933 | RSS: Main: 328335360 23:37:53 INFO - PROCESS | 1933 | 23:40:56 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52319/tests/dromaeo/cssquery-yui.html (next: http://localhost:52319/tests/dromaeo/cssquery-dojo.html) 23:40:56 INFO - PROCESS | 1933 | RSS: Main: 384679936 23:40:56 INFO - PROCESS | 1933 | 23:40:56 INFO - PROCESS | 1933 | __start_tp_report 23:40:56 INFO - PROCESS | 1933 | _x_x_mozilla_page_load 23:40:56 INFO - PROCESS | 1933 | _x_x_mozilla_page_load_details 23:40:56 INFO - PROCESS | 1933 | |i|pagename|runs| 23:40:56 INFO - PROCESS | 1933 | |0;dojo.html;3282.835858207089;3286.769926105173;3331.650176731445;3345.5483509726178;3394.1853955050797;5545.473180047899;5617.719114044302;5665.971670141648;5666.121751128571;5688.715564221788;5649.802256921009;5681.346645135811;5697.943020569793;5700;5704.172894930232;5151.381834179903;5192.376914770233;5200;5206.401263854667;5245.108331583621;5212.322398088262;5213.400458947212;5214.322138122039;5222.9477705223035;5235;5184.403793563751;5192.376914770214;5204.817831375903;5214.817481388152;5225.294585231002;5275.868103297429;5283.392409872837;5287.894242115136;5311.814086506954;5349.732513374316;5065.341505604285;5102.591792656579;5103.4896510349035;5197.870053248699;5203.089459344599;5652.971735141339;5666.093425052013;5683.715814209273;5698.572607054488;5779.971100144515;6184.721687524072;6188.040853667689;6193.938060619426;6222.377762223787;6224.688765561704;5986.161937328778;6186.907196392058;6209.254889413254;6239.750409983595;6267.843303917439;5799.797007104731;5813.651180929158;5837.328707198684;5851.414858514199;5859.414058594191;6255.874882502325;6261.843453913598;6265.624062556261;6267.874642507216;6278.183836101278;5948.315943666447;6060.969695151496;6095.2685677718955;6145.539084568675;6151.907721384183;6167.013277875608;6208.50331973441;6242.843928901813;6263.248410190715;6265.467435267962;6103.35914728956;6159.014557670751;6188.938110618927;6196.504279657616;6201.317855035943;6092.45167934888;6115.362036198677;6142.938570614237;6196.81409557714;6214.658193799294;4970.428400733962;4978.27814966835;4978.850634480971;4983.252512123138;4983.875403114951;3763.190913953458;3817.751846129993;4501.212287849613;4503.3245013247615;4516.096780643885;5639.238702775155;5689.203511508392;5693.629914055573;5743.052396354555;5743.368229494752;6174.8147555573405;6236.407541283571;6256.499480041586;6277.497800175975;6302.401271879165;5673.290838645169;5692.430756924357;5694.658320500783;5695.914561281584;5735.082386818089;5341.572674186055;5374.355077390738;5393.083175860113;5432.728363581805;5443.918341224885;5259.474052594786;6097.0105204680185;6235.189425374672;6257.874842503215;6258.780942666985;6272.717727702356;6275.184226050585;6277;6279.623222606659;6281.15204447412;5966.105084237225;5982.312034116132;6257.874842503215;6268.874622507616;6270.811875643738;5940.079287710412;6006.33930267679;6016.308124565731;6110.114033465212;6186.783462578788;6129.389354848411;6130.184124957687;6131.254333328352;6145.7708458307625;6145.893739126821;6016.669083200466;6107.067445037884;6115.755369785159;6119.326874043941;6131.63210207389;6062.909056364247;6066.757329706762;6085.878282434238;6087.239095113111;6100.206973093647;4918.631102667386;5759.827205183851;5918.852028699317;5931.6441013539325;5934;5543.196236545759;5811.750473648186;6071.301800292847;6081.847953801013;6090.23872015998;5846.327672317738;5849.034909239927;5864.706764661664;5901.144334071621;5948.405159484017 23:40:56 INFO - PROCESS | 1933 | |1;ext.html;470.47307016141906;507.8679543318736;520.5757307794148;526.5234962359065;532.3319234360877;32198.35603287933;40783.79608101959;40951;42694.35958460625;42767.57232427675;42780.35829462553;43123.568764312346;43167.92080197997;43287;43329;8667;8832.072632373605;9584.281178911575;9734;9744.269179811507;8741.95629021854;8842.425242359226;8860;11798.292102473879;12087.033037357034;11994.700132496713;12097.758044839098;12101.455434505468;12103.636890893287;12107.939460302687;12035.638930832089;12092.758144837055;12098.758024839542;12110.212836165621;12115;11825.526978920832;11902.761944761056;11911.642650720492;11922.463489142923;11925.582604608797;39460.802695986626;39716.40425393621;39734.40398394026;39785.00537486557;40506.594934050874;3053.9541906871414;3293.390722716305;3362.495625656147;3479.4954731564035;3628.8004159771203;30110.397792044037;30475.390492190254;31055.534166987512;31184.908528201406;31549.211269718213;2734.808563400563;3242.643309235982;3295;3335.6831101045364;3365.9158521036925;352466;358447.2077639621;358804;358837;359206;32744.345113097606;59363;59685.70157149187;59833.70083149643;59893.70053149707;33056.1735956603;34475.65524344775;34656.480152797725;34713.82643086819;34735.82632086823;14401.351939162763;14569.41722331116;14575.635609109646;14576.63558411027;14596.927015365067;12310.692232694255;12438.751224975449;12442.564510242099;12446.688832779253;12456.12807103494;7195.568265904062;7311.086114235721;7511.9624401877645;7630.809229769189;7637.732679356196;3165.446046941776;3203.6475987641343;3206.1663967368654;3206.51902214665;3241.6434192238835;925.842606756853;931.3852857114272;934.5887809363858;951;989.475577943691;29556.852215738785;29894.40211195764;29955.700442995727;30646.23384415363;30654;1034.2088302448633;1047.3715770537617;1103.1340397787699;1127.9548818047351;1183.7277426192024;13839.238841863795;14855.777163342558;15434.922825385802;15464.304106315243;15805;516233;623314;623988;638122;643415;57102.143467848015;57383.71308143516;57438.138427923615;57587.4241257582;57675.71162144246;1228.263042174688;1349.6625843538918;1371.35546293242;1385.9514916977857;1388.4168649167375;29887.10812444888;30642;30793.538096929013;30972.69027309698;31532;49081;49545.50454495553;49595;49704;49798.75100624474;499213;499357;501234.48148277076;503558;506986;26017.479650407266;26034.73965260323;26809;26817.793199306467;27630.723692762815;46671.76664116658;46751;46957.53042469532;47056.29415558838;48003;32164.03507894767;38773.80613096916;38793.612063879;40426.191476169726;40676.98307542335;31425;38251;38606.42090368703;38715.80642096772;40285.39571906365;37722.868313950625;38374.61625383822;38675.80662096784;38723.2255354897;38775.80612096922;33097.338053239284;33510.66489335075;33752.83123584367;33831;34122;25079.74920250774;25185.87407062953;25199.49601007933;25654.102106426184;25855.353616159748 23:40:56 INFO - PROCESS | 1933 | |2;jquery.html;312.5795804642755;352.59451630624795;372.97948612826286;379.1034204107285;404.55498951153714;15079.773803392944;16427.91786041071;17273.481795546137;20246.849395873847;20648.793512064876;19519.12163952622;19815.603687926232;20383.184672613115;20468.590628187427;20676.68984965223;5469.07025805614;5642.7178641067985;5900.763969441238;5910.497607703351;5917.585768996172;4451.554844515555;5768.192453056576;5830.941690583082;5857.121431785245;5857.6485410875275;5627.465390787868;5731.627444216134;5807.883842323151;5809.912851307234;5831.096180092092;5661.8584535386535;5770.913436298459;5771.076627739583;5773.682447465388;5779.21980532629;4822.903541929177;4985.326980857574;5056.2162864755655;5068.82259120929;5070.8225212117395;21074.89462552693;21150.788492115036;24526.509469810502;24743.50512989748;24848.254552363458;2204.4488877780555;2210.2153735424013;2384.546936082156;2497.900083996638;2838.219489640345;14779.704405911822;15024.248787560688;15025.774613380809;15296.464623738228;15696.372145114181;1772.9379471718428;1789.1143883777613;1799.16338902411;1872.662920674278;1892.7350170976074;87205.56397218037;114823;119189.40405298004;119256.99999999913;120044;10128.44293563861;10363.63727269557;10517.947410263052;10680.412577308167;10821.67534973952;10982.890171098186;11379.487923043484;13213.405396757169;13217.14088584239;13217.471301147847;7077.716891324392;7830.725924592726;7890.447668663253;8014.15851335613;8024.277814996562;8212;8239.423240373237;8398.060678762384;8420.368472364597;8424.410291279673;3687.391580389206;3739.1586892949413;3756.5799968097226;3803.31540322739;3823.847046118124;1654.2390500369966;1821.2077746180453;1865.1886429403253;1870.4108205915093;1871.606962537855;814.3444527155578;851.3955091884831;889.1860604367148;895.3463971300987;944.3248077624531;19310.420687379403;20145.58980871325;21262.149514019577;21313;21692.240771573233;858.4677499950071;884.8318819424288;887.7913690282778;908.7909780750465;963.0081016552962;10487.056164945192;10866.61966831157;10933;10937.343759374464;10972.670819875417;518691;542997;544313.8352924709;549274;549554;76871.40019365273;123340;129559;129843;135354;817.6361519124051;988.4761076629397;999.0159692702682;1016.7966406718685;1018.4093225928943;18421;18686;18805.717914231023;18927;19185.904070480115;52652.736736316074;55849.464712711226;56439;56985.715071424376;57072.71463642655;74766.62616687099;75292;76001;77908;82644;6050;7828.334591559781;7906.209379062278;7992.841038049566;8141.389395795458;12650.746985060063;12701.42843572023;12767.93616031914;13118;13186.208827470382;10444.529996150344;10785.838212426977;11161.553537858395;11267.605633802777;11279.887201127884;10103;10386.636467723594;10987.285826421257;11027.669169924915;11371.090312775124;11240.437978101227;11348.943255283672;11712.414379281172;11774.882251177378;11891.46488408041;6994.755183568551;7887.881681774663;7927.176749347797;8152;8336.708215212439;10426.947865260625;10728.537451860564;10845.620403285848;11033;11253.606123785628 23:40:56 INFO - PROCESS | 1933 | |3;mootools.html;1491.0382803092007;1520.2246854104408;1698.6178109925268;1853.9258429662814;1876.18386002089;8426.620802063906;8920.553972301392;8933.508657023862;8934.865977010342;8941.821163576724;7775.1447340792465;8036.879446808302;8438.620262088205;8661.480311181334;8690.565471726404;2341.0869760793303;2370.798482129021;2371.7983971362455;2372.5847976604114;2374.1215750172373;2348.4246359641825;2371.1226846066893;2398.46034642205;2403;2404.95190096198;2362.0905951208824;2369.289213236022;2373.4422410733464;2401.7718316759965;2408.1812183857487;2342.9882850585636;2349.9177528786495;2357.092519380034;2361.5394997975486;2383.9642405363934;2261.027758064032;2278.931632051041;2297.1041293895396;2299.7930186283147;2302.309307207831;8653.913460865373;8804.427712198687;8806.559672016374;8813.911860881437;8821;5147;5307.708076055816;5312.203169524563;5317.41508434072;5319.7340132993195;3620.294042661682;3639.6542328478754;3711.6473934976134;3715.405535114369;3721.5162028936334;1092.3282181458346;1096.8683757949018;1100.1199040767433;1108.3460758152728;1118.3122379736403;11412.258203216765;11481.196316257781;11485.885141148565;11487.0810335173;11491.942540287246;4474.463064432289;4645.349651048856;4751.691140075884;4756.6670333077045;4770.666053376231;9809.509524523744;9824.803503930025;9833.754156146011;9835.508224588742;9848.655297064568;5298.496642818926;5302.867428314245;5303.496167864047;5310.230016647642;5313.256144139824;4141.316682747373;4238.660907127422;4390.5828946250695;4823.493533179036;4883.731394773251;3822.120912190212;3824.521934758155;3950.506186726659;3965.20695860829;4000.4799376081505;1091.826286741756;1261.148724610884;1286.5625687266374;1291.8708129187007;1296.6887946892678;792.2038351456749;872.5244741615835;898.7708134425679;919.9080091990882;935.5088578496344;5788.421157884178;5807.970960145172;5941.910871336933;6098.573099882966;6261.248650161919;943.1370296179057;952.5570609666538;952.6665667016489;959.3380567408466;985.280745056113;3837.846486140579;4045.777238650127;4051.493563304587;4143.461350024538;4154.397612346193;10562.471876406149;10605.469726513644;10781.245312828183;10788.352698838095;10811.243212975016;10543.789124217472;10674.946625266824;10685.198610104273;10685.786284274427;10688.465576721133;6450.064980505923;8048.637811298388;8058.0733215679775;8265.504069755834;8270.172982701682;5857.556687643558;5996.970015149896;6017.176357482453;6111.7249723763425;6173.907391389043;9846.310758246855;9967.202623790225;9998.800023999625;10013.94853540397;10029.147522460673;9749.853752193581;10057.446840423847;10100.292979491365;10245.180385569285;10259.897401025893;6825.726970921108;7059.788206353817;7283.453740969554;7406.370458511087;7441.851162976818;7980.561069141253;8272;8272.296854767412;8351.540665263468;8362.331013518782;7318.194998550049;7345.853082938204;7374.410047196318;7399.186089530255;7412.1846596875375;7095.858082838417;7135.286471352823;7226;7267.890981635174;7351.8162045949275;6615.503837212132;6805.1833779944745;6881.41507971828;6941.201761797458;6945.791626251221;4931.5315045070665;4937.728424936663;4984.252362145708;4985.177445721415;5058.671186372874;4967.230079337708;5027.868729535825;5034.823781167642;5107.744612769421;5120.769565369493 23:40:56 INFO - PROCESS | 1933 | |4;prototype.html;275.6058835864715;297.58189743410514;303.2282840171763;311.81758671177363;314.3320444056379;4358.455193100863;4907.1412502812;5173.172292433212;5537.5081213453905;5618.381977982429;4252.489701235848;4815.975920120394;4973.278874563186;5030.37120359955;5398.865028374293;3852.3451013327804;4202.411662367271;4216.9578304216875;4286.271333873249;4299.31211006239;3714.7213958953184;4157.334826427772;4230.238557059728;4248.532661407243;4290.420793192926;3230.6607806180364;4017.7790221537803;4156.688248381369;4194.874153775377;4237.682173836959;3569.678728914384;4141.337386018223;4147.688923330762;4148.958510414887;4166.874993750162;3547.3260080584605;3548.432250839853;3548.9112772180642;3564.3940530109685;3564.5048466073854;5109.744512774384;5211.869703257449;5428.782848686048;5433.1850222466555;5469.480399362054;4548.658850586219;4555.567221114022;4757.286407038937;4799.880002999918;4822.180229361017;5246.973765131188;5400.83797486076;5773.307203135609;5902.704864756745;5925.674087925162;2882.884684612613;3480.9477857832153;3814.5422549293985;3898.532176138853;3904.1020565269864;5034.11902916988;6085.14807926895;6096.878062438771;6125.264968203799;6126.23422072241;3774.358359078963;3934.940975885364;3955.9011024724614;3968.3849003403943;3985.1830374773103;3370.915727106842;3886.436466712311;4038.818253178614;4047.4821941771834;4077.2478576960434;4588.783972247357;4646.186917289461;4681.231608555077;4691.906161876744;4733.621310295168;5278.891432799079;5304.681719096867;5449.727513624303;5710.371859095496;5808.099744539603;1291.9354032298347;1348.871857173567;1373.3407964177252;1385.452746165259;1388.4931999819994;718.9748358807417;766.2375935943771;818.705266104202;860.9096044915319;904.1771987491351;4095.6313931746286;4437.822487100544;4791.305260737174;5208;5342.572594192456;4531.864044078683;4820.927686084711;5682.210103817284;5740.483356497935;5863.325717542537;5108.6168537359845;5346.732663366816;5469.234307196995;5544.889102218014;5597.468240517144;2500.9374765631005;2671.2253446500686;2926.678065412803;3174.968020466903;3191.329820737671;5377.623566350396;5887.470127688529;5931.822045338647;6168.722407491674;6223.875522489616;4911.68074075184;5158.458361872024;5873.383294754075;6058.969705151446;6072.635641861414;6088.695565221633;6134.78528251509;6157.384261573807;6190.3190649028575;6195.31851496335;5626.971865140812;5934.329551701665;5971.343152253162;5998.4601385875485;6013.482112963965;5928.940710593011;5953.583249172516;5959.0763431668165;5963.4334738199805;5981.162637230704;5756.280464941882;5872.735726892385;6097.512199023979;6145;6172.598781079216;3382.729381649511;3865.4974853268895;3970.8431796721097;3992.48097747291;3993.940090898697;4843.457184977915;5899.587028907935;6070.908936366046;6087.8782424350375;6094.841980023782;4821.421429428351;5026.396832380138;5904.380040095728;5937.732802024006;5983.491403230774;5848.748231930652;5880.441358070976;5893.764249429974;5924.348321684697;5930.525557955267;5584.804531841366;5677.51741102011;5765.56758243125;5882.941170588239;5891.1752354669525;4778.989236780806;4958.330625365674;5041.495850415075;5295.920561191509;5601.971990140023;5759.1649210865025;5822.777216784522;5856.535866033491;5860.970695146497;5888.911666324922 23:40:56 INFO - PROCESS | 1933 | |5;yui.html;713.5005496152694;754.2382193984078;778.4784194589624;810.3800592546703;833.9457935234213;14960.326785294661;15737.685246295066;15760.842391576081;15779.842201577982;15794.973326733769;14067.648308792259;14117.29413529322;14154.929225353859;14159.575212743634;14162.50431234907;693.109354479494;698.2110215456539;716.9426445884317;850.2560259772699;879.9648014079429;862.2584577263548;877.2192748453881;877.6840337478502;878.9648414063462;879.6745204274428;925.2782829393084;926.3561824531953;929.2798081486834;930.4696323095839;930.5672862119113;715.0739791969412;882.9558522073935;913.9451632902014;915.6703586708812;917.958691858868;685.1812084558954;685.4721864164616;685.7702669605688;686.1046334533432;687.6424259385142;14817.925910370486;14820.555383338517;14822.40710371584;14840.183789891551;14872.553823385315;13611.52359667407;13766.105203161762;13920.721585568333;13988.510402135978;14274.286285685777;11035.503402346914;12255.75488490234;12429.31638759868;12527.686807829787;12578.245305281713;3902.804859757001;4570.382998295221;4598.540145985408;4635.165670179366;4676.018036212399;103958.34890267324;106545.46727266314;108286;108620;109671.90328096776;10418.322809017389;10489.370637761758;10686;10775;11038.889611103787;20284.594308113756;20523;20526.076326565042;20550.897245513977;20594.794052059588;6536.934630653727;7572.697092116254;7673.654685539164;7730.149683534807;7953.403494737918;4372.672049596293;4387.956120438755;4434.645228381722;4567.9086418271445;4612.654050946193;3432.982835085809;3500.9124771880906;3533.505309256706;3542.096765324877;3556.964430355715;645.2676212498848;659.7662371365482;664.0736173038584;669.7923643670478;681.9659017049128;632.6046221111806;643.784332248702;669.1535207961932;672.3108813466237;687.2303020616941;7652.660784362825;8304.837322774816;8633.266052479077;9637.18083962857;9766.267529935283;565.7369323264619;604.5767962426319;621.65498148528;633.0757094641874;678.081199974038;4078.4290199372113;4958.553730164303;5044.899102018013;5105.540501354896;5140.691558506502;88411;102081.48959255304;103503.44744828834;106198;135815;126285;129285;129922;131478;133444.33277833744;74425;83415.582922085;106589;107301.39047914288;109483.45258273657;9414.340996130206;9792.853107203255;9915.405075695482;9930.702078937644;9941.403515789076;48263;63381.415464611906;63852.7229455399;63868;64112.67943660252;91168;97121;98526;98991;99099.7697316164;12792.936035319764;13084.934575327443;13183.934080329536;13233.735325293634;13682.657933551343;14855.257237138316;15018.098914065191;15029.699406012038;15151.757348092757;15310.157941313335;14003.43986240539;14231.928840355731;14248.928755356572;14688.339024743698;14851.702965940405;13756.312184390541;14135.858641413453;14449.988500804842;14717;14902.403903843724;13505.932470337586;13688.93155534216;13979.370928308454;14350.171993586948;14391.136531807706;10995.560177592806;11409.412740527789;11438.542458301576;11571.13216508782;11588.334683022784;9786.902130978884;11295.3787541686;11348.886511134782;11564.248323858923;11729.35488548138 23:40:56 INFO - PROCESS | 1933 | __end_tp_report 23:40:56 INFO - PROCESS | 1933 | __start_cc_report 23:40:56 INFO - PROCESS | 1933 | _x_x_mozilla_cycle_collect,2055 23:40:56 INFO - PROCESS | 1933 | __end_cc_report 23:40:56 INFO - PROCESS | 1933 | __startTimestamp1472798456700__endTimestamp 23:40:56 INFO - PROCESS | 1933 | 23:40:56 INFO - PROCESS | 1933 | ------- Summary: start ------- 23:40:56 INFO - PROCESS | 1933 | Number of tests: 6 23:40:56 INFO - PROCESS | 1933 | 23:40:56 INFO - PROCESS | 1933 | [#0] dojo.html Cycles:165 Average:5699.19 Median:5864.71 stddev:641.26 (10.9%) stddev-sans-first:614.57 23:40:56 INFO - PROCESS | 1933 | Values: 3282.8 3286.8 3331.7 3345.5 3394.2 5545.5 5617.7 5666.0 5666.1 5688.7 5649.8 5681.3 5697.9 5700.0 5704.2 5151.4 5192.4 5200.0 5206.4 5245.1 5212.3 5213.4 5214.3 5222.9 5235.0 5184.4 5192.4 5204.8 5214.8 5225.3 5275.9 5283.4 5287.9 5311.8 5349.7 5065.3 5102.6 5103.5 5197.9 5203.1 5653.0 5666.1 5683.7 5698.6 5780.0 6184.7 6188.0 6193.9 6222.4 6224.7 5986.2 6186.9 6209.3 6239.8 6267.8 5799.8 5813.7 5837.3 5851.4 5859.4 6255.9 6261.8 6265.6 6267.9 6278.2 5948.3 6061.0 6095.3 6145.5 6151.9 6167.0 6208.5 6242.8 6263.2 6265.5 6103.4 6159.0 6188.9 6196.5 6201.3 6092.5 6115.4 6142.9 6196.8 6214.7 4970.4 4978.3 4978.9 4983.3 4983.9 3763.2 3817.8 4501.2 4503.3 4516.1 5639.2 5689.2 5693.6 5743.1 5743.4 6174.8 6236.4 6256.5 6277.5 6302.4 5673.3 5692.4 5694.7 5695.9 5735.1 5341.6 5374.4 5393.1 5432.7 5443.9 5259.5 6097.0 6235.2 6257.9 6258.8 6272.7 6275.2 6277.0 6279.6 6281.2 5966.1 5982.3 6257.9 6268.9 6270.8 5940.1 6006.3 6016.3 6110.1 6186.8 6129.4 6130.2 6131.3 6145.8 6145.9 6016.7 6107.1 6115.8 6119.3 6131.6 6062.9 6066.8 6085.9 6087.2 6100.2 4918.6 5759.8 5918.9 5931.6 5934.0 5543.2 5811.8 6071.3 6081.8 6090.2 5846.3 5849.0 5864.7 5901.1 5948.4 23:40:56 INFO - PROCESS | 1933 | 23:40:56 INFO - PROCESS | 1933 | [#1] ext.html Cycles:180 Average:62344.11 Median:34566.07 stddev:133846.79 (387.2%) stddev-sans-first:134141.64 23:40:56 INFO - PROCESS | 1933 | Values: 470.5 507.9 520.6 526.5 532.3 32198.4 40783.8 40951.0 42694.4 42767.6 42780.4 43123.6 43167.9 43287.0 43329.0 8667.0 8832.1 9584.3 9734.0 9744.3 8742.0 8842.4 8860.0 11798.3 12087.0 11994.7 12097.8 12101.5 12103.6 12107.9 12035.6 12092.8 12098.8 12110.2 12115.0 11825.5 11902.8 11911.6 11922.5 11925.6 39460.8 39716.4 39734.4 39785.0 40506.6 3054.0 3293.4 3362.5 3479.5 3628.8 30110.4 30475.4 31055.5 31184.9 31549.2 2734.8 3242.6 3295.0 3335.7 3365.9 352466.0 358447.2 358804.0 358837.0 359206.0 32744.3 59363.0 59685.7 59833.7 59893.7 33056.2 34475.7 34656.5 34713.8 34735.8 14401.4 14569.4 14575.6 14576.6 14596.9 12310.7 12438.8 12442.6 12446.7 12456.1 7195.6 7311.1 7512.0 7630.8 7637.7 3165.4 3203.6 3206.2 3206.5 3241.6 925.8 931.4 934.6 951.0 989.5 29556.9 29894.4 29955.7 30646.2 30654.0 1034.2 1047.4 1103.1 1128.0 1183.7 13839.2 14855.8 15434.9 15464.3 15805.0 516233.0 623314.0 623988.0 638122.0 643415.0 57102.1 57383.7 57438.1 57587.4 57675.7 1228.3 1349.7 1371.4 1386.0 1388.4 29887.1 30642.0 30793.5 30972.7 31532.0 49081.0 49545.5 49595.0 49704.0 49798.8 499213.0 499357.0 501234.5 503558.0 506986.0 26017.5 26034.7 26809.0 26817.8 27630.7 46671.8 46751.0 46957.5 47056.3 48003.0 32164.0 38773.8 38793.6 40426.2 40677.0 31425.0 38251.0 38606.4 38715.8 40285.4 37722.9 38374.6 38675.8 38723.2 38775.8 33097.3 33510.7 33752.8 33831.0 34122.0 25079.7 25185.9 25199.5 25654.1 25855.4 23:40:56 INFO - PROCESS | 1933 | 23:40:56 INFO - PROCESS | 1933 | [#2] jquery.html Cycles:180 Average:32901.31 Median:24635.01 stddev:90624.48 (367.9%) stddev-sans-first:90845.67 23:40:56 INFO - PROCESS | 1933 | Values: 312.6 352.6 373.0 379.1 404.6 15079.8 16427.9 17273.5 20246.8 20648.8 19519.1 19815.6 20383.2 20468.6 20676.7 5469.1 5642.7 5900.8 5910.5 5917.6 4451.6 5768.2 5830.9 5857.1 5857.6 5627.5 5731.6 5807.9 5809.9 5831.1 5661.9 5770.9 5771.1 5773.7 5779.2 4822.9 4985.3 5056.2 5068.8 5070.8 21074.9 21150.8 24526.5 24743.5 24848.3 2204.4 2210.2 2384.5 2497.9 2838.2 14779.7 15024.2 15025.8 15296.5 15696.4 1772.9 1789.1 1799.2 1872.7 1892.7 87205.6 114823.0 119189.4 119257.0 120044.0 10128.4 10363.6 10517.9 10680.4 10821.7 10982.9 11379.5 13213.4 13217.1 13217.5 7077.7 7830.7 7890.4 8014.2 8024.3 8212.0 8239.4 8398.1 8420.4 8424.4 3687.4 3739.2 3756.6 3803.3 3823.8 1654.2 1821.2 1865.2 1870.4 1871.6 814.3 851.4 889.2 895.3 944.3 19310.4 20145.6 21262.1 21313.0 21692.2 858.5 884.8 887.8 908.8 963.0 10487.1 10866.6 10933.0 10937.3 10972.7 518691.0 542997.0 544313.8 549274.0 549554.0 76871.4 123340.0 129559.0 129843.0 135354.0 817.6 988.5 999.0 1016.8 1018.4 18421.0 18686.0 18805.7 18927.0 19185.9 52652.7 55849.5 56439.0 56985.7 57072.7 74766.6 75292.0 76001.0 77908.0 82644.0 6050.0 7828.3 7906.2 7992.8 8141.4 12650.7 12701.4 12767.9 13118.0 13186.2 10444.5 10785.8 11161.6 11267.6 11279.9 10103.0 10386.6 10987.3 11027.7 11371.1 11240.4 11348.9 11712.4 11774.9 11891.5 6994.8 7887.9 7927.2 8152.0 8336.7 10426.9 10728.5 10845.6 11033.0 11253.6 23:40:56 INFO - PROCESS | 1933 | 23:40:56 INFO - PROCESS | 1933 | [#3] mootools.html Cycles:180 Average:5679.20 Median:5006.52 stddev:3157.26 (63.1%) stddev-sans-first:3150.43 23:40:56 INFO - PROCESS | 1933 | Values: 1491.0 1520.2 1698.6 1853.9 1876.2 8426.6 8920.6 8933.5 8934.9 8941.8 7775.1 8036.9 8438.6 8661.5 8690.6 2341.1 2370.8 2371.8 2372.6 2374.1 2348.4 2371.1 2398.5 2403.0 2405.0 2362.1 2369.3 2373.4 2401.8 2408.2 2343.0 2349.9 2357.1 2361.5 2384.0 2261.0 2278.9 2297.1 2299.8 2302.3 8653.9 8804.4 8806.6 8813.9 8821.0 5147.0 5307.7 5312.2 5317.4 5319.7 3620.3 3639.7 3711.6 3715.4 3721.5 1092.3 1096.9 1100.1 1108.3 1118.3 11412.3 11481.2 11485.9 11487.1 11491.9 4474.5 4645.3 4751.7 4756.7 4770.7 9809.5 9824.8 9833.8 9835.5 9848.7 5298.5 5302.9 5303.5 5310.2 5313.3 4141.3 4238.7 4390.6 4823.5 4883.7 3822.1 3824.5 3950.5 3965.2 4000.5 1091.8 1261.1 1286.6 1291.9 1296.7 792.2 872.5 898.8 919.9 935.5 5788.4 5808.0 5941.9 6098.6 6261.2 943.1 952.6 952.7 959.3 985.3 3837.8 4045.8 4051.5 4143.5 4154.4 10562.5 10605.5 10781.2 10788.4 10811.2 10543.8 10674.9 10685.2 10685.8 10688.5 6450.1 8048.6 8058.1 8265.5 8270.2 5857.6 5997.0 6017.2 6111.7 6173.9 9846.3 9967.2 9998.8 10013.9 10029.1 9749.9 10057.4 10100.3 10245.2 10259.9 6825.7 7059.8 7283.5 7406.4 7441.9 7980.6 8272.0 8272.3 8351.5 8362.3 7318.2 7345.9 7374.4 7399.2 7412.2 7095.9 7135.3 7226.0 7267.9 7351.8 6615.5 6805.2 6881.4 6941.2 6945.8 4931.5 4937.7 4984.3 4985.2 5058.7 4967.2 5027.9 5034.8 5107.7 5120.8 23:40:56 INFO - PROCESS | 1933 | 23:40:56 INFO - PROCESS | 1933 | [#4] prototype.html Cycles:180 Average:4646.39 Median:5134.10 stddev:1470.12 (28.6%) stddev-sans-first:1437.17 23:40:56 INFO - PROCESS | 1933 | Values: 275.6 297.6 303.2 311.8 314.3 4358.5 4907.1 5173.2 5537.5 5618.4 4252.5 4816.0 4973.3 5030.4 5398.9 3852.3 4202.4 4217.0 4286.3 4299.3 3714.7 4157.3 4230.2 4248.5 4290.4 3230.7 4017.8 4156.7 4194.9 4237.7 3569.7 4141.3 4147.7 4149.0 4166.9 3547.3 3548.4 3548.9 3564.4 3564.5 5109.7 5211.9 5428.8 5433.2 5469.5 4548.7 4555.6 4757.3 4799.9 4822.2 5247.0 5400.8 5773.3 5902.7 5925.7 2882.9 3480.9 3814.5 3898.5 3904.1 5034.1 6085.1 6096.9 6125.3 6126.2 3774.4 3934.9 3955.9 3968.4 3985.2 3370.9 3886.4 4038.8 4047.5 4077.2 4588.8 4646.2 4681.2 4691.9 4733.6 5278.9 5304.7 5449.7 5710.4 5808.1 1291.9 1348.9 1373.3 1385.5 1388.5 719.0 766.2 818.7 860.9 904.2 4095.6 4437.8 4791.3 5208.0 5342.6 4531.9 4820.9 5682.2 5740.5 5863.3 5108.6 5346.7 5469.2 5544.9 5597.5 2500.9 2671.2 2926.7 3175.0 3191.3 5377.6 5887.5 5931.8 6168.7 6223.9 4911.7 5158.5 5873.4 6059.0 6072.6 6088.7 6134.8 6157.4 6190.3 6195.3 5627.0 5934.3 5971.3 5998.5 6013.5 5928.9 5953.6 5959.1 5963.4 5981.2 5756.3 5872.7 6097.5 6145.0 6172.6 3382.7 3865.5 3970.8 3992.5 3993.9 4843.5 5899.6 6070.9 6087.9 6094.8 4821.4 5026.4 5904.4 5937.7 5983.5 5848.7 5880.4 5893.8 5924.3 5930.5 5584.8 5677.5 5765.6 5882.9 5891.2 4779.0 4958.3 5041.5 5295.9 5602.0 5759.2 5822.8 5856.5 5861.0 5888.9 23:40:56 INFO - PROCESS | 1933 | 23:40:56 INFO - PROCESS | 1933 | [#5] yui.html Cycles:180 Average:23453.22 Median:3729.88 stddev:35896.15 (962.4%) stddev-sans-first:35956.25 23:40:56 INFO - PROCESS | 1933 | Values: 713.5 754.2 778.5 810.4 833.9 14960.3 15737.7 15760.8 15779.8 15795.0 14067.6 14117.3 14154.9 14159.6 14162.5 693.1 698.2 716.9 850.3 880.0 862.3 877.2 877.7 879.0 879.7 925.3 926.4 929.3 930.5 930.6 715.1 883.0 913.9 915.7 918.0 685.2 685.5 685.8 686.1 687.6 14817.9 14820.6 14822.4 14840.2 14872.6 13611.5 13766.1 13920.7 13988.5 14274.3 11035.5 12255.8 12429.3 12527.7 12578.2 3902.8 4570.4 4598.5 4635.2 4676.0 103958.3 106545.5 108286.0 108620.0 109671.9 10418.3 10489.4 10686.0 10775.0 11038.9 20284.6 20523.0 20526.1 20550.9 20594.8 6536.9 7572.7 7673.7 7730.1 7953.4 4372.7 4388.0 4434.6 4567.9 4612.7 3433.0 3500.9 3533.5 3542.1 3557.0 645.3 659.8 664.1 669.8 682.0 632.6 643.8 669.2 672.3 687.2 7652.7 8304.8 8633.3 9637.2 9766.3 565.7 604.6 621.7 633.1 678.1 4078.4 4958.6 5044.9 5105.5 5140.7 88411.0 102081.5 103503.4 106198.0 135815.0 126285.0 129285.0 129922.0 131478.0 133444.3 74425.0 83415.6 106589.0 107301.4 109483.5 9414.3 9792.9 9915.4 9930.7 9941.4 48263.0 63381.4 63852.7 63868.0 64112.7 91168.0 97121.0 98526.0 98991.0 99099.8 12792.9 13084.9 13183.9 13233.7 13682.7 14855.3 15018.1 15029.7 15151.8 15310.2 14003.4 14231.9 14248.9 14688.3 14851.7 13756.3 14135.9 14450.0 14717.0 14902.4 13505.9 13688.9 13979.4 14350.2 14391.1 10995.6 11409.4 11438.5 11571.1 11588.3 9786.9 11295.4 11348.9 11564.2 11729.4 23:40:56 INFO - PROCESS | 1933 | -------- Summary: end -------- 23:40:56 INFO - PROCESS | 1933 | 23:40:57 INFO - TEST-INFO | 1933: exit 0 23:40:57 INFO - TEST-OK | dromaeo_css | took 1118446ms 23:40:57 INFO - TEST-START | kraken 23:40:57 INFO - operating with platform_type : mac_ 23:40:57 INFO - Initialising browser for kraken test... 23:40:57 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmpqjcqwN/profile http://localhost:52319/getInfo.html) 23:40:59 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 23:40:59 INFO - PROCESS | 1938 | colorDepth:24 23:40:59 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 23:40:59 INFO - PROCESS | 1938 | __metrics 23:40:59 INFO - PROCESS | 1938 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:40:59 INFO - PROCESS | 1938 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:41:00 INFO - TEST-INFO | 1938: exit 0 23:41:00 INFO - Browser initialized. 23:41:00 INFO - Running cycle 1/1 for kraken test... 23:41:00 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/30/cty5_80j7yx3964y35jzqrp000000w/T/tmpqjcqwN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:41:12 INFO - PROCESS | 1939 | RSS: Main: 233832448 23:41:12 INFO - PROCESS | 1939 | 23:41:15 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52319/tests/kraken/driver.html?testName=audio-beat-detection) 23:41:16 INFO - PROCESS | 1939 | RSS: Main: 293482496 23:41:16 INFO - PROCESS | 1939 | 23:41:19 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52319/tests/kraken/driver.html?testName=audio-dft) 23:41:19 INFO - PROCESS | 1939 | RSS: Main: 289013760 23:41:19 INFO - PROCESS | 1939 | 23:41:23 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52319/tests/kraken/driver.html?testName=audio-fft) 23:41:23 INFO - PROCESS | 1939 | RSS: Main: 379809792 23:41:23 INFO - PROCESS | 1939 | 23:41:25 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52319/tests/kraken/driver.html?testName=audio-oscillator) 23:41:26 INFO - PROCESS | 1939 | RSS: Main: 280698880 23:41:26 INFO - PROCESS | 1939 | 23:41:28 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52319/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:41:29 INFO - PROCESS | 1939 | RSS: Main: 284033024 23:41:29 INFO - PROCESS | 1939 | 23:41:31 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52319/tests/kraken/driver.html?testName=imaging-darkroom) 23:41:32 INFO - PROCESS | 1939 | RSS: Main: 322846720 23:41:32 INFO - PROCESS | 1939 | 23:41:35 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52319/tests/kraken/driver.html?testName=imaging-desaturate) 23:41:36 INFO - PROCESS | 1939 | RSS: Main: 325763072 23:41:36 INFO - PROCESS | 1939 | 23:41:38 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52319/tests/kraken/driver.html?testName=json-parse-financial) 23:41:39 INFO - PROCESS | 1939 | RSS: Main: 323887104 23:41:39 INFO - PROCESS | 1939 | 23:41:41 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52319/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:41:41 INFO - PROCESS | 1939 | RSS: Main: 297484288 23:41:41 INFO - PROCESS | 1939 | 23:41:43 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:41:44 INFO - PROCESS | 1939 | RSS: Main: 303083520 23:41:44 INFO - PROCESS | 1939 | 23:41:46 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:41:47 INFO - PROCESS | 1939 | RSS: Main: 304168960 23:41:47 INFO - PROCESS | 1939 | 23:41:50 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:41:50 INFO - PROCESS | 1939 | RSS: Main: 319614976 23:41:50 INFO - PROCESS | 1939 | 23:41:53 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:41:54 INFO - PROCESS | 1939 | RSS: Main: 315772928 23:41:54 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:52319/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52319/tests/kraken/driver.html?testName=ai-astar) 23:41:56 INFO - PROCESS | 1939 | RSS: Main: 359153664 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | __start_tp_report 23:41:56 INFO - PROCESS | 1939 | _x_x_mozilla_page_load 23:41:56 INFO - PROCESS | 1939 | _x_x_mozilla_page_load_details 23:41:56 INFO - PROCESS | 1939 | |i|pagename|runs| 23:41:56 INFO - PROCESS | 1939 | |0;ai-astar;112;112;116;112;113;114;115;111;114;124 23:41:56 INFO - PROCESS | 1939 | |1;audio-beat-detection;144;111;134;159;107;150;161;138;166;108 23:41:56 INFO - PROCESS | 1939 | |2;audio-dft;186;184;184;183;186;186;185;184;185;181 23:41:56 INFO - PROCESS | 1939 | |3;audio-fft;79;74;78;156;59;58;59;63;145;58 23:41:56 INFO - PROCESS | 1939 | |4;audio-oscillator;82;85;129;69;69;92;122;68;70;116 23:41:56 INFO - PROCESS | 1939 | |5;imaging-gaussian-blur;117;86;89;85;87;84;89;85;88;84 23:41:56 INFO - PROCESS | 1939 | |6;imaging-darkroom;93;93;104;123;93;94;93;93;93;91 23:41:56 INFO - PROCESS | 1939 | |7;imaging-desaturate;85;86;84;86;86;85;85;86;84;84 23:41:56 INFO - PROCESS | 1939 | |8;json-parse-financial;56;56;58;57;58;54;58;55;56;56 23:41:56 INFO - PROCESS | 1939 | |9;json-stringify-tinderbox;67;66;69;79;77;72;71;72;94;60 23:41:56 INFO - PROCESS | 1939 | |10;stanford-crypto-aes;91;99;108;107;89;84;87;87;90;90 23:41:56 INFO - PROCESS | 1939 | |11;stanford-crypto-ccm;139;139;137;137;139;139;142;153;187;179 23:41:56 INFO - PROCESS | 1939 | |12;stanford-crypto-pbkdf2;172;170;171;171;170;169;170;174;176;171 23:41:56 INFO - PROCESS | 1939 | |13;stanford-crypto-sha256-iterative;68;67;68;66;68;67;67;68;67;67 23:41:56 INFO - PROCESS | 1939 | __end_tp_report 23:41:56 INFO - PROCESS | 1939 | __start_cc_report 23:41:56 INFO - PROCESS | 1939 | _x_x_mozilla_cycle_collect,2163 23:41:56 INFO - PROCESS | 1939 | __end_cc_report 23:41:56 INFO - PROCESS | 1939 | __startTimestamp1472798516418__endTimestamp 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | ------- Summary: start ------- 23:41:56 INFO - PROCESS | 1939 | Number of tests: 14 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#0] ai-astar Cycles:10 Average:114.30 Median:114.00 stddev:3.74 (3.3%) stddev-sans-first:3.88 23:41:56 INFO - PROCESS | 1939 | Values: 112.0 112.0 116.0 112.0 113.0 114.0 115.0 111.0 114.0 124.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:147.00 stddev:22.46 (15.3%) stddev-sans-first:23.71 23:41:56 INFO - PROCESS | 1939 | Values: 144.0 111.0 134.0 159.0 107.0 150.0 161.0 138.0 166.0 108.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:1.58 (0.9%) stddev-sans-first:1.56 23:41:56 INFO - PROCESS | 1939 | Values: 186.0 184.0 184.0 183.0 186.0 186.0 185.0 184.0 185.0 181.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#3] audio-fft Cycles:10 Average:82.90 Median:61.00 stddev:36.66 (60.1%) stddev-sans-first:38.86 23:41:56 INFO - PROCESS | 1939 | Values: 79.0 74.0 78.0 156.0 59.0 58.0 59.0 63.0 145.0 58.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:69.50 stddev:23.76 (34.2%) stddev-sans-first:25.01 23:41:56 INFO - PROCESS | 1939 | Values: 82.0 85.0 129.0 69.0 69.0 92.0 122.0 68.0 70.0 116.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:86.50 stddev:9.88 (11.4%) stddev-sans-first:2.00 23:41:56 INFO - PROCESS | 1939 | Values: 117.0 86.0 89.0 85.0 87.0 84.0 89.0 85.0 88.0 84.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:93.00 stddev:9.81 (10.5%) stddev-sans-first:10.30 23:41:56 INFO - PROCESS | 1939 | Values: 93.0 93.0 104.0 123.0 93.0 94.0 93.0 93.0 93.0 91.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:0.88 (1.0%) stddev-sans-first:0.93 23:41:56 INFO - PROCESS | 1939 | Values: 85.0 86.0 84.0 86.0 86.0 85.0 85.0 86.0 84.0 84.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.50 stddev:1.35 (2.4%) stddev-sans-first:1.42 23:41:56 INFO - PROCESS | 1939 | Values: 56.0 56.0 58.0 57.0 58.0 54.0 58.0 55.0 56.0 56.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#9] json-stringify-tinderbox Cycles:10 Average:72.70 Median:72.00 stddev:9.24 (12.8%) stddev-sans-first:9.57 23:41:56 INFO - PROCESS | 1939 | Values: 67.0 66.0 69.0 79.0 77.0 72.0 71.0 72.0 94.0 60.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:89.50 stddev:8.48 (9.5%) stddev-sans-first:8.96 23:41:56 INFO - PROCESS | 1939 | Values: 91.0 99.0 108.0 107.0 89.0 84.0 87.0 87.0 90.0 90.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:140.50 stddev:18.55 (13.2%) stddev-sans-first:19.31 23:41:56 INFO - PROCESS | 1939 | Values: 139.0 139.0 137.0 137.0 139.0 139.0 142.0 153.0 187.0 179.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:171.00 stddev:2.12 (1.2%) stddev-sans-first:2.24 23:41:56 INFO - PROCESS | 1939 | Values: 172.0 170.0 171.0 171.0 170.0 169.0 170.0 174.0 176.0 171.0 23:41:56 INFO - PROCESS | 1939 | 23:41:56 INFO - PROCESS | 1939 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:0.67 (1.0%) stddev-sans-first:0.67 23:41:56 INFO - PROCESS | 1939 | Values: 68.0 67.0 68.0 66.0 68.0 67.0 67.0 68.0 67.0 67.0 23:41:56 INFO - PROCESS | 1939 | -------- Summary: end -------- 23:41:56 INFO - PROCESS | 1939 | 23:41:57 INFO - TEST-INFO | 1939: exit 0 23:41:57 INFO - TEST-OK | kraken | took 59928ms 23:41:57 INFO - SUITE-END | took 1178s 23:41:57 INFO - Completed test suite (00:19:38) 23:41:57 INFO - javascript benchmark 23:41:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3282.835858207089, 3286.769926105173, 3331.650176731445, 3345.5483509726178, 3394.1853955050797, 5545.473180047899, 5617.719114044302, 5665.971670141648, 5666.121751128571, 5688.715564221788, 5649.802256921009, 5681.346645135811, 5697.943020569793, 5700.0, 5704.172894930232, 5151.381834179903, 5192.376914770233, 5200.0, 5206.401263854667, 5245.108331583621, 5212.322398088262, 5213.400458947212, 5214.322138122039, 5222.9477705223035, 5235.0, 5184.403793563751, 5192.376914770214, 5204.817831375903, 5214.817481388152, 5225.294585231002, 5275.868103297429, 5283.392409872837, 5287.894242115136, 5311.814086506954, 5349.732513374316, 5065.341505604285, 5102.591792656579, 5103.4896510349035, 5197.870053248699, 5203.089459344599, 5652.971735141339, 5666.093425052013, 5683.715814209273, 5698.572607054488, 5779.971100144515, 6184.721687524072, 6188.040853667689, 6193.938060619426, 6222.377762223787, 6224.688765561704, 5986.161937328778, 6186.907196392058, 6209.254889413254, 6239.750409983595, 6267.843303917439, 5799.797007104731, 5813.651180929158, 5837.328707198684, 5851.414858514199, 5859.414058594191, 6255.874882502325, 6261.843453913598, 6265.624062556261, 6267.874642507216, 6278.183836101278, 5948.315943666447, 6060.969695151496, 6095.2685677718955, 6145.539084568675, 6151.907721384183, 6167.013277875608, 6208.50331973441, 6242.843928901813, 6263.248410190715, 6265.467435267962, 6103.35914728956, 6159.014557670751, 6188.938110618927, 6196.504279657616, 6201.317855035943, 6092.45167934888, 6115.362036198677, 6142.938570614237, 6196.81409557714, 6214.658193799294, 4970.428400733962, 4978.27814966835, 4978.850634480971, 4983.252512123138, 4983.875403114951, 3763.190913953458, 3817.751846129993, 4501.212287849613, 4503.3245013247615, 4516.096780643885, 5639.238702775155, 5689.203511508392, 5693.629914055573, 5743.052396354555, 5743.368229494752, 6174.8147555573405, 6236.407541283571, 6256.499480041586, 6277.497800175975, 6302.401271879165, 5673.290838645169, 5692.430756924357, 5694.658320500783, 5695.914561281584, 5735.082386818089, 5341.572674186055, 5374.355077390738, 5393.083175860113, 5432.728363581805, 5443.918341224885, 5259.474052594786, 6097.0105204680185, 6235.189425374672, 6257.874842503215, 6258.780942666985, 6272.717727702356, 6275.184226050585, 6277.0, 6279.623222606659, 6281.15204447412, 5966.105084237225, 5982.312034116132, 6257.874842503215, 6268.874622507616, 6270.811875643738, 5940.079287710412, 6006.33930267679, 6016.308124565731, 6110.114033465212, 6186.783462578788, 6129.389354848411, 6130.184124957687, 6131.254333328352, 6145.7708458307625, 6145.893739126821, 6016.669083200466, 6107.067445037884, 6115.755369785159, 6119.326874043941, 6131.63210207389, 6062.909056364247, 6066.757329706762, 6085.878282434238, 6087.239095113111, 6100.206973093647, 4918.631102667386, 5759.827205183851, 5918.852028699317, 5931.6441013539325, 5934.0, 5543.196236545759, 5811.750473648186, 6071.301800292847, 6081.847953801013, 6090.23872015998, 5846.327672317738, 5849.034909239927, 5864.706764661664, 5901.144334071621, 5948.405159484017], "value": 5657.703101215479, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.47307016141906, 507.8679543318736, 520.5757307794148, 526.5234962359065, 532.3319234360877, 32198.35603287933, 40783.79608101959, 40951.0, 42694.35958460625, 42767.57232427675, 42780.35829462553, 43123.568764312346, 43167.92080197997, 43287.0, 43329.0, 8667.0, 8832.072632373605, 9584.281178911575, 9734.0, 9744.269179811507, 8741.95629021854, 8842.425242359226, 8860.0, 11798.292102473879, 12087.033037357034, 11994.700132496713, 12097.758044839098, 12101.455434505468, 12103.636890893287, 12107.939460302687, 12035.638930832089, 12092.758144837055, 12098.758024839542, 12110.212836165621, 12115.0, 11825.526978920832, 11902.761944761056, 11911.642650720492, 11922.463489142923, 11925.582604608797, 39460.802695986626, 39716.40425393621, 39734.40398394026, 39785.00537486557, 40506.594934050874, 3053.9541906871414, 3293.390722716305, 3362.495625656147, 3479.4954731564035, 3628.8004159771203, 30110.397792044037, 30475.390492190254, 31055.534166987512, 31184.908528201406, 31549.211269718213, 2734.808563400563, 3242.643309235982, 3295.0, 3335.6831101045364, 3365.9158521036925, 352466.0, 358447.2077639621, 358804.0, 358837.0, 359206.0, 32744.345113097606, 59363.0, 59685.70157149187, 59833.70083149643, 59893.70053149707, 33056.1735956603, 34475.65524344775, 34656.480152797725, 34713.82643086819, 34735.82632086823, 14401.351939162763, 14569.41722331116, 14575.635609109646, 14576.63558411027, 14596.927015365067, 12310.692232694255, 12438.751224975449, 12442.564510242099, 12446.688832779253, 12456.12807103494, 7195.568265904062, 7311.086114235721, 7511.9624401877645, 7630.809229769189, 7637.732679356196, 3165.446046941776, 3203.6475987641343, 3206.1663967368654, 3206.51902214665, 3241.6434192238835, 925.842606756853, 931.3852857114272, 934.5887809363858, 951.0, 989.475577943691, 29556.852215738785, 29894.40211195764, 29955.700442995727, 30646.23384415363, 30654.0, 1034.2088302448633, 1047.3715770537617, 1103.1340397787699, 1127.9548818047351, 1183.7277426192024, 13839.238841863795, 14855.777163342558, 15434.922825385802, 15464.304106315243, 15805.0, 516233.0, 623314.0, 623988.0, 638122.0, 643415.0, 57102.143467848015, 57383.71308143516, 57438.138427923615, 57587.4241257582, 57675.71162144246, 1228.263042174688, 1349.6625843538918, 1371.35546293242, 1385.9514916977857, 1388.4168649167375, 29887.10812444888, 30642.0, 30793.538096929013, 30972.69027309698, 31532.0, 49081.0, 49545.50454495553, 49595.0, 49704.0, 49798.75100624474, 499213.0, 499357.0, 501234.48148277076, 503558.0, 506986.0, 26017.479650407266, 26034.73965260323, 26809.0, 26817.793199306467, 27630.723692762815, 46671.76664116658, 46751.0, 46957.53042469532, 47056.29415558838, 48003.0, 32164.03507894767, 38773.80613096916, 38793.612063879, 40426.191476169726, 40676.98307542335, 31425.0, 38251.0, 38606.42090368703, 38715.80642096772, 40285.39571906365, 37722.868313950625, 38374.61625383822, 38675.80662096784, 38723.2255354897, 38775.80612096922, 33097.338053239284, 33510.66489335075, 33752.83123584367, 33831.0, 34122.0, 25079.74920250774, 25185.87407062953, 25199.49601007933, 25654.102106426184, 25855.353616159748], "value": 18645.441363693164, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.5795804642755, 352.59451630624795, 372.97948612826286, 379.1034204107285, 404.55498951153714, 15079.773803392944, 16427.91786041071, 17273.481795546137, 20246.849395873847, 20648.793512064876, 19519.12163952622, 19815.603687926232, 20383.184672613115, 20468.590628187427, 20676.68984965223, 5469.07025805614, 5642.7178641067985, 5900.763969441238, 5910.497607703351, 5917.585768996172, 4451.554844515555, 5768.192453056576, 5830.941690583082, 5857.121431785245, 5857.6485410875275, 5627.465390787868, 5731.627444216134, 5807.883842323151, 5809.912851307234, 5831.096180092092, 5661.8584535386535, 5770.913436298459, 5771.076627739583, 5773.682447465388, 5779.21980532629, 4822.903541929177, 4985.326980857574, 5056.2162864755655, 5068.82259120929, 5070.8225212117395, 21074.89462552693, 21150.788492115036, 24526.509469810502, 24743.50512989748, 24848.254552363458, 2204.4488877780555, 2210.2153735424013, 2384.546936082156, 2497.900083996638, 2838.219489640345, 14779.704405911822, 15024.248787560688, 15025.774613380809, 15296.464623738228, 15696.372145114181, 1772.9379471718428, 1789.1143883777613, 1799.16338902411, 1872.662920674278, 1892.7350170976074, 87205.56397218037, 114823.0, 119189.40405298004, 119256.99999999913, 120044.0, 10128.44293563861, 10363.63727269557, 10517.947410263052, 10680.412577308167, 10821.67534973952, 10982.890171098186, 11379.487923043484, 13213.405396757169, 13217.14088584239, 13217.471301147847, 7077.716891324392, 7830.725924592726, 7890.447668663253, 8014.15851335613, 8024.277814996562, 8212.0, 8239.423240373237, 8398.060678762384, 8420.368472364597, 8424.410291279673, 3687.391580389206, 3739.1586892949413, 3756.5799968097226, 3803.31540322739, 3823.847046118124, 1654.2390500369966, 1821.2077746180453, 1865.1886429403253, 1870.4108205915093, 1871.606962537855, 814.3444527155578, 851.3955091884831, 889.1860604367148, 895.3463971300987, 944.3248077624531, 19310.420687379403, 20145.58980871325, 21262.149514019577, 21313.0, 21692.240771573233, 858.4677499950071, 884.8318819424288, 887.7913690282778, 908.7909780750465, 963.0081016552962, 10487.056164945192, 10866.61966831157, 10933.0, 10937.343759374464, 10972.670819875417, 518691.0, 542997.0, 544313.8352924709, 549274.0, 549554.0, 76871.40019365273, 123340.0, 129559.0, 129843.0, 135354.0, 817.6361519124051, 988.4761076629397, 999.0159692702682, 1016.7966406718685, 1018.4093225928943, 18421.0, 18686.0, 18805.717914231023, 18927.0, 19185.904070480115, 52652.736736316074, 55849.464712711226, 56439.0, 56985.715071424376, 57072.71463642655, 74766.62616687099, 75292.0, 76001.0, 77908.0, 82644.0, 6050.0, 7828.334591559781, 7906.209379062278, 7992.841038049566, 8141.389395795458, 12650.746985060063, 12701.42843572023, 12767.93616031914, 13118.0, 13186.208827470382, 10444.529996150344, 10785.838212426977, 11161.553537858395, 11267.605633802777, 11279.887201127884, 10103.0, 10386.636467723594, 10987.285826421257, 11027.669169924915, 11371.090312775124, 11240.437978101227, 11348.943255283672, 11712.414379281172, 11774.882251177378, 11891.46488408041, 6994.755183568551, 7887.881681774663, 7927.176749347797, 8152.0, 8336.708215212439, 10426.947865260625, 10728.537451860564, 10845.620403285848, 11033.0, 11253.606123785628], "value": 9299.797281875322, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1491.0382803092007, 1520.2246854104408, 1698.6178109925268, 1853.9258429662814, 1876.18386002089, 8426.620802063906, 8920.553972301392, 8933.508657023862, 8934.865977010342, 8941.821163576724, 7775.1447340792465, 8036.879446808302, 8438.620262088205, 8661.480311181334, 8690.565471726404, 2341.0869760793303, 2370.798482129021, 2371.7983971362455, 2372.5847976604114, 2374.1215750172373, 2348.4246359641825, 2371.1226846066893, 2398.46034642205, 2403.0, 2404.95190096198, 2362.0905951208824, 2369.289213236022, 2373.4422410733464, 2401.7718316759965, 2408.1812183857487, 2342.9882850585636, 2349.9177528786495, 2357.092519380034, 2361.5394997975486, 2383.9642405363934, 2261.027758064032, 2278.931632051041, 2297.1041293895396, 2299.7930186283147, 2302.309307207831, 8653.913460865373, 8804.427712198687, 8806.559672016374, 8813.911860881437, 8821.0, 5147.0, 5307.708076055816, 5312.203169524563, 5317.41508434072, 5319.7340132993195, 3620.294042661682, 3639.6542328478754, 3711.6473934976134, 3715.405535114369, 3721.5162028936334, 1092.3282181458346, 1096.8683757949018, 1100.1199040767433, 1108.3460758152728, 1118.3122379736403, 11412.258203216765, 11481.196316257781, 11485.885141148565, 11487.0810335173, 11491.942540287246, 4474.463064432289, 4645.349651048856, 4751.691140075884, 4756.6670333077045, 4770.666053376231, 9809.509524523744, 9824.803503930025, 9833.754156146011, 9835.508224588742, 9848.655297064568, 5298.496642818926, 5302.867428314245, 5303.496167864047, 5310.230016647642, 5313.256144139824, 4141.316682747373, 4238.660907127422, 4390.5828946250695, 4823.493533179036, 4883.731394773251, 3822.120912190212, 3824.521934758155, 3950.506186726659, 3965.20695860829, 4000.4799376081505, 1091.826286741756, 1261.148724610884, 1286.5625687266374, 1291.8708129187007, 1296.6887946892678, 792.2038351456749, 872.5244741615835, 898.7708134425679, 919.9080091990882, 935.5088578496344, 5788.421157884178, 5807.970960145172, 5941.910871336933, 6098.573099882966, 6261.248650161919, 943.1370296179057, 952.5570609666538, 952.6665667016489, 959.3380567408466, 985.280745056113, 3837.846486140579, 4045.777238650127, 4051.493563304587, 4143.461350024538, 4154.397612346193, 10562.471876406149, 10605.469726513644, 10781.245312828183, 10788.352698838095, 10811.243212975016, 10543.789124217472, 10674.946625266824, 10685.198610104273, 10685.786284274427, 10688.465576721133, 6450.064980505923, 8048.637811298388, 8058.0733215679775, 8265.504069755834, 8270.172982701682, 5857.556687643558, 5996.970015149896, 6017.176357482453, 6111.7249723763425, 6173.907391389043, 9846.310758246855, 9967.202623790225, 9998.800023999625, 10013.94853540397, 10029.147522460673, 9749.853752193581, 10057.446840423847, 10100.292979491365, 10245.180385569285, 10259.897401025893, 6825.726970921108, 7059.788206353817, 7283.453740969554, 7406.370458511087, 7441.851162976818, 7980.561069141253, 8272.0, 8272.296854767412, 8351.540665263468, 8362.331013518782, 7318.194998550049, 7345.853082938204, 7374.410047196318, 7399.186089530255, 7412.1846596875375, 7095.858082838417, 7135.286471352823, 7226.0, 7267.890981635174, 7351.8162045949275, 6615.503837212132, 6805.1833779944745, 6881.41507971828, 6941.201761797458, 6945.791626251221, 4931.5315045070665, 4937.728424936663, 4984.252362145708, 4985.177445721415, 5058.671186372874, 4967.230079337708, 5027.868729535825, 5034.823781167642, 5107.744612769421, 5120.769565369493], "value": 4582.121051585289, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.6058835864715, 297.58189743410514, 303.2282840171763, 311.81758671177363, 314.3320444056379, 4358.455193100863, 4907.1412502812, 5173.172292433212, 5537.5081213453905, 5618.381977982429, 4252.489701235848, 4815.975920120394, 4973.278874563186, 5030.37120359955, 5398.865028374293, 3852.3451013327804, 4202.411662367271, 4216.9578304216875, 4286.271333873249, 4299.31211006239, 3714.7213958953184, 4157.334826427772, 4230.238557059728, 4248.532661407243, 4290.420793192926, 3230.6607806180364, 4017.7790221537803, 4156.688248381369, 4194.874153775377, 4237.682173836959, 3569.678728914384, 4141.337386018223, 4147.688923330762, 4148.958510414887, 4166.874993750162, 3547.3260080584605, 3548.432250839853, 3548.9112772180642, 3564.3940530109685, 3564.5048466073854, 5109.744512774384, 5211.869703257449, 5428.782848686048, 5433.1850222466555, 5469.480399362054, 4548.658850586219, 4555.567221114022, 4757.286407038937, 4799.880002999918, 4822.180229361017, 5246.973765131188, 5400.83797486076, 5773.307203135609, 5902.704864756745, 5925.674087925162, 2882.884684612613, 3480.9477857832153, 3814.5422549293985, 3898.532176138853, 3904.1020565269864, 5034.11902916988, 6085.14807926895, 6096.878062438771, 6125.264968203799, 6126.23422072241, 3774.358359078963, 3934.940975885364, 3955.9011024724614, 3968.3849003403943, 3985.1830374773103, 3370.915727106842, 3886.436466712311, 4038.818253178614, 4047.4821941771834, 4077.2478576960434, 4588.783972247357, 4646.186917289461, 4681.231608555077, 4691.906161876744, 4733.621310295168, 5278.891432799079, 5304.681719096867, 5449.727513624303, 5710.371859095496, 5808.099744539603, 1291.9354032298347, 1348.871857173567, 1373.3407964177252, 1385.452746165259, 1388.4931999819994, 718.9748358807417, 766.2375935943771, 818.705266104202, 860.9096044915319, 904.1771987491351, 4095.6313931746286, 4437.822487100544, 4791.305260737174, 5208.0, 5342.572594192456, 4531.864044078683, 4820.927686084711, 5682.210103817284, 5740.483356497935, 5863.325717542537, 5108.6168537359845, 5346.732663366816, 5469.234307196995, 5544.889102218014, 5597.468240517144, 2500.9374765631005, 2671.2253446500686, 2926.678065412803, 3174.968020466903, 3191.329820737671, 5377.623566350396, 5887.470127688529, 5931.822045338647, 6168.722407491674, 6223.875522489616, 4911.68074075184, 5158.458361872024, 5873.383294754075, 6058.969705151446, 6072.635641861414, 6088.695565221633, 6134.78528251509, 6157.384261573807, 6190.3190649028575, 6195.31851496335, 5626.971865140812, 5934.329551701665, 5971.343152253162, 5998.4601385875485, 6013.482112963965, 5928.940710593011, 5953.583249172516, 5959.0763431668165, 5963.4334738199805, 5981.162637230704, 5756.280464941882, 5872.735726892385, 6097.512199023979, 6145.0, 6172.598781079216, 3382.729381649511, 3865.4974853268895, 3970.8431796721097, 3992.48097747291, 3993.940090898697, 4843.457184977915, 5899.587028907935, 6070.908936366046, 6087.8782424350375, 6094.841980023782, 4821.421429428351, 5026.396832380138, 5904.380040095728, 5937.732802024006, 5983.491403230774, 5848.748231930652, 5880.441358070976, 5893.764249429974, 5924.348321684697, 5930.525557955267, 5584.804531841366, 5677.51741102011, 5765.56758243125, 5882.941170588239, 5891.1752354669525, 4778.989236780806, 4958.330625365674, 5041.495850415075, 5295.920561191509, 5601.971990140023, 5759.1649210865025, 5822.777216784522, 5856.535866033491, 5860.970695146497, 5888.911666324922], "value": 4168.150610708386, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.5005496152694, 754.2382193984078, 778.4784194589624, 810.3800592546703, 833.9457935234213, 14960.326785294661, 15737.685246295066, 15760.842391576081, 15779.842201577982, 15794.973326733769, 14067.648308792259, 14117.29413529322, 14154.929225353859, 14159.575212743634, 14162.50431234907, 693.109354479494, 698.2110215456539, 716.9426445884317, 850.2560259772699, 879.9648014079429, 862.2584577263548, 877.2192748453881, 877.6840337478502, 878.9648414063462, 879.6745204274428, 925.2782829393084, 926.3561824531953, 929.2798081486834, 930.4696323095839, 930.5672862119113, 715.0739791969412, 882.9558522073935, 913.9451632902014, 915.6703586708812, 917.958691858868, 685.1812084558954, 685.4721864164616, 685.7702669605688, 686.1046334533432, 687.6424259385142, 14817.925910370486, 14820.555383338517, 14822.40710371584, 14840.183789891551, 14872.553823385315, 13611.52359667407, 13766.105203161762, 13920.721585568333, 13988.510402135978, 14274.286285685777, 11035.503402346914, 12255.75488490234, 12429.31638759868, 12527.686807829787, 12578.245305281713, 3902.804859757001, 4570.382998295221, 4598.540145985408, 4635.165670179366, 4676.018036212399, 103958.34890267324, 106545.46727266314, 108286.0, 108620.0, 109671.90328096776, 10418.322809017389, 10489.370637761758, 10686.0, 10775.0, 11038.889611103787, 20284.594308113756, 20523.0, 20526.076326565042, 20550.897245513977, 20594.794052059588, 6536.934630653727, 7572.697092116254, 7673.654685539164, 7730.149683534807, 7953.403494737918, 4372.672049596293, 4387.956120438755, 4434.645228381722, 4567.9086418271445, 4612.654050946193, 3432.982835085809, 3500.9124771880906, 3533.505309256706, 3542.096765324877, 3556.964430355715, 645.2676212498848, 659.7662371365482, 664.0736173038584, 669.7923643670478, 681.9659017049128, 632.6046221111806, 643.784332248702, 669.1535207961932, 672.3108813466237, 687.2303020616941, 7652.660784362825, 8304.837322774816, 8633.266052479077, 9637.18083962857, 9766.267529935283, 565.7369323264619, 604.5767962426319, 621.65498148528, 633.0757094641874, 678.081199974038, 4078.4290199372113, 4958.553730164303, 5044.899102018013, 5105.540501354896, 5140.691558506502, 88411.0, 102081.48959255304, 103503.44744828834, 106198.0, 135815.0, 126285.0, 129285.0, 129922.0, 131478.0, 133444.33277833744, 74425.0, 83415.582922085, 106589.0, 107301.39047914288, 109483.45258273657, 9414.340996130206, 9792.853107203255, 9915.405075695482, 9930.702078937644, 9941.403515789076, 48263.0, 63381.415464611906, 63852.7229455399, 63868.0, 64112.67943660252, 91168.0, 97121.0, 98526.0, 98991.0, 99099.7697316164, 12792.936035319764, 13084.934575327443, 13183.934080329536, 13233.735325293634, 13682.657933551343, 14855.257237138316, 15018.098914065191, 15029.699406012038, 15151.757348092757, 15310.157941313335, 14003.43986240539, 14231.928840355731, 14248.928755356572, 14688.339024743698, 14851.702965940405, 13756.312184390541, 14135.858641413453, 14449.988500804842, 14717.0, 14902.403903843724, 13505.932470337586, 13688.93155534216, 13979.370928308454, 14350.171993586948, 14391.136531807706, 10995.560177592806, 11409.412740527789, 11438.542458301576, 11571.13216508782, 11588.334683022784, 9786.902130978884, 11295.3787541686, 11348.886511134782, 11564.248323858923, 11729.35488548138], "value": 7803.8673287144275, "unit": "score"}], "name": "dromaeo_css", "value": 7258.408696357192, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 116.0, 112.0, 113.0, 114.0, 115.0, 111.0, 114.0, 124.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 111.0, 134.0, 159.0, 107.0, 150.0, 161.0, 138.0, 166.0, 108.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 184.0, 183.0, 186.0, 186.0, 185.0, 184.0, 185.0, 181.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 78.0, 156.0, 59.0, 58.0, 59.0, 63.0, 145.0, 58.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 129.0, 69.0, 69.0, 92.0, 122.0, 68.0, 70.0, 116.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 86.0, 89.0, 85.0, 87.0, 84.0, 89.0, 85.0, 88.0, 84.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 104.0, 123.0, 93.0, 94.0, 93.0, 93.0, 93.0, 91.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 86.0, 86.0, 85.0, 85.0, 86.0, 84.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 58.0, 57.0, 58.0, 54.0, 58.0, 55.0, 56.0, 56.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 79.0, 77.0, 72.0, 71.0, 72.0, 94.0, 60.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 99.0, 108.0, 107.0, 89.0, 84.0, 87.0, 87.0, 90.0, 90.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 137.0, 137.0, 139.0, 139.0, 142.0, 153.0, 187.0, 179.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 170.0, 171.0, 171.0, 170.0, 169.0, 170.0, 174.0, 176.0, 171.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 66.0, 68.0, 67.0, 67.0, 68.0, 67.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1491.2, "alertThreshold": 2.0}]} 23:41:57 INFO - Return code: 0 23:41:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:41:57 INFO - # TBPL SUCCESS # 23:41:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:41:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:41:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:41:57 INFO - 2016-09-01 23:41:57.718 screenresolution[1943:16357] starting screenresolution argv=screenresolution get 23:41:57 INFO - 2016-09-01 23:41:57.721 screenresolution[1943:16357] Display 0: 1600x1200x32@60 23:41:57 INFO - 2016-09-01 23:41:57.726 screenresolution[1944:16361] starting screenresolution argv=screenresolution list 23:41:57 INFO - Available Modes on Display 0 23:41:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:41:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:41:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:41:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:41:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:41:57 INFO - Intel Iris: 23:41:57 INFO - Chipset Model: Intel Iris 23:41:57 INFO - Type: GPU 23:41:57 INFO - Bus: Built-In 23:41:57 INFO - VRAM (Dynamic, Max): 1536 MB 23:41:57 INFO - Vendor: Intel (0x8086) 23:41:57 INFO - Device ID: 0x0a2e 23:41:57 INFO - Revision ID: 0x0009 23:41:57 INFO - Displays: 23:41:57 INFO - Display: 23:41:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:41:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:41:57 INFO - Display Serial Number: 1600x1200 60 23:41:57 INFO - Main Display: Yes 23:41:57 INFO - Mirror: Off 23:41:57 INFO - Online: Yes 23:41:57 INFO - Rotation: Supported 23:41:57 INFO - Return code: 0 23:41:57 INFO - Running post-action listener: _resource_record_post_action 23:41:57 INFO - [mozharness: 2016-09-02 06:41:57.961237Z] Finished run-tests step (success) 23:41:57 INFO - Running post-run listener: _resource_record_post_run 23:41:58 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1093632; Write bytes: 703883264; Read time: 13; Write time: 1832 23:41:58 INFO - TinderboxPrint: CPU usage
53.5% 23:41:58 INFO - TinderboxPrint: I/O read bytes / time
1,093,632 / 13 23:41:58 INFO - TinderboxPrint: I/O write bytes / time
703,883,264 / 1,832 23:41:58 INFO - TinderboxPrint: CPU idle
2,254.8 (46.5%) 23:41:58 INFO - TinderboxPrint: CPU system
968.4 (20.0%) 23:41:58 INFO - TinderboxPrint: CPU user
1,630.5 (33.6%) 23:41:58 INFO - TinderboxPrint: Swap in / out
407,486,464 / 0 23:41:58 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394430976; Write bytes: 388228608; Read time: 29127; Write time: 664 23:41:58 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1093632; Write bytes: 294879744; Read time: 13; Write time: 1123 23:41:58 INFO - Running post-run listener: _upload_blobber_files 23:41:58 INFO - Blob upload gear active. 23:41:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:41:58 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 23:41:58 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:41:58 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:41:58 INFO - (blobuploader) - INFO - Open directory for files ... 23:41:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:41:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:41:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:42:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:42:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:42:00 INFO - (blobuploader) - INFO - Done attempting. 23:42:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:42:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:42:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:42:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:42:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:42:01 INFO - (blobuploader) - INFO - Done attempting. 23:42:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:42:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:42:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:42:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:42:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:42:03 INFO - (blobuploader) - INFO - Done attempting. 23:42:03 INFO - (blobuploader) - INFO - Iteration through files over. 23:42:03 INFO - Return code: 0 23:42:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:42:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:42:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c40a578412cb54a8536bd341204ffc8380077db83648e9046068e51aab6b6fd445aeab7b9abe36be2df6603acd71942381a6325692e5c70552ae091bf3028885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c8b0a5f8fe42b01db8de09fa3505cf3839ead6c3bc7a6c73b1acb91f01c8673f4f65c31ea6bfcd39daec1a4280670d1efa88a199eb1924dd43cbef72f54be024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/07746ec856ac4324a429a8966bb21b5456f52bc782db52a03f011857d650fc91d96e940726305d389a2a8539356f6c9e764346d2d2ad77cc321deb24a82e98f9"} 23:42:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:42:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:42:03 INFO - Contents: 23:42:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c40a578412cb54a8536bd341204ffc8380077db83648e9046068e51aab6b6fd445aeab7b9abe36be2df6603acd71942381a6325692e5c70552ae091bf3028885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c8b0a5f8fe42b01db8de09fa3505cf3839ead6c3bc7a6c73b1acb91f01c8673f4f65c31ea6bfcd39daec1a4280670d1efa88a199eb1924dd43cbef72f54be024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/07746ec856ac4324a429a8966bb21b5456f52bc782db52a03f011857d650fc91d96e940726305d389a2a8539356f6c9e764346d2d2ad77cc321deb24a82e98f9"} 23:42:03 INFO - Running post-run listener: copy_logs_to_upload_dir 23:42:03 INFO - Copying logs to upload dir... 23:42:03 INFO - mkdir: /builds/slave/test/build/upload/logs 23:42:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.601398 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 23:42:03.879862) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 23:42:03.883842) ========= 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.hVekxdkAUn/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.w3vt70BBuk/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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/Autoland/sha512/c40a578412cb54a8536bd341204ffc8380077db83648e9046068e51aab6b6fd445aeab7b9abe36be2df6603acd71942381a6325692e5c70552ae091bf3028885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c8b0a5f8fe42b01db8de09fa3505cf3839ead6c3bc7a6c73b1acb91f01c8673f4f65c31ea6bfcd39daec1a4280670d1efa88a199eb1924dd43cbef72f54be024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/07746ec856ac4324a429a8966bb21b5456f52bc782db52a03f011857d650fc91d96e940726305d389a2a8539356f6c9e764346d2d2ad77cc321deb24a82e98f9"} build_url:https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011197 build_url: 'https://queue.taskcluster.net/v1/task/IKCEuYZUS0CZmcZ26MpSRg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c40a578412cb54a8536bd341204ffc8380077db83648e9046068e51aab6b6fd445aeab7b9abe36be2df6603acd71942381a6325692e5c70552ae091bf3028885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c8b0a5f8fe42b01db8de09fa3505cf3839ead6c3bc7a6c73b1acb91f01c8673f4f65c31ea6bfcd39daec1a4280670d1efa88a199eb1924dd43cbef72f54be024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/07746ec856ac4324a429a8966bb21b5456f52bc782db52a03f011857d650fc91d96e940726305d389a2a8539356f6c9e764346d2d2ad77cc321deb24a82e98f9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 23:42:03.916688) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:42:03.917018) ========= 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.hVekxdkAUn/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.w3vt70BBuk/Listeners TMPDIR=/var/folders/30/cty5_80j7yx3964y35jzqrp000000w/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.005382 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 23:42:03.965307) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 23:42:03.965633) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 23:42:03.965967) ========= ========= Total master_lag: 0.17 =========