builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0091 starttime: 1470959531.48 results: success (0) buildid: 20160811141917 builduid: 8449227432b54e12bd15a1bed8bff7d8 revision: 0aea46757bba10aac413524e50979808189a6d15 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:11.478971) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:11.479810) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:11.480410) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:11.506251) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:11.506533) ========= 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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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-08-11 16:52:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.24M=0.001s 2016-08-11 16:52:11 (8.24 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096167 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:11.625677) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:11.626002) ========= 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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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.021537 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:11.677054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:11.677909) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 0aea46757bba10aac413524e50979808189a6d15 --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 0aea46757bba10aac413524e50979808189a6d15 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6gCyCIWkZE/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.RlG7UHv29Q/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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-08-11 16:52:11,755 truncating revision to first 12 chars 2016-08-11 16:52:11,755 Setting DEBUG logging. 2016-08-11 16:52:11,755 attempt 1/10 2016-08-11 16:52:11,755 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/0aea46757bba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-11 16:52:12,225 unpacking tar archive at: autoland-0aea46757bba/testing/mozharness/ program finished with exit code 0 elapsedTime=0.750118 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:12.451592) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:12.451906) ========= script_repo_revision: 0aea46757bba10aac413524e50979808189a6d15 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:12.452307) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:12.452576) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 16:52:12.470847) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-11 16:52:12.471462) ========= /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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 16:52:12 INFO - MultiFileLogger online at 20160811 16:52:12 in /builds/slave/test 16:52:12 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 16:52:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:52:12 INFO - {'append_to_log': False, 16:52:12 INFO - 'base_work_dir': '/builds/slave/test', 16:52:12 INFO - 'blob_upload_branch': 'Autoland', 16:52:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:52:12 INFO - 'branch': 'Autoland', 16:52:12 INFO - 'buildbot_json_path': 'buildprops.json', 16:52:12 INFO - 'config_files': ('talos/mac_config.py',), 16:52:12 INFO - 'default_actions': ('clobber', 16:52:12 INFO - 'read-buildbot-config', 16:52:12 INFO - 'download-and-extract', 16:52:12 INFO - 'populate-webroot', 16:52:12 INFO - 'create-virtualenv', 16:52:12 INFO - 'install', 16:52:12 INFO - 'run-tests'), 16:52:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:52:12 INFO - 'download_minidump_stackwalk': True, 16:52:12 INFO - 'download_symbols': 'ondemand', 16:52:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:52:12 INFO - 'tooltool.py': '/tools/tooltool.py', 16:52:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:52:12 INFO - '/tools/misc-python/virtualenv.py')}, 16:52:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:52:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:52:12 INFO - 'installer_path': 'installer.exe', 16:52:12 INFO - 'log_level': 'info', 16:52:12 INFO - 'log_name': 'talos', 16:52:12 INFO - 'log_to_console': True, 16:52:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:52:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:52:12 INFO - 'opt_config_files': (), 16:52:12 INFO - 'pip_index': False, 16:52:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:52:12 INFO - 'cmd': ('bash', 16:52:12 INFO - '-c', 16:52:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:52:12 INFO - 'enabled': True, 16:52:12 INFO - 'halt_on_failure': False, 16:52:12 INFO - 'name': 'check_screen_resolution'},), 16:52:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:52:12 INFO - 'cmd': ('bash', 16:52:12 INFO - '-c', 16:52:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:52:12 INFO - 'enabled': True, 16:52:12 INFO - 'halt_on_failure': False, 16:52:12 INFO - 'name': 'check_screen_resolution'},), 16:52:12 INFO - 'run_cmd_checks_enabled': True, 16:52:12 INFO - 'sps_profile': False, 16:52:12 INFO - 'sps_profile_interval': 0, 16:52:12 INFO - 'suite': 'dromaeojs', 16:52:12 INFO - 'system_bits': '32', 16:52:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:52:12 INFO - 'title': 't-yosemite-r7-0091', 16:52:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:52:12 INFO - 'use_talos_json': True, 16:52:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:52:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:52:12 INFO - 'work_dir': 'build'} 16:52:12 INFO - [mozharness: 2016-08-11 23:52:12.665439Z] Running clobber step. 16:52:12 INFO - Running pre-action listener: _resource_record_pre_action 16:52:12 INFO - Running main action method: clobber 16:52:12 INFO - rmtree: /builds/slave/test/build 16:52:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:52:12 INFO - Running post-action listener: _resource_record_post_action 16:52:12 INFO - [mozharness: 2016-08-11 23:52:12.878190Z] Finished clobber step (success) 16:52:12 INFO - [mozharness: 2016-08-11 23:52:12.878322Z] Running read-buildbot-config step. 16:52:12 INFO - Running pre-action listener: _resource_record_pre_action 16:52:12 INFO - Running main action method: read_buildbot_config 16:52:12 INFO - Using buildbot properties: 16:52:12 INFO - { 16:52:12 INFO - "project": "", 16:52:12 INFO - "product": "firefox", 16:52:12 INFO - "script_repo_revision": "production", 16:52:12 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:52:12 INFO - "repository": "", 16:52:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:52:12 INFO - "buildid": "20160811141917", 16:52:12 INFO - "pgo_build": "False", 16:52:12 INFO - "basedir": "/builds/slave/test", 16:52:12 INFO - "buildnumber": 76, 16:52:12 INFO - "slavename": "t-yosemite-r7-0091", 16:52:12 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 16:52:12 INFO - "platform": "yosemite_r7", 16:52:12 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:52:12 INFO - "branch": "autoland", 16:52:12 INFO - "repo_path": "integration/autoland", 16:52:12 INFO - "revision": "0aea46757bba10aac413524e50979808189a6d15", 16:52:12 INFO - "stage_platform": "macosx64", 16:52:12 INFO - "builduid": "8449227432b54e12bd15a1bed8bff7d8", 16:52:12 INFO - "slavebuilddir": "test" 16:52:12 INFO - } 16:52:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:52:12 INFO - Running post-action listener: _resource_record_post_action 16:52:12 INFO - [mozharness: 2016-08-11 23:52:12.881522Z] Finished read-buildbot-config step (success) 16:52:12 INFO - [mozharness: 2016-08-11 23:52:12.881633Z] Running download-and-extract step. 16:52:12 INFO - Running pre-action listener: _resource_record_pre_action 16:52:12 INFO - Running main action method: download_and_extract 16:52:12 INFO - mkdir: /builds/slave/test/build/tests 16:52:12 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')]} 16:52:12 INFO - trying https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:52:12 INFO - Downloading https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/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 16:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/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 16:52:14 INFO - Downloaded 1199 bytes. 16:52:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:52:14 INFO - Using the following test package requirements: 16:52:14 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:52:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:52:14 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:52:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:52:14 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:52:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:52:14 INFO - u'jsshell-mac.zip'], 16:52:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:52:14 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:52:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:52:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:52:14 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:52:14 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:52:14 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:52:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:52:14 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:52:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:52:14 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:52:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:52:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:14 INFO - trying https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:52:14 INFO - Downloading https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/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 16:52:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/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 16:52:15 INFO - Downloaded 16328720 bytes. 16:52:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:52:22 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 16:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:22 INFO - trying https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:52:22 INFO - Downloading https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/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 16:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/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 16:52:22 INFO - Downloaded 16328720 bytes. 16:52:22 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:29 INFO - trying https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:52:29 INFO - Downloading https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/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 16:52:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/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 16:52:30 INFO - Downloaded 14413719 bytes. 16:52:30 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 16:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:30 INFO - trying https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:52:30 INFO - Downloading https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:52:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/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 16:52:33 INFO - Downloaded 110910404 bytes. 16:52:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:52:33 INFO - mkdir: /builds/slave/test/properties 16:52:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:52:33 INFO - Writing to file /builds/slave/test/properties/build_url 16:52:33 INFO - Contents: 16:52:33 INFO - build_url:https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:52:34 INFO - Running post-action listener: _resource_record_post_action 16:52:34 INFO - Running post-action listener: set_extra_try_arguments 16:52:34 INFO - [mozharness: 2016-08-11 23:52:34.184476Z] Finished download-and-extract step (success) 16:52:34 INFO - [mozharness: 2016-08-11 23:52:34.184631Z] Running populate-webroot step. 16:52:34 INFO - Running pre-action listener: _resource_record_pre_action 16:52:34 INFO - Running main action method: populate_webroot 16:52:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:52:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:52:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:52:34 INFO - '--tppagecycles', 16:52:34 INFO - '7'], 16:52:34 INFO - 'tests': ['tsvgm']}}, 16:52:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:52:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:52:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:52:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:52:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:52:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:52:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:52:34 INFO - 'talos_options': ['--disable-e10s'], 16:52:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:52:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:52:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:52:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:52:34 INFO - 'talos_options': ['--disable-e10s'], 16:52:34 INFO - 'tests': ['damp', 'tps']}, 16:52:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:52:34 INFO - 'tests': ['damp', 'tps']}, 16:52:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:52:34 INFO - 'tests': ['dromaeo_dom']}, 16:52:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:52:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:52:34 INFO - 'tests': ['basic_compositor_video']}, 16:52:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:52:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:52:34 INFO - 'tests': ['a11yr', 16:52:34 INFO - 'ts_paint', 16:52:34 INFO - 'tpaint', 16:52:34 INFO - 'sessionrestore', 16:52:34 INFO - 'sessionrestore_no_auto_restore', 16:52:34 INFO - 'tabpaint']}, 16:52:34 INFO - 'other-e10s': {'tests': ['a11yr', 16:52:34 INFO - 'ts_paint', 16:52:34 INFO - 'tpaint', 16:52:34 INFO - 'sessionrestore', 16:52:34 INFO - 'sessionrestore_no_auto_restore', 16:52:34 INFO - 'tabpaint']}, 16:52:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:52:34 INFO - 'ts_paint', 16:52:34 INFO - 'tpaint', 16:52:34 INFO - 'sessionrestore', 16:52:34 INFO - 'sessionrestore_no_auto_restore', 16:52:34 INFO - 'tabpaint']}, 16:52:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:52:34 INFO - 'ts_paint', 16:52:34 INFO - 'tpaint', 16:52:34 INFO - 'sessionrestore', 16:52:34 INFO - 'sessionrestore_no_auto_restore', 16:52:34 INFO - 'tabpaint']}, 16:52:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:52:34 INFO - 'tests': ['a11yr', 16:52:34 INFO - 'ts_paint', 16:52:34 INFO - 'tpaint', 16:52:34 INFO - 'sessionrestore', 16:52:34 INFO - 'sessionrestore_no_auto_restore', 16:52:34 INFO - 'tabpaint']}, 16:52:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:52:34 INFO - 'tests': ['a11yr', 16:52:34 INFO - 'ts_paint', 16:52:34 INFO - 'tpaint', 16:52:34 INFO - 'sessionrestore', 16:52:34 INFO - 'sessionrestore_no_auto_restore', 16:52:34 INFO - 'tabpaint']}, 16:52:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:52:34 INFO - 'tests': ['tsvgx', 16:52:34 INFO - 'tsvgr_opacity', 16:52:34 INFO - 'tart', 16:52:34 INFO - 'tscrollx', 16:52:34 INFO - 'cart']}, 16:52:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:52:34 INFO - 'tsvgr_opacity', 16:52:34 INFO - 'tart', 16:52:34 INFO - 'tscrollx', 16:52:34 INFO - 'cart']}, 16:52:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:52:34 INFO - 'talos_options': ['--disable-e10s'], 16:52:34 INFO - 'tests': ['tp5o']}, 16:52:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:52:34 INFO - 'tests': ['tp5o']}, 16:52:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:52:34 INFO - 'talos_options': ['--disable-e10s', 16:52:34 INFO - '--xperf_path', 16:52:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:52:34 INFO - 'tests': ['tp5n']}, 16:52:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:52:34 INFO - 'talos_options': ['--xperf_path', 16:52:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:52:34 INFO - 'tests': ['tp5n']}}, 16:52:34 INFO - 'talos.zip': {'path': '', 16:52:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:52:34 INFO - Running post-action listener: _resource_record_post_action 16:52:34 INFO - [mozharness: 2016-08-11 23:52:34.203315Z] Finished populate-webroot step (success) 16:52:34 INFO - [mozharness: 2016-08-11 23:52:34.203445Z] Running create-virtualenv step. 16:52:34 INFO - Running pre-action listener: _resource_record_pre_action 16:52:34 INFO - Running main action method: create_virtualenv 16:52:34 INFO - Creating virtualenv /builds/slave/test/build/venv 16:52:34 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 16:52:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:52:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:52:34 INFO - Using real prefix '/tools/python27' 16:52:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:52:35 INFO - Installing distribute.............................................................................................................................................................................................done. 16:52:37 INFO - Installing pip.................done. 16:52:37 INFO - Return code: 0 16:52:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:52: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')]} 16:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52: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')]} 16:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:37 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 0x10286fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102904618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb77b5947f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027d3d30>, '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 0x102538db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027cbb20>, '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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 16:52:37 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 16:52:37 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 16:52:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6gCyCIWkZE/Render', 16:52:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:37 INFO - 'HOME': '/Users/cltbld', 16:52:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:37 INFO - 'LOGNAME': 'cltbld', 16:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:37 INFO - 'MOZ_NO_REMOTE': '1', 16:52:37 INFO - 'NO_EM_RESTART': '1', 16:52:37 INFO - 'PAGER': '/bin/cat', 16:52:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:37 INFO - 'PWD': '/builds/slave/test', 16:52:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:37 INFO - 'SHELL': '/bin/bash', 16:52:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlG7UHv29Q/Listeners', 16:52:37 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 16:52:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:37 INFO - 'USER': 'cltbld', 16:52:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:37 INFO - 'XPC_FLAGS': '0x0', 16:52:37 INFO - 'XPC_SERVICE_NAME': '0', 16:52:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:38 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 16:52:38 INFO - Cleaning up... 16:52:38 INFO - Return code: 0 16:52:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:38 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 0x10286fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102904618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb77b5947f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027d3d30>, '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 0x102538db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027cbb20>, '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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 16:52:38 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 16:52:38 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 16:52:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6gCyCIWkZE/Render', 16:52:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:38 INFO - 'HOME': '/Users/cltbld', 16:52:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:38 INFO - 'LOGNAME': 'cltbld', 16:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:38 INFO - 'MOZ_NO_REMOTE': '1', 16:52:38 INFO - 'NO_EM_RESTART': '1', 16:52:38 INFO - 'PAGER': '/bin/cat', 16:52:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:38 INFO - 'PWD': '/builds/slave/test', 16:52:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:38 INFO - 'SHELL': '/bin/bash', 16:52:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlG7UHv29Q/Listeners', 16:52:38 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 16:52:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:38 INFO - 'USER': 'cltbld', 16:52:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:38 INFO - 'XPC_FLAGS': '0x0', 16:52:38 INFO - 'XPC_SERVICE_NAME': '0', 16:52:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:38 INFO - Downloading/unpacking psutil>=3.1.1 16:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:52:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:52:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:52:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:52:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:52:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:52:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:52:41 INFO - Installing collected packages: psutil 16:52:41 INFO - Running setup.py install for psutil 16:52:41 INFO - building 'psutil._psutil_osx' extension 16:52:41 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 16:52:42 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 16:52:42 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 16:52:42 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 16:52:42 INFO - building 'psutil._psutil_posix' extension 16:52:42 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 16:52:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:52:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:52:42 INFO - ^ 16:52:42 INFO - 1 warning generated. 16:52:42 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 16:52:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:52:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:52:42 INFO - Successfully installed psutil 16:52:42 INFO - Cleaning up... 16:52:42 INFO - Return code: 0 16:52:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10286fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102904618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb77b5947f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027d3d30>, '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 0x102538db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027cbb20>, '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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 16:52:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:52:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6gCyCIWkZE/Render', 16:52:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:42 INFO - 'HOME': '/Users/cltbld', 16:52:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:42 INFO - 'LOGNAME': 'cltbld', 16:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:42 INFO - 'MOZ_NO_REMOTE': '1', 16:52:42 INFO - 'NO_EM_RESTART': '1', 16:52:42 INFO - 'PAGER': '/bin/cat', 16:52:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:42 INFO - 'PWD': '/builds/slave/test', 16:52:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:42 INFO - 'SHELL': '/bin/bash', 16:52:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlG7UHv29Q/Listeners', 16:52:42 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 16:52:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:42 INFO - 'USER': 'cltbld', 16:52:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:42 INFO - 'XPC_FLAGS': '0x0', 16:52:42 INFO - 'XPC_SERVICE_NAME': '0', 16:52:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:52:43 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 16:52:43 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 16:52:43 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 16:52:43 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 16:52:43 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 16:52:43 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 16:52:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:52:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:52:45 INFO - Installing collected packages: mozsystemmonitor 16:52:45 INFO - Running setup.py install for mozsystemmonitor 16:52:45 INFO - Successfully installed mozsystemmonitor 16:52:45 INFO - Cleaning up... 16:52:45 INFO - Return code: 0 16:52:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:52:45 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')]} 16:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:45 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')]} 16:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:45 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 0x10286fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102904618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb77b5947f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027d3d30>, '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 0x102538db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027cbb20>, '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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 16:52:45 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 16:52:45 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 16:52:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6gCyCIWkZE/Render', 16:52:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:45 INFO - 'HOME': '/Users/cltbld', 16:52:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:45 INFO - 'LOGNAME': 'cltbld', 16:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:45 INFO - 'MOZ_NO_REMOTE': '1', 16:52:45 INFO - 'NO_EM_RESTART': '1', 16:52:45 INFO - 'PAGER': '/bin/cat', 16:52:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:45 INFO - 'PWD': '/builds/slave/test', 16:52:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:45 INFO - 'SHELL': '/bin/bash', 16:52:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlG7UHv29Q/Listeners', 16:52:45 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 16:52:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:45 INFO - 'USER': 'cltbld', 16:52:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:45 INFO - 'XPC_FLAGS': '0x0', 16:52:45 INFO - 'XPC_SERVICE_NAME': '0', 16:52:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:46 INFO - Downloading/unpacking jsonschema==2.5.1 16:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:52:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:52:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:52:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:52:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:52:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:52:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:52:48 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 16:52:48 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 16:52:48 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 16:52:48 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 16:52:48 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 16:52:48 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 16:52:48 INFO - Downloading functools32-3.2.3-2.tar.gz 16:52:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:52:48 INFO - warning: no files found matching '*.txt' 16:52:48 INFO - no previously-included directories found matching 'build' 16:52:48 INFO - no previously-included directories found matching 'dist' 16:52:48 INFO - no previously-included directories found matching '.git*' 16:52:48 INFO - Installing collected packages: jsonschema, functools32 16:52:48 INFO - Running setup.py install for functools32 16:52:49 INFO - warning: no files found matching '*.txt' 16:52:49 INFO - no previously-included directories found matching 'build' 16:52:49 INFO - no previously-included directories found matching 'dist' 16:52:49 INFO - no previously-included directories found matching '.git*' 16:52:49 INFO - Successfully installed jsonschema functools32 16:52:49 INFO - Cleaning up... 16:52:49 INFO - Return code: 0 16:52:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:49 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 0x10286fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102904618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb77b5947f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027d3d30>, '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 0x102538db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027cbb20>, '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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 16:52:49 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 16:52:49 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 16:52:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6gCyCIWkZE/Render', 16:52:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:49 INFO - 'HOME': '/Users/cltbld', 16:52:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:49 INFO - 'LOGNAME': 'cltbld', 16:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:49 INFO - 'MOZ_NO_REMOTE': '1', 16:52:49 INFO - 'NO_EM_RESTART': '1', 16:52:49 INFO - 'PAGER': '/bin/cat', 16:52:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:49 INFO - 'PWD': '/builds/slave/test', 16:52:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:49 INFO - 'SHELL': '/bin/bash', 16:52:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlG7UHv29Q/Listeners', 16:52:49 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 16:52:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:49 INFO - 'USER': 'cltbld', 16:52:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:49 INFO - 'XPC_FLAGS': '0x0', 16:52:49 INFO - 'XPC_SERVICE_NAME': '0', 16:52:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:52:49 INFO - Cleaning up... 16:52:49 INFO - Return code: 0 16:52:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:49 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 0x10286fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102904618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb77b5947f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027d3d30>, '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 0x102538db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027cbb20>, '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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 16:52:49 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 16:52:49 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 16:52:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6gCyCIWkZE/Render', 16:52:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:49 INFO - 'HOME': '/Users/cltbld', 16:52:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:49 INFO - 'LOGNAME': 'cltbld', 16:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:49 INFO - 'MOZ_NO_REMOTE': '1', 16:52:49 INFO - 'NO_EM_RESTART': '1', 16:52:49 INFO - 'PAGER': '/bin/cat', 16:52:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:49 INFO - 'PWD': '/builds/slave/test', 16:52:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:49 INFO - 'SHELL': '/bin/bash', 16:52:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlG7UHv29Q/Listeners', 16:52:49 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 16:52:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:49 INFO - 'USER': 'cltbld', 16:52:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:49 INFO - 'XPC_FLAGS': '0x0', 16:52:49 INFO - 'XPC_SERVICE_NAME': '0', 16:52:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:49 INFO - Downloading/unpacking blobuploader==1.2.4 16:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:52:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:52:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:52:52 INFO - Downloading blobuploader-1.2.4.tar.gz 16:52:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:52:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:52:52 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 16:52:52 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 16:52:52 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 16:52:52 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 16:52:52 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 16:52:52 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 16:52:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:52:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:52:52 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 16:52:52 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 16:52:52 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 16:52:52 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 16:52:52 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 16:52:52 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 16:52:53 INFO - Downloading docopt-0.6.1.tar.gz 16:52:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:52:53 INFO - Installing collected packages: blobuploader, requests, docopt 16:52:53 INFO - Running setup.py install for blobuploader 16:52:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:52:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:52:53 INFO - Running setup.py install for requests 16:52:54 INFO - Running setup.py install for docopt 16:52:54 INFO - Successfully installed blobuploader requests docopt 16:52:54 INFO - Cleaning up... 16:52:54 INFO - Return code: 0 16:52:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:52:54 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')]} 16:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:54 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')]} 16:52:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:54 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 0x10286fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102904618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb77b5947f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027d3d30>, '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 0x102538db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027cbb20>, '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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 16:52:54 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 16:52:54 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 16:52:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6gCyCIWkZE/Render', 16:52:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:54 INFO - 'HOME': '/Users/cltbld', 16:52:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:54 INFO - 'LOGNAME': 'cltbld', 16:52:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:54 INFO - 'MOZ_NO_REMOTE': '1', 16:52:54 INFO - 'NO_EM_RESTART': '1', 16:52:54 INFO - 'PAGER': '/bin/cat', 16:52:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:54 INFO - 'PWD': '/builds/slave/test', 16:52:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:54 INFO - 'SHELL': '/bin/bash', 16:52:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlG7UHv29Q/Listeners', 16:52:54 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 16:52:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:54 INFO - 'USER': 'cltbld', 16:52:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:54 INFO - 'XPC_FLAGS': '0x0', 16:52:54 INFO - 'XPC_SERVICE_NAME': '0', 16:52:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:52:54 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-wxgI30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:52:54 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-G9qthb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:52:54 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-9O5JvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:52:54 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-jIb_rA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:52:55 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-k7Eq9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:52:55 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-zclgej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:52:55 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-TPUsHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:52:55 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-8XiM3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:52:55 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-fCf0cY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:52:55 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-ioYqx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:52:55 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-7XHCrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:52:55 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-f2q848-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:52:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:52:55 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-_4Sz9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:52:56 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-yuq6zB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:52:56 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-RjkOIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:52:56 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-YgseYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:52:56 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-w0HvPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:52:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:52:56 INFO - Running setup.py install for manifestparser 16:52:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:52:56 INFO - Running setup.py install for mozcrash 16:52:56 INFO - Running setup.py install for mozdebug 16:52:56 INFO - Running setup.py install for mozdevice 16:52:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:52:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:52:57 INFO - Running setup.py install for mozfile 16:52:57 INFO - Running setup.py install for mozhttpd 16:52:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:52:57 INFO - Running setup.py install for mozinfo 16:52:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:52:57 INFO - Running setup.py install for mozInstall 16:52:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:52:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:52:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:52:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:52:57 INFO - Running setup.py install for mozleak 16:52:57 INFO - Running setup.py install for mozlog 16:52:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:52:57 INFO - Running setup.py install for moznetwork 16:52:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:52:58 INFO - Running setup.py install for mozprocess 16:52:58 INFO - Running setup.py install for mozprofile 16:52:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:52:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:52:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:52:58 INFO - Running setup.py install for mozrunner 16:52:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:52:58 INFO - Running setup.py install for mozscreenshot 16:52:58 INFO - Running setup.py install for moztest 16:52:58 INFO - Running setup.py install for mozversion 16:52:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:52:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:52:59 INFO - Cleaning up... 16:52:59 INFO - Return code: 0 16:52:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:52:59 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')]} 16:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:52:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:59 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')]} 16:52:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:52:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:52:59 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 0x10286fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102904618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb77b5947f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027d3d30>, '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 0x102538db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027cbb20>, '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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 16:52:59 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 16:52:59 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 16:52:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6gCyCIWkZE/Render', 16:52:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:52:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:52:59 INFO - 'HOME': '/Users/cltbld', 16:52:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:52:59 INFO - 'LOGNAME': 'cltbld', 16:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:59 INFO - 'MOZ_NO_REMOTE': '1', 16:52:59 INFO - 'NO_EM_RESTART': '1', 16:52:59 INFO - 'PAGER': '/bin/cat', 16:52:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:59 INFO - 'PWD': '/builds/slave/test', 16:52:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:52:59 INFO - 'SHELL': '/bin/bash', 16:52:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlG7UHv29Q/Listeners', 16:52:59 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 16:52:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:52:59 INFO - 'USER': 'cltbld', 16:52:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:52:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:59 INFO - 'XPC_FLAGS': '0x0', 16:52:59 INFO - 'XPC_SERVICE_NAME': '0', 16:52:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:52:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:52:59 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-AGxpjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:52:59 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)) 16:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:52:59 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-qc9fVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:52:59 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)) 16:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:52:59 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-KbloSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:52:59 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)) 16:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:52:59 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-_8JSay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:52:59 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)) 16:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:52:59 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-Wk46go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:52:59 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)) 16:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:52:59 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-WFyLgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:52:59 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)) 16:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:52:59 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-pploKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:53:00 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)) 16:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:53:00 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-t39KwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:53:00 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)) 16:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:53:00 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-fuH_On-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:53:00 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)) 16:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:53:00 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-REXd6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:53:00 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)) 16:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:53:00 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-DMjcnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:53:00 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)) 16:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:53:00 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-stlNud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:53:00 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)) 16:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:53:00 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-12ty0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:53:00 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)) 16:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:53:00 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-Dbn6_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:53:00 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)) 16:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:53:00 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-R8FcoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:53:00 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)) 16:53:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:53:00 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-zCXwv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:53:01 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)) 16:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:53:01 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-eqkUNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:53:01 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)) 16:53:01 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)) 16:53:01 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)) 16:53:01 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)) 16:53:01 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)) 16:53:01 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)) 16:53:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:53:01 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 16:53:01 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 16:53:01 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 16:53:01 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 16:53:01 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 16:53:01 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 16:53:03 INFO - Downloading blessings-1.6.tar.gz 16:53:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:53:03 INFO - Installing collected packages: blessings 16:53:03 INFO - Running setup.py install for blessings 16:53:03 INFO - Successfully installed blessings 16:53:03 INFO - Cleaning up... 16:53:03 INFO - Return code: 0 16:53:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:53:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:53:04 INFO - Reading from file tmpfile_stdout 16:53:04 INFO - Current package versions: 16:53:04 INFO - blessings == 1.6 16:53:04 INFO - blobuploader == 1.2.4 16:53:04 INFO - docopt == 0.6.1 16:53:04 INFO - functools32 == 3.2.3-2 16:53:04 INFO - jsonschema == 2.5.1 16:53:04 INFO - manifestparser == 1.1 16:53:04 INFO - mozInstall == 1.12 16:53:04 INFO - mozcrash == 0.17 16:53:04 INFO - mozdebug == 0.1 16:53:04 INFO - mozdevice == 0.48 16:53:04 INFO - mozfile == 1.2 16:53:04 INFO - mozhttpd == 0.7 16:53:04 INFO - mozinfo == 0.9 16:53:04 INFO - mozleak == 0.1 16:53:04 INFO - mozlog == 3.3 16:53:04 INFO - moznetwork == 0.27 16:53:04 INFO - mozprocess == 0.23 16:53:04 INFO - mozprofile == 0.28 16:53:04 INFO - mozrunner == 6.12 16:53:04 INFO - mozscreenshot == 0.1 16:53:04 INFO - mozsystemmonitor == 0.3 16:53:04 INFO - moztest == 0.7 16:53:04 INFO - mozversion == 1.4 16:53:04 INFO - psutil == 3.1.1 16:53:04 INFO - requests == 1.2.3 16:53:04 INFO - wsgiref == 0.1.2 16:53:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:53:04 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')]} 16:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:04 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')]} 16:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:04 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 0x10286fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102904618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb77b5947f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027d3d30>, '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 0x102538db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027cbb20>, '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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 16:53:04 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 16:53:04 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 16:53:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6gCyCIWkZE/Render', 16:53:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:04 INFO - 'HOME': '/Users/cltbld', 16:53:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:04 INFO - 'LOGNAME': 'cltbld', 16:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:04 INFO - 'MOZ_NO_REMOTE': '1', 16:53:04 INFO - 'NO_EM_RESTART': '1', 16:53:04 INFO - 'PAGER': '/bin/cat', 16:53:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:04 INFO - 'PWD': '/builds/slave/test', 16:53:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:04 INFO - 'SHELL': '/bin/bash', 16:53:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlG7UHv29Q/Listeners', 16:53:04 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 16:53:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:04 INFO - 'USER': 'cltbld', 16:53:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:04 INFO - 'XPC_FLAGS': '0x0', 16:53:04 INFO - 'XPC_SERVICE_NAME': '0', 16:53:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:04 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)) 16:53:04 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)) 16:53:04 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)) 16:53:04 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)) 16:53:04 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)) 16:53:04 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)) 16:53:04 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)) 16:53:04 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)) 16:53:04 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)) 16:53:04 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)) 16:53:04 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)) 16:53:04 INFO - Cleaning up... 16:53:04 INFO - Return code: 0 16:53:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:53:04 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')]} 16:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:04 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')]} 16:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:53:04 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 0x10286fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102904618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb77b5947f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027d3d30>, '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 0x102538db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027cbb20>, '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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 16:53:04 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 16:53:04 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 16:53:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6gCyCIWkZE/Render', 16:53:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:04 INFO - 'HOME': '/Users/cltbld', 16:53:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:04 INFO - 'LOGNAME': 'cltbld', 16:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:04 INFO - 'MOZ_NO_REMOTE': '1', 16:53:04 INFO - 'NO_EM_RESTART': '1', 16:53:04 INFO - 'PAGER': '/bin/cat', 16:53:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:04 INFO - 'PWD': '/builds/slave/test', 16:53:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:04 INFO - 'SHELL': '/bin/bash', 16:53:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlG7UHv29Q/Listeners', 16:53:04 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 16:53:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:04 INFO - 'USER': 'cltbld', 16:53:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:04 INFO - 'XPC_FLAGS': '0x0', 16:53:04 INFO - 'XPC_SERVICE_NAME': '0', 16:53:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:53:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:53:04 INFO - Cleaning up... 16:53:04 INFO - Return code: 0 16:53:04 INFO - Running post-action listener: _resource_record_post_action 16:53:04 INFO - Running post-action listener: _start_resource_monitoring 16:53:04 INFO - Starting resource monitoring. 16:53:04 INFO - [mozharness: 2016-08-11 23:53:04.824352Z] Finished create-virtualenv step (success) 16:53:04 INFO - [mozharness: 2016-08-11 23:53:04.825358Z] Running install step. 16:53:04 INFO - Running pre-action listener: _resource_record_pre_action 16:53:04 INFO - Running main action method: install 16:53:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:53:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:53:05 INFO - Reading from file tmpfile_stdout 16:53:05 INFO - Detecting whether we're running mozinstall >=1.0... 16:53:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:53:05 INFO - Reading from file tmpfile_stdout 16:53:05 INFO - Output received: 16:53:05 INFO - Usage: mozinstall [options] installer 16:53:05 INFO - Options: 16:53:05 INFO - -h, --help show this help message and exit 16:53:05 INFO - -d DEST, --destination=DEST 16:53:05 INFO - Directory to install application into. [default: 16:53:05 INFO - "/builds/slave/test"] 16:53:05 INFO - --app=APP Application being installed. [default: firefox] 16:53:05 INFO - mkdir: /builds/slave/test/build/application 16:53:05 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'] 16:53:05 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 16:53:39 INFO - Reading from file tmpfile_stdout 16:53:39 INFO - Output received: 16:53:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:53:39 INFO - Running post-action listener: _resource_record_post_action 16:53:39 INFO - [mozharness: 2016-08-11 23:53:39.985016Z] Finished install step (success) 16:53:39 INFO - [mozharness: 2016-08-11 23:53:39.985163Z] Running run-tests step. 16:53:39 INFO - Running pre-action listener: _resource_record_pre_action 16:53:39 INFO - Running main action method: run_tests 16:53:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:53:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:53:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:53:40 INFO - 2016-08-11 16:53:40.000 screenresolution[1997:277781] starting screenresolution argv=screenresolution get 16:53:40 INFO - 2016-08-11 16:53:40.002 screenresolution[1997:277781] Display 0: 1600x1200x32@60 16:53:40 INFO - 2016-08-11 16:53:40.008 screenresolution[1998:277783] starting screenresolution argv=screenresolution list 16:53:40 INFO - Available Modes on Display 0 16:53:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:53:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:53:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:53:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:53:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:53:40 INFO - Intel Iris: 16:53:40 INFO - Chipset Model: Intel Iris 16:53:40 INFO - Type: GPU 16:53:40 INFO - Bus: Built-In 16:53:40 INFO - VRAM (Dynamic, Max): 1536 MB 16:53:40 INFO - Vendor: Intel (0x8086) 16:53:40 INFO - Device ID: 0x0a2e 16:53:40 INFO - Revision ID: 0x0009 16:53:40 INFO - Displays: 16:53:40 INFO - Display: 16:53:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:53:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:53:40 INFO - Display Serial Number: 1600x1200 60 16:53:40 INFO - Main Display: Yes 16:53:40 INFO - Mirror: Off 16:53:40 INFO - Online: Yes 16:53:40 INFO - Rotation: Supported 16:53:40 INFO - Return code: 0 16:53:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:53:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:53:40 INFO - Python 2.7.3 16:53:40 INFO - Return code: 0 16:53:40 INFO - grabbing minidump binary from tooltool 16:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:53:40 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 0x1027d3d30>, '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 0x102538db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027cbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:53:40 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 16:53:40 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 16:53:40 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 16:53:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:53:40 INFO - Return code: 0 16:53:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:53:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:53:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:53:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:53:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:53:40 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/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0091', '--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 16:53:40 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/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0091 --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 16:53:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6gCyCIWkZE/Render', 16:53:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:53:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:53:40 INFO - 'HOME': '/Users/cltbld', 16:53:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:53:40 INFO - 'LOGNAME': 'cltbld', 16:53:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:53:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:53:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:53:40 INFO - 'MOZ_NO_REMOTE': '1', 16:53:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:53:40 INFO - 'NO_EM_RESTART': '1', 16:53:40 INFO - 'PAGER': '/bin/cat', 16:53:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:53:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:53:40 INFO - 'PWD': '/builds/slave/test', 16:53:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:53:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:53:40 INFO - 'SHELL': '/bin/bash', 16:53:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RlG7UHv29Q/Listeners', 16:53:40 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 16:53:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:53:40 INFO - 'USER': 'cltbld', 16:53:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:53:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:53:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:53:40 INFO - 'XPC_FLAGS': '0x0', 16:53:40 INFO - 'XPC_SERVICE_NAME': '0', 16:53:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:53:40 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/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0091', '--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 16:53:40 INFO - mozversion application_buildid: 20160811141917 16:53:40 INFO - mozversion application_changeset: 0aea46757bba10aac413524e50979808189a6d15 16:53:40 INFO - mozversion application_display_name: Nightly 16:53:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:53:40 INFO - mozversion application_name: Firefox 16:53:40 INFO - mozversion application_remotingname: firefox 16:53:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:53:40 INFO - mozversion application_vendor: Mozilla 16:53:40 INFO - mozversion application_version: 51.0a1 16:53:40 INFO - mozversion platform_buildid: 20160811141917 16:53:40 INFO - mozversion platform_changeset: 0aea46757bba10aac413524e50979808189a6d15 16:53:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:53:40 INFO - mozversion platform_version: 51.0a1 16:53:40 INFO - using testdate: 1470959620 16:53:40 INFO - actual date: 1470959620 16:53:40 INFO - starting webserver on 'localhost:49370' 16:53:40 INFO - SUITE-START | Running 2 tests 16:53:40 INFO - TEST-START | dromaeo_css 16:53:40 INFO - operating with platform_type : mac_ 16:53:40 INFO - Initialising browser for dromaeo_css test... 16:53:40 INFO - TEST-INFO | started process 2008 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmpdD2fQZ/profile http://localhost:49370/getInfo.html) 16:53:43 INFO - PROCESS | 2008 | __metrics Screen width/height:1600/1200 16:53:43 INFO - PROCESS | 2008 | colorDepth:24 16:53:43 INFO - PROCESS | 2008 | Browser inner width/height: 1024/689 16:53:43 INFO - PROCESS | 2008 | __metrics 16:53:43 INFO - PROCESS | 2008 | 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" 16:53:43 INFO - PROCESS | 2008 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:53:44 INFO - TEST-INFO | 2008: exit 0 16:53:44 INFO - Browser initialized. 16:53:44 INFO - Running cycle 1/1 for dromaeo_css test... 16:53:44 INFO - TEST-INFO | started process 2012 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmpdD2fQZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:53:55 INFO - PROCESS | 2012 | RSS: Main: 243552256 16:53:55 INFO - PROCESS | 2012 | 16:54:47 INFO - PROCESS | 2012 | 1470959687608 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] 16:56:58 INFO - PROCESS | 2012 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49370/tests/dromaeo/cssquery-ext.html) 16:57:00 INFO - PROCESS | 2012 | RSS: Main: 445702144 16:57:00 INFO - PROCESS | 2012 | 17:00:03 INFO - PROCESS | 2012 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-ext.html (next: http://localhost:49370/tests/dromaeo/cssquery-jquery.html) 17:00:04 INFO - PROCESS | 2012 | RSS: Main: 384180224 17:00:04 INFO - PROCESS | 2012 | 17:03:06 INFO - PROCESS | 2012 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49370/tests/dromaeo/cssquery-mootools.html) 17:03:07 INFO - PROCESS | 2012 | RSS: Main: 348602368 17:03:07 INFO - PROCESS | 2012 | 17:06:10 INFO - PROCESS | 2012 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49370/tests/dromaeo/cssquery-prototype.html) 17:06:10 INFO - PROCESS | 2012 | RSS: Main: 357781504 17:06:10 INFO - PROCESS | 2012 | 17:09:14 INFO - PROCESS | 2012 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49370/tests/dromaeo/cssquery-yui.html) 17:09:14 INFO - PROCESS | 2012 | RSS: Main: 342507520 17:09:14 INFO - PROCESS | 2012 | 17:12:17 INFO - PROCESS | 2012 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-yui.html (next: http://localhost:49370/tests/dromaeo/cssquery-dojo.html) 17:12:18 INFO - PROCESS | 2012 | RSS: Main: 397873152 17:12:18 INFO - PROCESS | 2012 | 17:12:18 INFO - PROCESS | 2012 | __start_tp_report 17:12:18 INFO - PROCESS | 2012 | _x_x_mozilla_page_load 17:12:18 INFO - PROCESS | 2012 | _x_x_mozilla_page_load_details 17:12:18 INFO - PROCESS | 2012 | |i|pagename|runs| 17:12:18 INFO - PROCESS | 2012 | |0;dojo.html;3252;3268.313654132632;3275.1320899961515;3302.702756751895;3314.07205982325;5463.426340234278;5543.473370029846;5557.555395568355;5640.915386269204;5650.576206784493;4642.558956899097;5555.333359996796;5571.1086226203815;5613.158026296058;5621.241132447119;5084.822031228899;5116.360454943132;5127.589792816584;5160.690358578498;5161.122609156452;5095.4140273868425;5190.4550022247495;5232.764525596338;5259;5269.183276592115;5189.584833213353;5200.453952335007;5200.92198617021;5206.323177986876;5233.267342572043;64.54644906778161;4529.479109902338;5198.16829307309;5209.453007434222;5287.391949925731;5087.465816089294;5090.312807770941;5095.643304968654;5145.6398052136365;5162.328897243372;5496.587755918322;5550.389457159709;5564.4713752193475;5608.4391560844;5611.046122159243;5932.377100404438;5985;6091.664958427285;6110.297315808694;6137.631742095488;6124.472479235491;6171.6297022178815;6191.597546159485;6207.255129384457;6256;4851.223804191312;5744.4829965303325;5749.137629355589;5768.7980920668415;5770.971145144247;6128.503123582198;6348.936510634834;6356.77751278703;6357.173567436296;6359.554831161774;5862.443067908542;5999.670018149044;6053.424924632153;6086.056661217518;6115.388461153849;6185.907211391833;6213.37866213375;6233.688315584202;6234.688265586793;6240.095186197976;5700.173474846124;6116.66358350295;6163.075538669146;6178.907316390258;6179;5916.911246331309;5970.31341395745;6080.513558915276;6111.257501106137;6122.062734707273;4865.489123642037;4877.170880950247;4884.584810291165;4943.777530011158;4956.702597844141;4401.955980440219;4421.668374871898;4424.048829501673;4435.556444355539;4463.888402789957;5433.809816656398;5436.293281873332;5501.944980550224;5600.60795744294;5601.859953501195;6103.883205879379;6150.108234306001;6181.629102253879;6189.535784816067;6198.845028874314;5553.694546799884;5560.332760068737;5578.079616863172;5585.106382978785;5601;5235.345581802275;5273.077211487975;5280.498352656491;5346.064438723208;5350.892982140335;5287.524122828964;6287.339829317947;6341.143945567474;6357.777477788255;6358.809235722936;6113.266408031065;6120.051392034242;6127.080937859357;6136.29432615255;6136.723847426787;6137.46891820379;6280.434760871361;6307.779227727007;6354.586951848115;6357.3006969234275;5912.793052243151;6072.848178795389;6090.908636370546;6114.905704492528;6147.616786223064;6194.38056194377;6213.471854892385;6215.409536094064;6221.564490485623;6226.750929962932;5947.04847224442;5956.225690660186;6016.458518733335;6025.367336429787;6026.819195424145;5774.884502310014;5808.854778630399;5811.389804070681;5897.203877476657;6025.246844144482;5729.914051289317;5741.569382296261;5753.971230143822;5758.992176369203;5795.420457954171;5879.265091863517;5887.970560147171;5891.998360278849;5896.705164741831;5902.026165682614;5688.317401911797;5760.740766665594;5784.971075144597;5808.709564521672;5862.736176872137 17:12:18 INFO - PROCESS | 2012 | |1;ext.html;434.30294377524075;522.2062465053119;534.1000414301899;539.7813885376422;552.5412909917815;31282.843585782095;32842.34315313695;32889.17777055575;32990.340193196185;41480.792596037056;40660.59339406605;43478.34782478258;43619.56380436195;43650.563494365044;43845.78077109618;9646.710598682033;9801.852972205423;9832.115109640132;9840.409575425461;9869.753256168617;8576.271016963565;8779.429337093101;9703.854442183372;9810.21518278536;9851.704448866545;10136.94931525345;11602.361870097142;11779.175457717964;11837.467313970848;11996.460159292852;11851;11937.462814173296;11955.7608847823;11959;11991.64025079249;9997.650082247159;11733;11746.58886938953;11817.645470635895;11832.349220792852;40216;40562.59437405647;40565.18869622591;40678;40781.1843763126;3362.8150451725146;3413.149492162012;3705.6294370562996;4136.198465748504;4311.892202694927;31484.527732084036;31994.6800531994;32922.50616240758;32999.835000825085;33225.66774332274;3219.5653586765957;3244.205169733407;3266.4120458317493;3366.309906469168;3373.8987830365127;513801;521144;521370;522563;523143;33284.1678958028;33962.660373396444;58770;59029.70485147633;59131.11303330454;35196;35298.470522942174;35402.46896296558;35433.29133417317;35456.29087418289;14183.290835458185;14259.78610320846;14278.928605356907;14280.857191427953;14297;11897.881021189678;11998.520059197534;12014.63956081319;12022.398880056136;12036.217645853167;7219.566825990458;7400.074990626172;7642.885356719654;7778.844423111506;7857.096433910059;2904.4917139500503;3283.212029112996;3286.901392958215;3287.425149700599;3328.6671332866517;844.687465637718;901.8827552418275;1014.2038499777639;1056.5841772025524;1068.2682362581656;27866.442671146462;30382.69617303843;30926.53610195849;31048;31132.018876979575;1083.550326614462;1148.4602236948622;1153.5270539078938;1200.6218041317131;1243.881831226032;15422.768658470131;15493.380264789508;15720.842791572166;15873.761893571605;15957.361705531648;664800;671193.6440317767;673399;674690;676191;50789.49210507922;51421.48578514242;51484;58826;59593.70203148956;1349.2713934475273;1370.9520166794314;1396.6508372906774;1400.1179257067981;1405.8313002439772;30625.846870765505;31687;31813.886513972826;31863.715433987792;32074.839625802655;50243.99512009666;50748.74625626996;50805.237921431944;50829;50863;677148;685529;686496;871937;872112;42407.3638895423;42681.359779603954;42791.572084278756;43376.56623433851;43377;47369;47622;47898.76050619724;48005.51994480011;48236.27645585249;38831.41752873653;38917.13535808024;39255;39941.60058399495;40422.39366409565;32428.83785581057;38650.8404747858;38733.80633096817;39652;40184.39723404093;37063.629363706015;37205.0698732523;37834.43248351222;37990.43014354842;38451.615483845926;28720.11223768891;33271.50092748562;33680.32639347248;34748;34879.82560087183;22905.427364316027;25088;25097.99608015659;25332.493350132525;25595.616065758655 17:12:18 INFO - PROCESS | 2012 | |2;jquery.html;345.7666075399108;361.7160528984746;371.32418997424685;372.5957336290126;392.02777112760356;15136.848631513682;15148.848511514881;21152.153913843427;21276.787232127674;21413.892930535363;19899.801001989978;20493.975301234917;20790.48023799406;20980.160793568277;21375.35873923781;5786.855328616797;5808.912866306998;6003.1895694081395;6067.575269731121;6086.878262434749;5857;6022.939770602282;6039.909401358983;6042.879142417149;6055.394460553932;3646.4348026056005;4045.453863728403;4289.892752681177;4293.484781826185;6293.30773614902;6604.9432090865475;6634.966825165843;6639.76760813374;6644.169478815148;6644.867102657968;5587.916181257285;5667.360057711229;5707.14392841073;5734.025215713339;5775.711214439261;21075.578488430147;25497.362565935815;25923.611145832827;26099.217023489327;26565.734342656713;1829.2865782344895;2185.573813106445;2279.6922415473864;2574.562324404837;3119.937601247985;15576.29906654192;15647.513486218904;15844.52466426009;16130.677386452206;16290.51128466148;1852.110986726379;1864.3008871673123;1877.2115711401245;1879.4549580621604;1905.3236101184157;111848;115065.42467287694;115341;115898;117999.82000179974;10179.727534058242;11205.66383008511;11248.493817778219;11416.885831141582;11580;14387.568372948619;14392;14393.712125757636;14401.135931844123;14406.495772648117;8308.501489910625;8453.450525715809;8460.196281353261;8466.788330291669;8578.957105214435;7284.927150728425;8348.457350272212;8364.306799125205;8382.245597896097;8485.787855303666;4506.301523263899;4525.932111018272;4545.772711364419;4563.498015218323;4610.608098311681;1887.9528011799814;1923.3172223860608;1925.1721759643488;1929.0354822588706;1935.2162374238455;755.4296506137831;826.900771907375;852.5012867472598;904.8235594059096;995.785906030207;16745.665086698198;19390.612187756167;20428.897855510626;20974.790252097282;21318.680219796715;924.0502539641351;929.4051806843626;946.9763255918657;982.3663736889808;986.3933680786408;11047.171462140372;11399.430028498542;11404.771904562029;11452.94273528627;11457.541698331974;597185.168889868;598519;605475;614815;620822;129786;131609;132954.3352283252;133121;135059;952.9618815247451;967.1634036558327;991.0188912976246;998.5556427389741;1001.249063202598;18098.81901181024;18898;19093.71359429582;19144.329948451737;19150.138243778783;55293.89412211653;56559.86880262454;56661;57131.71434142803;57454.85090298254;73048;79108;79177.20822791698;79546;83257.58371208105;6316.684165791784;7580.924190758022;7862.921370786219;8013.439059265796;8370.16298370158;11340.773184536098;12983.35083245853;13006.804897926728;13447.46210151583;13517.932410337886;10966.013058824745;11236.269642473213;11322.94338528302;11347.319160850375;11520.193586449202;9923.55344009507;11311.660650180507;11360.715982100182;11505.539778408769;11614.593489227836;11075.667729968114;11788;11807.76384472323;11973.401329933295;11998.040156787261;8162.102168761313;8491.532965686947;12119.575814846437;12215.144939854124;13009.869901301245;11193.328400296008;11588.47851846652;12179.878201217874;12271.877281227073;12313.56902508408 17:12:18 INFO - PROCESS | 2012 | |3;mootools.html;1340.4183063984494;1502.0386952350498;1535.654477742508;1703.9659206815863;1879.304657276807;7662.731804386847;8099.55452450115;8115.14790946951;8601.784955376119;8838.116188381158;8697.173768491999;8774.517401542913;8779.2098711116;8784.692535761249;8794.6921857735;2279.2592407467596;2334.661474086265;2336.042222688698;2337.357226762637;2342.0982921575146;2279.1453205048106;2308.8960996755186;2326.5463234669246;2332.8367014309006;2335.3577766114286;2285.885705714716;2313.618252988255;2318.2002209237785;2326.2672258238686;2330.545543618995;2277.908883644652;2297.414159389362;2300.2179259051836;2304.331743794298;2309.2956648222203;2288.4507718147693;2293.8508996915148;2296.9310920672406;2299.4596269876565;2300.896459659319;8718.738437846874;8849.734507964771;8864.157904999014;8875.334349923782;8904.287656987426;5083.3391659084455;5147.794088236466;5251.186066159751;5255.710935898524;5266.315379000744;2995.445842519141;3638.5815631202486;3643.908902277438;3646.690031347339;3656.305301992613;1074.8710154781397;1079.1906070447164;1106.7952428800697;1112.9888701112966;1116.776644671061;11482;11614.303141811519;11617.709557261052;11618.477168527521;11628.95339419448;4353.25994580922;4432.268675668479;4510.616597589176;4575.26795712691;4596.47140578831;9133.360664753403;9150.81698366029;9152.450852948845;9153.038930912158;9154.176124148858;5154.561862241751;5154.742262886915;5156.92264616031;5159.045576568347;5160.690358578498;4096.3855421686385;4335.783210839445;4390.385346051556;4796.832110876103;4842.055799119138;3196.5844440223086;3725.1990821973404;3767.2842159990064;3794.430835374716;3851.1912498375655;1118.3234143343323;1124.8425220469142;1127.3574062784298;1136.1535655936216;1140.811766058591;895.4671970177734;898.2723993565234;908.123660667459;947.7677968897526;965.2153537045674;5376.569874410116;5655.095184770499;5882.264926359442;5986.820395388146;6001.63990160583;408.1143917698601;423.1579157476661;427.21392637547063;436.77287810338447;448.4887228559445;3901.941470877939;3921.9607803922167;3926.862559810393;4041.0503531670033;4073.368627862686;10416.114630256361;10704.983026612459;10791.460426978776;10812.567497300177;10813.351198927932;10763.83854242187;10775.191860610328;10777.461126943621;10791;10796.568137274578;7799.64901579419;8165.591720413956;8206;8235.917640823514;8256.463329883658;5757.683327417032;5783.681897495678;6003.669798161143;6091.208142941389;6101.542384321282;8749.65001399937;9397.483138427453;9795;10008.899911000797;10083.445410502492;9143.222826059593;9902.455364954709;10192.980701929748;10249.846252306072;10291.794164116825;7525.285097915689;7600.695972161052;7707;7731.922680773345;7762.4178186637355;8133.34933205354;8292.875606866022;8303.709370172015;8305.086440491661;8307.626156823078;7432.3682486987045;7549.811254718676;7565.962170189114;7660.387169026353;7687.88468172989;7384.335409813142;7420.814479638052;7452.403807695479;7455.850882982419;7492.850142997218;6912.135983002125;7058.329458701415;7078.079849619517;7086.681099350439;7087.893681594884;4956.132676781477;4996;5005.149124648891;5083.517065878884;5141.562967147835;4194.245035893447;4950.133726597759;5020.221865610836;5140.948590514047;5264.78940842362 17:12:18 INFO - PROCESS | 2012 | |4;prototype.html;279.12355204657365;292.0814039844687;295.2898279637472;306.4682775384708;311.2949170129657;5103.515166059227;5114.514121158489;5145.614078944081;5169.741512924348;5225.346831646044;4139;5323.669082729318;5356.866078348044;5370;5388.191771234307;3556.270964452294;3707.5921648618632;4045.7572545647354;4076.8165432555456;4085.7957102144774;3456.9629111266518;4039.333509970851;4053.574374690659;4112.424260603518;4125.690573207006;2918.5622156676454;3397.5073614325906;3897.473841031467;3960.425738267964;3998.920021599566;3903.8873920932397;4001.9399709004388;4039.7172197946156;4046.9595304046875;4047.575004624516;2988.923987364547;3199.40810949975;3414.146463384154;3485.285516469143;3492.7729697569575;5076.873078173038;5099.46455622158;5187.455317191679;5202.869928251786;5245.895082098336;4237.639800616951;4540.02389486262;4543.659225558064;4572.765353354575;4576.427946506687;4894.241392584119;5138.274750805636;5566.944330556683;5610.635308704962;5711.543076553866;3653.7442379033478;3765.303418867491;3783.1487915218854;3801.4107813288983;3834.5206849143856;4928.8767780805765;5449.945500544944;5761.423857614247;5830.9125363119165;5960.582759206857;3512.034190597606;3831.1227498526077;3863.188730366598;3887.3391523441082;3909.4331321958157;3485.477178423206;3943.132510847609;3962.920741585152;3963.23635981991;3980.6616437602547;4242;4520.2315606347;4544.818207271738;4603.263477843529;4619;5563.415841336601;5569.718964638075;5606.439356064442;5620.74706638194;5690.926899474321;1322.6561094115407;1342.89256859451;1346.9663258418423;1347.5783048883522;1348.8246527951303;685.143570536829;688.900109484122;879.4855009819199;909.736176508805;917.3945196170496;5237.6071794615555;5249.816256431007;5257.924114393047;5281.894362112736;5298.013245033113;5008.824691135793;5589.35722391922;5753.597248192586;5771.604784014671;5802.839432113594;4661.156694416403;5055.848324550269;5073.923891141636;5273.815416460406;5279.815206467755;2783.359827239746;3023.591815104977;3046.5430185471914;3059.7552195824283;3061.249993751545;4750.786214620351;5231.712255825966;5536.75084621193;5913.368550994852;5979.282486101695;5430.456954304538;5651.9152212716845;5733.77064917407;5910.822675319747;5943.732532036069;5507.504324610804;5964.761409543657;5980.55145864062;5988.520918326523;5991.340952495222;4616.722996620214;5243.108671525773;5520.951019306319;5750.9424905752085;5794.652320860761;5038.924416133687;5158.303629010035;5411.810586629449;5584.3857175711455;5615.073512870331;5859.267591551056;5980.910286345621;5992.310884248367;5997.31030931431;6051.878962420815;3268.4443644580956;3835.213781174908;3845.769253844778;3872.8644497442456;3875.224955008953;5483.917741233805;5755.7697692093525;5774.527495488624;5777.364489906191;5826.82519524415;4475.776211189363;5139.820106296262;5193.480651934777;5636.549076073986;5648.491635752802;5402.378726446352;5612.663240205601;5640.464155905182;5690.743916523684;5706.28671416073;4779.173035234368;5476.67139971603;5507.06245034802;5512.4211957743855;5568.468671115475;4306.504751953566;4920.114379411741;5012.348394708811;5361.766793637407;5682.772689092555;4725.40932383452;5422.5119739223655;5516.289950115477;5544.61187716856;5585.944140558705 17:12:18 INFO - PROCESS | 2012 | |5;yui.html;710.6801939127392;747.85790699767;763.9991610989604;775.3138472451882;853.0744142605273;14035.649108772286;15230;16457.259423325948;16501.927374720635;16508.587285317873;14911.92544037281;14931.850681493182;14988.175650339253;15000.249987500636;15006.94951353403;678.915135608049;704.4294121761365;830.3523251863526;841.7600983719641;866.4931015356025;857.2499063319596;859.3855393393723;860.4493124400389;861.5433820075339;862.0948004595182;914.0356923445762;916.0061333453211;917;917.8302014127374;918.4581097152678;715.6707914359401;870.9651613935436;891.0599317719802;899.1952202778542;914.1863741270275;672.0725398949419;672.9091572637681;673.293042305582;673.3401266758603;673.9865202695919;14947.626309342244;14961.476348327866;14983.775243371358;14996.100233985995;15017.474388396464;13861.306934653227;14074.22591757453;14084.647883802885;14084.647883802987;14339.42642294307;11900.523979040829;12957.546485872948;13242.404091815795;13504.662383440493;13602.387892544859;4328.372386004012;4535.909281814378;4627.330885377157;4670.813167473297;4788.233882578806;118985.99999999914;119195.80804191934;119327;119531;120022;10734.302270352402;11002;11340.6030788922;11465.713357166138;11678.474468648932;22662.433439164153;22696.205632802772;22710;22730.318090457313;22744.886275568515;7853.332466740391;7860.489068210548;7904.723334683258;8049.315808156255;8093.433459657767;3955.802209889494;4478.888027799266;4495.57292057254;4506.1663592235545;4576.359309696645;3365.74756893234;3514.769830559335;3516.4373700207843;3517;3522.207503311786;617.2531237202945;626.6459450410504;634.4353525362432;637.9330170332141;644.3813938618978;534.4041393845833;536.1582315764213;539.7085573790188;563.737861894213;570.4552152694167;7931.806115816504;8719.215270625547;8837.29301655866;9186.954065229766;9364.391314564531;582.1156171171833;628.8585068359582;630.250002497027;632.045611127192;642.2966851297822;4368.497622773421;4400.515943246241;4438.400815889813;4489.371487991683;4549.181147393501;122993;164001;166606;167544;170312.148439257;104328.87013389611;138324;138826;140078;141453;107956;108891.45554272179;109195;109305;110270.44864775625;8447.831043379221;8465.95767021161;8526.78683032929;8652.870206946774;8725.459954825503;56353.436465636456;58619.70690146522;65135.34864651293;66288.33711662822;66773.66613166903;95326.52336738272;100358;103430;103685.48157259166;103796.48101759443;11574.537018519164;11710.765784684429;11879.703007424883;12277.815832762695;12383.380830958236;12367.134300598871;12428.502859885864;12490.188137771016;12552.748945020865;12587.811182832447;11937.462814173557;11981.28112313264;12006.63980080599;12009.279443233434;12165.878341216829;11949.342786146846;12032;12034.63896083119;12078.154529182873;12104.45529951172;11744.647660570197;11842.585509507126;11869.94065029704;11877.584284550345;11996.76006479883;9584.472853993097;10050.09549140581;10167.644132455329;10189.949050254701;10450.42522661261;9426.481543515172;9781.755456113655;9997.400155990665;10010.618833723047;10031 17:12:18 INFO - PROCESS | 2012 | __end_tp_report 17:12:18 INFO - PROCESS | 2012 | __start_cc_report 17:12:18 INFO - PROCESS | 2012 | _x_x_mozilla_cycle_collect,2187 17:12:18 INFO - PROCESS | 2012 | __end_cc_report 17:12:18 INFO - PROCESS | 2012 | __startTimestamp1470960738039__endTimestamp 17:12:18 INFO - PROCESS | 2012 | 17:12:18 INFO - PROCESS | 2012 | ------- Summary: start ------- 17:12:18 INFO - PROCESS | 2012 | Number of tests: 6 17:12:18 INFO - PROCESS | 2012 | 17:12:18 INFO - PROCESS | 2012 | [#0] dojo.html Cycles:165 Average:5606.62 Median:5774.88 stddev:770.63 (13.3%) stddev-sans-first:750.53 17:12:18 INFO - PROCESS | 2012 | Values: 3252.0 3268.3 3275.1 3302.7 3314.1 5463.4 5543.5 5557.6 5640.9 5650.6 4642.6 5555.3 5571.1 5613.2 5621.2 5084.8 5116.4 5127.6 5160.7 5161.1 5095.4 5190.5 5232.8 5259.0 5269.2 5189.6 5200.5 5200.9 5206.3 5233.3 64.5 4529.5 5198.2 5209.5 5287.4 5087.5 5090.3 5095.6 5145.6 5162.3 5496.6 5550.4 5564.5 5608.4 5611.0 5932.4 5985.0 6091.7 6110.3 6137.6 6124.5 6171.6 6191.6 6207.3 6256.0 4851.2 5744.5 5749.1 5768.8 5771.0 6128.5 6348.9 6356.8 6357.2 6359.6 5862.4 5999.7 6053.4 6086.1 6115.4 6185.9 6213.4 6233.7 6234.7 6240.1 5700.2 6116.7 6163.1 6178.9 6179.0 5916.9 5970.3 6080.5 6111.3 6122.1 4865.5 4877.2 4884.6 4943.8 4956.7 4402.0 4421.7 4424.0 4435.6 4463.9 5433.8 5436.3 5501.9 5600.6 5601.9 6103.9 6150.1 6181.6 6189.5 6198.8 5553.7 5560.3 5578.1 5585.1 5601.0 5235.3 5273.1 5280.5 5346.1 5350.9 5287.5 6287.3 6341.1 6357.8 6358.8 6113.3 6120.1 6127.1 6136.3 6136.7 6137.5 6280.4 6307.8 6354.6 6357.3 5912.8 6072.8 6090.9 6114.9 6147.6 6194.4 6213.5 6215.4 6221.6 6226.8 5947.0 5956.2 6016.5 6025.4 6026.8 5774.9 5808.9 5811.4 5897.2 6025.2 5729.9 5741.6 5754.0 5759.0 5795.4 5879.3 5888.0 5892.0 5896.7 5902.0 5688.3 5760.7 5785.0 5808.7 5862.7 17:12:18 INFO - PROCESS | 2012 | 17:12:18 INFO - PROCESS | 2012 | [#1] ext.html Cycles:180 Average:75953.11 Median:19580.96 stddev:177466.86 (906.3%) stddev-sans-first:177874.13 17:12:18 INFO - PROCESS | 2012 | Values: 434.3 522.2 534.1 539.8 552.5 31282.8 32842.3 32889.2 32990.3 41480.8 40660.6 43478.3 43619.6 43650.6 43845.8 9646.7 9801.9 9832.1 9840.4 9869.8 8576.3 8779.4 9703.9 9810.2 9851.7 10136.9 11602.4 11779.2 11837.5 11996.5 11851.0 11937.5 11955.8 11959.0 11991.6 9997.7 11733.0 11746.6 11817.6 11832.3 40216.0 40562.6 40565.2 40678.0 40781.2 3362.8 3413.1 3705.6 4136.2 4311.9 31484.5 31994.7 32922.5 32999.8 33225.7 3219.6 3244.2 3266.4 3366.3 3373.9 513801.0 521144.0 521370.0 522563.0 523143.0 33284.2 33962.7 58770.0 59029.7 59131.1 35196.0 35298.5 35402.5 35433.3 35456.3 14183.3 14259.8 14278.9 14280.9 14297.0 11897.9 11998.5 12014.6 12022.4 12036.2 7219.6 7400.1 7642.9 7778.8 7857.1 2904.5 3283.2 3286.9 3287.4 3328.7 844.7 901.9 1014.2 1056.6 1068.3 27866.4 30382.7 30926.5 31048.0 31132.0 1083.6 1148.5 1153.5 1200.6 1243.9 15422.8 15493.4 15720.8 15873.8 15957.4 664800.0 671193.6 673399.0 674690.0 676191.0 50789.5 51421.5 51484.0 58826.0 59593.7 1349.3 1371.0 1396.7 1400.1 1405.8 30625.8 31687.0 31813.9 31863.7 32074.8 50244.0 50748.7 50805.2 50829.0 50863.0 677148.0 685529.0 686496.0 871937.0 872112.0 42407.4 42681.4 42791.6 43376.6 43377.0 47369.0 47622.0 47898.8 48005.5 48236.3 38831.4 38917.1 39255.0 39941.6 40422.4 32428.8 38650.8 38733.8 39652.0 40184.4 37063.6 37205.1 37834.4 37990.4 38451.6 28720.1 33271.5 33680.3 34748.0 34879.8 22905.4 25088.0 25098.0 25332.5 25595.6 17:12:18 INFO - PROCESS | 2012 | 17:12:18 INFO - PROCESS | 2012 | [#2] jquery.html Cycles:180 Average:35645.02 Median:2232.63 stddev:101334.03 (4538.8%) stddev-sans-first:101583.63 17:12:18 INFO - PROCESS | 2012 | Values: 345.8 361.7 371.3 372.6 392.0 15136.8 15148.8 21152.2 21276.8 21413.9 19899.8 20494.0 20790.5 20980.2 21375.4 5786.9 5808.9 6003.2 6067.6 6086.9 5857.0 6022.9 6039.9 6042.9 6055.4 3646.4 4045.5 4289.9 4293.5 6293.3 6604.9 6635.0 6639.8 6644.2 6644.9 5587.9 5667.4 5707.1 5734.0 5775.7 21075.6 25497.4 25923.6 26099.2 26565.7 1829.3 2185.6 2279.7 2574.6 3119.9 15576.3 15647.5 15844.5 16130.7 16290.5 1852.1 1864.3 1877.2 1879.5 1905.3 111848.0 115065.4 115341.0 115898.0 117999.8 10179.7 11205.7 11248.5 11416.9 11580.0 14387.6 14392.0 14393.7 14401.1 14406.5 8308.5 8453.5 8460.2 8466.8 8579.0 7284.9 8348.5 8364.3 8382.2 8485.8 4506.3 4525.9 4545.8 4563.5 4610.6 1888.0 1923.3 1925.2 1929.0 1935.2 755.4 826.9 852.5 904.8 995.8 16745.7 19390.6 20428.9 20974.8 21318.7 924.1 929.4 947.0 982.4 986.4 11047.2 11399.4 11404.8 11452.9 11457.5 597185.2 598519.0 605475.0 614815.0 620822.0 129786.0 131609.0 132954.3 133121.0 135059.0 953.0 967.2 991.0 998.6 1001.2 18098.8 18898.0 19093.7 19144.3 19150.1 55293.9 56559.9 56661.0 57131.7 57454.9 73048.0 79108.0 79177.2 79546.0 83257.6 6316.7 7580.9 7862.9 8013.4 8370.2 11340.8 12983.4 13006.8 13447.5 13517.9 10966.0 11236.3 11322.9 11347.3 11520.2 9923.6 11311.7 11360.7 11505.5 11614.6 11075.7 11788.0 11807.8 11973.4 11998.0 8162.1 8491.5 12119.6 12215.1 13009.9 11193.3 11588.5 12179.9 12271.9 12313.6 17:12:18 INFO - PROCESS | 2012 | 17:12:18 INFO - PROCESS | 2012 | [#3] mootools.html Cycles:180 Average:5630.49 Median:4896.09 stddev:3197.16 (65.3%) stddev-sans-first:3189.87 17:12:18 INFO - PROCESS | 2012 | Values: 1340.4 1502.0 1535.7 1704.0 1879.3 7662.7 8099.6 8115.1 8601.8 8838.1 8697.2 8774.5 8779.2 8784.7 8794.7 2279.3 2334.7 2336.0 2337.4 2342.1 2279.1 2308.9 2326.5 2332.8 2335.4 2285.9 2313.6 2318.2 2326.3 2330.5 2277.9 2297.4 2300.2 2304.3 2309.3 2288.5 2293.9 2296.9 2299.5 2300.9 8718.7 8849.7 8864.2 8875.3 8904.3 5083.3 5147.8 5251.2 5255.7 5266.3 2995.4 3638.6 3643.9 3646.7 3656.3 1074.9 1079.2 1106.8 1113.0 1116.8 11482.0 11614.3 11617.7 11618.5 11629.0 4353.3 4432.3 4510.6 4575.3 4596.5 9133.4 9150.8 9152.5 9153.0 9154.2 5154.6 5154.7 5156.9 5159.0 5160.7 4096.4 4335.8 4390.4 4796.8 4842.1 3196.6 3725.2 3767.3 3794.4 3851.2 1118.3 1124.8 1127.4 1136.2 1140.8 895.5 898.3 908.1 947.8 965.2 5376.6 5655.1 5882.3 5986.8 6001.6 408.1 423.2 427.2 436.8 448.5 3901.9 3922.0 3926.9 4041.1 4073.4 10416.1 10705.0 10791.5 10812.6 10813.4 10763.8 10775.2 10777.5 10791.0 10796.6 7799.6 8165.6 8206.0 8235.9 8256.5 5757.7 5783.7 6003.7 6091.2 6101.5 8749.7 9397.5 9795.0 10008.9 10083.4 9143.2 9902.5 10193.0 10249.8 10291.8 7525.3 7600.7 7707.0 7731.9 7762.4 8133.3 8292.9 8303.7 8305.1 8307.6 7432.4 7549.8 7566.0 7660.4 7687.9 7384.3 7420.8 7452.4 7455.9 7492.9 6912.1 7058.3 7078.1 7086.7 7087.9 4956.1 4996.0 5005.1 5083.5 5141.6 4194.2 4950.1 5020.2 5140.9 5264.8 17:12:18 INFO - PROCESS | 2012 | 17:12:18 INFO - PROCESS | 2012 | [#4] prototype.html Cycles:180 Average:4514.69 Median:5139.05 stddev:1403.09 (27.3%) stddev-sans-first:1370.54 17:12:18 INFO - PROCESS | 2012 | Values: 279.1 292.1 295.3 306.5 311.3 5103.5 5114.5 5145.6 5169.7 5225.3 4139.0 5323.7 5356.9 5370.0 5388.2 3556.3 3707.6 4045.8 4076.8 4085.8 3457.0 4039.3 4053.6 4112.4 4125.7 2918.6 3397.5 3897.5 3960.4 3998.9 3903.9 4001.9 4039.7 4047.0 4047.6 2988.9 3199.4 3414.1 3485.3 3492.8 5076.9 5099.5 5187.5 5202.9 5245.9 4237.6 4540.0 4543.7 4572.8 4576.4 4894.2 5138.3 5566.9 5610.6 5711.5 3653.7 3765.3 3783.1 3801.4 3834.5 4928.9 5449.9 5761.4 5830.9 5960.6 3512.0 3831.1 3863.2 3887.3 3909.4 3485.5 3943.1 3962.9 3963.2 3980.7 4242.0 4520.2 4544.8 4603.3 4619.0 5563.4 5569.7 5606.4 5620.7 5690.9 1322.7 1342.9 1347.0 1347.6 1348.8 685.1 688.9 879.5 909.7 917.4 5237.6 5249.8 5257.9 5281.9 5298.0 5008.8 5589.4 5753.6 5771.6 5802.8 4661.2 5055.8 5073.9 5273.8 5279.8 2783.4 3023.6 3046.5 3059.8 3061.2 4750.8 5231.7 5536.8 5913.4 5979.3 5430.5 5651.9 5733.8 5910.8 5943.7 5507.5 5964.8 5980.6 5988.5 5991.3 4616.7 5243.1 5521.0 5750.9 5794.7 5038.9 5158.3 5411.8 5584.4 5615.1 5859.3 5980.9 5992.3 5997.3 6051.9 3268.4 3835.2 3845.8 3872.9 3875.2 5483.9 5755.8 5774.5 5777.4 5826.8 4475.8 5139.8 5193.5 5636.5 5648.5 5402.4 5612.7 5640.5 5690.7 5706.3 4779.2 5476.7 5507.1 5512.4 5568.5 4306.5 4920.1 5012.3 5361.8 5682.8 4725.4 5422.5 5516.3 5544.6 5585.9 17:12:18 INFO - PROCESS | 2012 | 17:12:18 INFO - PROCESS | 2012 | [#5] yui.html Cycles:180 Average:25538.78 Median:3440.26 stddev:41946.46 (1219.3%) stddev-sans-first:42022.71 17:12:18 INFO - PROCESS | 2012 | Values: 710.7 747.9 764.0 775.3 853.1 14035.6 15230.0 16457.3 16501.9 16508.6 14911.9 14931.9 14988.2 15000.2 15006.9 678.9 704.4 830.4 841.8 866.5 857.2 859.4 860.4 861.5 862.1 914.0 916.0 917.0 917.8 918.5 715.7 871.0 891.1 899.2 914.2 672.1 672.9 673.3 673.3 674.0 14947.6 14961.5 14983.8 14996.1 15017.5 13861.3 14074.2 14084.6 14084.6 14339.4 11900.5 12957.5 13242.4 13504.7 13602.4 4328.4 4535.9 4627.3 4670.8 4788.2 118986.0 119195.8 119327.0 119531.0 120022.0 10734.3 11002.0 11340.6 11465.7 11678.5 22662.4 22696.2 22710.0 22730.3 22744.9 7853.3 7860.5 7904.7 8049.3 8093.4 3955.8 4478.9 4495.6 4506.2 4576.4 3365.7 3514.8 3516.4 3517.0 3522.2 617.3 626.6 634.4 637.9 644.4 534.4 536.2 539.7 563.7 570.5 7931.8 8719.2 8837.3 9187.0 9364.4 582.1 628.9 630.3 632.0 642.3 4368.5 4400.5 4438.4 4489.4 4549.2 122993.0 164001.0 166606.0 167544.0 170312.1 104328.9 138324.0 138826.0 140078.0 141453.0 107956.0 108891.5 109195.0 109305.0 110270.4 8447.8 8466.0 8526.8 8652.9 8725.5 56353.4 58619.7 65135.3 66288.3 66773.7 95326.5 100358.0 103430.0 103685.5 103796.5 11574.5 11710.8 11879.7 12277.8 12383.4 12367.1 12428.5 12490.2 12552.7 12587.8 11937.5 11981.3 12006.6 12009.3 12165.9 11949.3 12032.0 12034.6 12078.2 12104.5 11744.6 11842.6 11869.9 11877.6 11996.8 9584.5 10050.1 10167.6 10189.9 10450.4 9426.5 9781.8 9997.4 10010.6 10031.0 17:12:18 INFO - PROCESS | 2012 | -------- Summary: end -------- 17:12:18 INFO - PROCESS | 2012 | 17:12:18 INFO - TEST-INFO | 2012: exit 0 17:12:18 INFO - TEST-OK | dromaeo_css | took 1118064ms 17:12:18 INFO - TEST-START | kraken 17:12:18 INFO - operating with platform_type : mac_ 17:12:18 INFO - Initialising browser for kraken test... 17:12:18 INFO - TEST-INFO | started process 2017 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmpsq8PZh/profile http://localhost:49370/getInfo.html) 17:12:20 INFO - PROCESS | 2017 | __metrics Screen width/height:1600/1200 17:12:20 INFO - PROCESS | 2017 | colorDepth:24 17:12:20 INFO - PROCESS | 2017 | Browser inner width/height: 1024/689 17:12:20 INFO - PROCESS | 2017 | __metrics 17:12:21 INFO - PROCESS | 2017 | 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" 17:12:21 INFO - PROCESS | 2017 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:12:21 INFO - TEST-INFO | 2017: exit 0 17:12:21 INFO - Browser initialized. 17:12:21 INFO - Running cycle 1/1 for kraken test... 17:12:21 INFO - TEST-INFO | started process 2018 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmpsq8PZh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:12:33 INFO - PROCESS | 2018 | RSS: Main: 243593216 17:12:33 INFO - PROCESS | 2018 | 17:12:36 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-beat-detection) 17:12:37 INFO - PROCESS | 2018 | RSS: Main: 293056512 17:12:37 INFO - PROCESS | 2018 | 17:12:40 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-dft) 17:12:40 INFO - PROCESS | 2018 | RSS: Main: 299900928 17:12:40 INFO - PROCESS | 2018 | 17:12:44 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-fft) 17:12:45 INFO - PROCESS | 2018 | RSS: Main: 394534912 17:12:45 INFO - PROCESS | 2018 | 17:12:47 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-oscillator) 17:12:47 INFO - PROCESS | 2018 | RSS: Main: 294973440 17:12:47 INFO - PROCESS | 2018 | 17:12:49 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:12:50 INFO - PROCESS | 2018 | RSS: Main: 300863488 17:12:50 INFO - PROCESS | 2018 | 17:12:53 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-darkroom) 17:12:54 INFO - PROCESS | 2018 | RSS: Main: 338284544 17:12:54 INFO - PROCESS | 2018 | 17:12:57 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-desaturate) 17:12:57 INFO - PROCESS | 2018 | RSS: Main: 339050496 17:12:57 INFO - PROCESS | 2018 | 17:13:00 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49370/tests/kraken/driver.html?testName=json-parse-financial) 17:13:01 INFO - PROCESS | 2018 | RSS: Main: 337588224 17:13:01 INFO - PROCESS | 2018 | 17:13:02 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49370/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:13:03 INFO - PROCESS | 2018 | RSS: Main: 316211200 17:13:03 INFO - PROCESS | 2018 | 17:13:05 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:13:06 INFO - PROCESS | 2018 | RSS: Main: 319668224 17:13:06 INFO - PROCESS | 2018 | 17:13:08 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:13:08 INFO - PROCESS | 2018 | RSS: Main: 327249920 17:13:08 INFO - PROCESS | 2018 | 17:13:11 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:13:12 INFO - PROCESS | 2018 | RSS: Main: 329322496 17:13:12 INFO - PROCESS | 2018 | 17:13:15 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:13:16 INFO - PROCESS | 2018 | RSS: Main: 331898880 17:13:16 INFO - PROCESS | 2018 | 17:13:17 INFO - PROCESS | 2018 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49370/tests/kraken/driver.html?testName=ai-astar) 17:13:18 INFO - PROCESS | 2018 | RSS: Main: 375496704 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | __start_tp_report 17:13:18 INFO - PROCESS | 2018 | _x_x_mozilla_page_load 17:13:18 INFO - PROCESS | 2018 | _x_x_mozilla_page_load_details 17:13:18 INFO - PROCESS | 2018 | |i|pagename|runs| 17:13:18 INFO - PROCESS | 2018 | |0;ai-astar;103;112;115;113;115;114;117;162;159;113 17:13:18 INFO - PROCESS | 2018 | |1;audio-beat-detection;143;107;104;170;107;105;135;109;139;107 17:13:18 INFO - PROCESS | 2018 | |2;audio-dft;184;183;185;184;184;184;259;210;186;183 17:13:18 INFO - PROCESS | 2018 | |3;audio-fft;78;58;65;88;59;58;84;88;95;56 17:13:18 INFO - PROCESS | 2018 | |4;audio-oscillator;87;70;103;71;74;85;105;70;68;103 17:13:18 INFO - PROCESS | 2018 | |5;imaging-gaussian-blur;120;130;91;88;91;87;91;88;92;87 17:13:18 INFO - PROCESS | 2018 | |6;imaging-darkroom;95;95;96;113;148;96;95;96;100;94 17:13:18 INFO - PROCESS | 2018 | |7;imaging-desaturate;87;87;84;85;84;85;84;87;84;84 17:13:18 INFO - PROCESS | 2018 | |8;json-parse-financial;54;53;54;53;55;52;53;53;53;53 17:13:18 INFO - PROCESS | 2018 | |9;json-stringify-tinderbox;69;72;74;116;110;103;73;74;74;73 17:13:18 INFO - PROCESS | 2018 | |10;stanford-crypto-aes;84;85;85;85;86;82;82;91;87;87 17:13:18 INFO - PROCESS | 2018 | |11;stanford-crypto-ccm;138;136;135;136;137;137;141;123;200;136 17:13:18 INFO - PROCESS | 2018 | |12;stanford-crypto-pbkdf2;218;147;220;206;163;165;166;163;164;166 17:13:18 INFO - PROCESS | 2018 | |13;stanford-crypto-sha256-iterative;67;66;67;67;65;66;68;79;82;83 17:13:18 INFO - PROCESS | 2018 | __end_tp_report 17:13:18 INFO - PROCESS | 2018 | __start_cc_report 17:13:18 INFO - PROCESS | 2018 | _x_x_mozilla_cycle_collect,2043 17:13:18 INFO - PROCESS | 2018 | __end_cc_report 17:13:18 INFO - PROCESS | 2018 | __startTimestamp1470960798119__endTimestamp 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | ------- Summary: start ------- 17:13:18 INFO - PROCESS | 2018 | Number of tests: 14 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#0] ai-astar Cycles:10 Average:122.30 Median:115.00 stddev:20.49 (17.8%) stddev-sans-first:20.51 17:13:18 INFO - PROCESS | 2018 | Values: 103.0 112.0 115.0 113.0 115.0 114.0 117.0 162.0 159.0 113.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#1] audio-beat-detection Cycles:10 Average:122.60 Median:122.00 stddev:22.75 (18.6%) stddev-sans-first:22.90 17:13:18 INFO - PROCESS | 2018 | Values: 143.0 107.0 104.0 170.0 107.0 105.0 135.0 109.0 139.0 107.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#2] audio-dft Cycles:10 Average:194.20 Median:184.50 stddev:24.19 (13.1%) stddev-sans-first:25.38 17:13:18 INFO - PROCESS | 2018 | Values: 184.0 183.0 185.0 184.0 184.0 184.0 259.0 210.0 186.0 183.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#3] audio-fft Cycles:10 Average:72.90 Median:81.00 stddev:15.20 (18.8%) stddev-sans-first:16.01 17:13:18 INFO - PROCESS | 2018 | Values: 78.0 58.0 65.0 88.0 59.0 58.0 84.0 88.0 95.0 56.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:70.50 stddev:15.23 (21.6%) stddev-sans-first:16.11 17:13:18 INFO - PROCESS | 2018 | Values: 87.0 70.0 103.0 71.0 74.0 85.0 105.0 70.0 68.0 103.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#5] imaging-gaussian-blur Cycles:10 Average:96.50 Median:89.50 stddev:15.31 (17.1%) stddev-sans-first:13.68 17:13:18 INFO - PROCESS | 2018 | Values: 120.0 130.0 91.0 88.0 91.0 87.0 91.0 88.0 92.0 87.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:95.00 stddev:16.84 (17.7%) stddev-sans-first:17.63 17:13:18 INFO - PROCESS | 2018 | Values: 95.0 95.0 96.0 113.0 148.0 96.0 95.0 96.0 100.0 94.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.00 stddev:1.37 (1.6%) stddev-sans-first:1.27 17:13:18 INFO - PROCESS | 2018 | Values: 87.0 87.0 84.0 85.0 84.0 85.0 84.0 87.0 84.0 84.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:0.82 (1.6%) stddev-sans-first:0.83 17:13:18 INFO - PROCESS | 2018 | Values: 54.0 53.0 54.0 53.0 55.0 52.0 53.0 53.0 53.0 53.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#9] json-stringify-tinderbox Cycles:10 Average:83.80 Median:73.00 stddev:18.17 (24.9%) stddev-sans-first:18.47 17:13:18 INFO - PROCESS | 2018 | Values: 69.0 72.0 74.0 116.0 110.0 103.0 73.0 74.0 74.0 73.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:85.50 stddev:2.63 (3.1%) stddev-sans-first:2.74 17:13:18 INFO - PROCESS | 2018 | Values: 84.0 85.0 85.0 85.0 86.0 82.0 82.0 91.0 87.0 87.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#11] stanford-crypto-ccm Cycles:10 Average:141.90 Median:137.00 stddev:20.95 (15.3%) stddev-sans-first:22.17 17:13:18 INFO - PROCESS | 2018 | Values: 138.0 136.0 135.0 136.0 137.0 137.0 141.0 123.0 200.0 136.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.80 Median:166.00 stddev:26.27 (15.8%) stddev-sans-first:23.49 17:13:18 INFO - PROCESS | 2018 | Values: 218.0 147.0 220.0 206.0 163.0 165.0 166.0 163.0 164.0 166.0 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - PROCESS | 2018 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:67.50 stddev:7.24 (10.7%) stddev-sans-first:7.54 17:13:18 INFO - PROCESS | 2018 | Values: 67.0 66.0 67.0 67.0 65.0 66.0 68.0 79.0 82.0 83.0 17:13:18 INFO - PROCESS | 2018 | -------- Summary: end -------- 17:13:18 INFO - PROCESS | 2018 | 17:13:18 INFO - TEST-INFO | 2018: exit 0 17:13:18 INFO - TEST-OK | kraken | took 60332ms 17:13:18 INFO - SUITE-END | took 1178s 17:13:19 INFO - Completed test suite (00:19:38) 17:13:19 INFO - javascript benchmark 17:13:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3252.0, 3268.313654132632, 3275.1320899961515, 3302.702756751895, 3314.07205982325, 5463.426340234278, 5543.473370029846, 5557.555395568355, 5640.915386269204, 5650.576206784493, 4642.558956899097, 5555.333359996796, 5571.1086226203815, 5613.158026296058, 5621.241132447119, 5084.822031228899, 5116.360454943132, 5127.589792816584, 5160.690358578498, 5161.122609156452, 5095.4140273868425, 5190.4550022247495, 5232.764525596338, 5259.0, 5269.183276592115, 5189.584833213353, 5200.453952335007, 5200.92198617021, 5206.323177986876, 5233.267342572043, 64.54644906778161, 4529.479109902338, 5198.16829307309, 5209.453007434222, 5287.391949925731, 5087.465816089294, 5090.312807770941, 5095.643304968654, 5145.6398052136365, 5162.328897243372, 5496.587755918322, 5550.389457159709, 5564.4713752193475, 5608.4391560844, 5611.046122159243, 5932.377100404438, 5985.0, 6091.664958427285, 6110.297315808694, 6137.631742095488, 6124.472479235491, 6171.6297022178815, 6191.597546159485, 6207.255129384457, 6256.0, 4851.223804191312, 5744.4829965303325, 5749.137629355589, 5768.7980920668415, 5770.971145144247, 6128.503123582198, 6348.936510634834, 6356.77751278703, 6357.173567436296, 6359.554831161774, 5862.443067908542, 5999.670018149044, 6053.424924632153, 6086.056661217518, 6115.388461153849, 6185.907211391833, 6213.37866213375, 6233.688315584202, 6234.688265586793, 6240.095186197976, 5700.173474846124, 6116.66358350295, 6163.075538669146, 6178.907316390258, 6179.0, 5916.911246331309, 5970.31341395745, 6080.513558915276, 6111.257501106137, 6122.062734707273, 4865.489123642037, 4877.170880950247, 4884.584810291165, 4943.777530011158, 4956.702597844141, 4401.955980440219, 4421.668374871898, 4424.048829501673, 4435.556444355539, 4463.888402789957, 5433.809816656398, 5436.293281873332, 5501.944980550224, 5600.60795744294, 5601.859953501195, 6103.883205879379, 6150.108234306001, 6181.629102253879, 6189.535784816067, 6198.845028874314, 5553.694546799884, 5560.332760068737, 5578.079616863172, 5585.106382978785, 5601.0, 5235.345581802275, 5273.077211487975, 5280.498352656491, 5346.064438723208, 5350.892982140335, 5287.524122828964, 6287.339829317947, 6341.143945567474, 6357.777477788255, 6358.809235722936, 6113.266408031065, 6120.051392034242, 6127.080937859357, 6136.29432615255, 6136.723847426787, 6137.46891820379, 6280.434760871361, 6307.779227727007, 6354.586951848115, 6357.3006969234275, 5912.793052243151, 6072.848178795389, 6090.908636370546, 6114.905704492528, 6147.616786223064, 6194.38056194377, 6213.471854892385, 6215.409536094064, 6221.564490485623, 6226.750929962932, 5947.04847224442, 5956.225690660186, 6016.458518733335, 6025.367336429787, 6026.819195424145, 5774.884502310014, 5808.854778630399, 5811.389804070681, 5897.203877476657, 6025.246844144482, 5729.914051289317, 5741.569382296261, 5753.971230143822, 5758.992176369203, 5795.420457954171, 5879.265091863517, 5887.970560147171, 5891.998360278849, 5896.705164741831, 5902.026165682614, 5688.317401911797, 5760.740766665594, 5784.971075144597, 5808.709564521672, 5862.736176872137], "value": 5559.077899187601, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.30294377524075, 522.2062465053119, 534.1000414301899, 539.7813885376422, 552.5412909917815, 31282.843585782095, 32842.34315313695, 32889.17777055575, 32990.340193196185, 41480.792596037056, 40660.59339406605, 43478.34782478258, 43619.56380436195, 43650.563494365044, 43845.78077109618, 9646.710598682033, 9801.852972205423, 9832.115109640132, 9840.409575425461, 9869.753256168617, 8576.271016963565, 8779.429337093101, 9703.854442183372, 9810.21518278536, 9851.704448866545, 10136.94931525345, 11602.361870097142, 11779.175457717964, 11837.467313970848, 11996.460159292852, 11851.0, 11937.462814173296, 11955.7608847823, 11959.0, 11991.64025079249, 9997.650082247159, 11733.0, 11746.58886938953, 11817.645470635895, 11832.349220792852, 40216.0, 40562.59437405647, 40565.18869622591, 40678.0, 40781.1843763126, 3362.8150451725146, 3413.149492162012, 3705.6294370562996, 4136.198465748504, 4311.892202694927, 31484.527732084036, 31994.6800531994, 32922.50616240758, 32999.835000825085, 33225.66774332274, 3219.5653586765957, 3244.205169733407, 3266.4120458317493, 3366.309906469168, 3373.8987830365127, 513801.0, 521144.0, 521370.0, 522563.0, 523143.0, 33284.1678958028, 33962.660373396444, 58770.0, 59029.70485147633, 59131.11303330454, 35196.0, 35298.470522942174, 35402.46896296558, 35433.29133417317, 35456.29087418289, 14183.290835458185, 14259.78610320846, 14278.928605356907, 14280.857191427953, 14297.0, 11897.881021189678, 11998.520059197534, 12014.63956081319, 12022.398880056136, 12036.217645853167, 7219.566825990458, 7400.074990626172, 7642.885356719654, 7778.844423111506, 7857.096433910059, 2904.4917139500503, 3283.212029112996, 3286.901392958215, 3287.425149700599, 3328.6671332866517, 844.687465637718, 901.8827552418275, 1014.2038499777639, 1056.5841772025524, 1068.2682362581656, 27866.442671146462, 30382.69617303843, 30926.53610195849, 31048.0, 31132.018876979575, 1083.550326614462, 1148.4602236948622, 1153.5270539078938, 1200.6218041317131, 1243.881831226032, 15422.768658470131, 15493.380264789508, 15720.842791572166, 15873.761893571605, 15957.361705531648, 664800.0, 671193.6440317767, 673399.0, 674690.0, 676191.0, 50789.49210507922, 51421.48578514242, 51484.0, 58826.0, 59593.70203148956, 1349.2713934475273, 1370.9520166794314, 1396.6508372906774, 1400.1179257067981, 1405.8313002439772, 30625.846870765505, 31687.0, 31813.886513972826, 31863.715433987792, 32074.839625802655, 50243.99512009666, 50748.74625626996, 50805.237921431944, 50829.0, 50863.0, 677148.0, 685529.0, 686496.0, 871937.0, 872112.0, 42407.3638895423, 42681.359779603954, 42791.572084278756, 43376.56623433851, 43377.0, 47369.0, 47622.0, 47898.76050619724, 48005.51994480011, 48236.27645585249, 38831.41752873653, 38917.13535808024, 39255.0, 39941.60058399495, 40422.39366409565, 32428.83785581057, 38650.8404747858, 38733.80633096817, 39652.0, 40184.39723404093, 37063.629363706015, 37205.0698732523, 37834.43248351222, 37990.43014354842, 38451.615483845926, 28720.11223768891, 33271.50092748562, 33680.32639347248, 34748.0, 34879.82560087183, 22905.427364316027, 25088.0, 25097.99608015659, 25332.493350132525, 25595.616065758655], "value": 19363.56520965601, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.7666075399108, 361.7160528984746, 371.32418997424685, 372.5957336290126, 392.02777112760356, 15136.848631513682, 15148.848511514881, 21152.153913843427, 21276.787232127674, 21413.892930535363, 19899.801001989978, 20493.975301234917, 20790.48023799406, 20980.160793568277, 21375.35873923781, 5786.855328616797, 5808.912866306998, 6003.1895694081395, 6067.575269731121, 6086.878262434749, 5857.0, 6022.939770602282, 6039.909401358983, 6042.879142417149, 6055.394460553932, 3646.4348026056005, 4045.453863728403, 4289.892752681177, 4293.484781826185, 6293.30773614902, 6604.9432090865475, 6634.966825165843, 6639.76760813374, 6644.169478815148, 6644.867102657968, 5587.916181257285, 5667.360057711229, 5707.14392841073, 5734.025215713339, 5775.711214439261, 21075.578488430147, 25497.362565935815, 25923.611145832827, 26099.217023489327, 26565.734342656713, 1829.2865782344895, 2185.573813106445, 2279.6922415473864, 2574.562324404837, 3119.937601247985, 15576.29906654192, 15647.513486218904, 15844.52466426009, 16130.677386452206, 16290.51128466148, 1852.110986726379, 1864.3008871673123, 1877.2115711401245, 1879.4549580621604, 1905.3236101184157, 111848.0, 115065.42467287694, 115341.0, 115898.0, 117999.82000179974, 10179.727534058242, 11205.66383008511, 11248.493817778219, 11416.885831141582, 11580.0, 14387.568372948619, 14392.0, 14393.712125757636, 14401.135931844123, 14406.495772648117, 8308.501489910625, 8453.450525715809, 8460.196281353261, 8466.788330291669, 8578.957105214435, 7284.927150728425, 8348.457350272212, 8364.306799125205, 8382.245597896097, 8485.787855303666, 4506.301523263899, 4525.932111018272, 4545.772711364419, 4563.498015218323, 4610.608098311681, 1887.9528011799814, 1923.3172223860608, 1925.1721759643488, 1929.0354822588706, 1935.2162374238455, 755.4296506137831, 826.900771907375, 852.5012867472598, 904.8235594059096, 995.785906030207, 16745.665086698198, 19390.612187756167, 20428.897855510626, 20974.790252097282, 21318.680219796715, 924.0502539641351, 929.4051806843626, 946.9763255918657, 982.3663736889808, 986.3933680786408, 11047.171462140372, 11399.430028498542, 11404.771904562029, 11452.94273528627, 11457.541698331974, 597185.168889868, 598519.0, 605475.0, 614815.0, 620822.0, 129786.0, 131609.0, 132954.3352283252, 133121.0, 135059.0, 952.9618815247451, 967.1634036558327, 991.0188912976246, 998.5556427389741, 1001.249063202598, 18098.81901181024, 18898.0, 19093.71359429582, 19144.329948451737, 19150.138243778783, 55293.89412211653, 56559.86880262454, 56661.0, 57131.71434142803, 57454.85090298254, 73048.0, 79108.0, 79177.20822791698, 79546.0, 83257.58371208105, 6316.684165791784, 7580.924190758022, 7862.921370786219, 8013.439059265796, 8370.16298370158, 11340.773184536098, 12983.35083245853, 13006.804897926728, 13447.46210151583, 13517.932410337886, 10966.013058824745, 11236.269642473213, 11322.94338528302, 11347.319160850375, 11520.193586449202, 9923.55344009507, 11311.660650180507, 11360.715982100182, 11505.539778408769, 11614.593489227836, 11075.667729968114, 11788.0, 11807.76384472323, 11973.401329933295, 11998.040156787261, 8162.102168761313, 8491.532965686947, 12119.575814846437, 12215.144939854124, 13009.869901301245, 11193.328400296008, 11588.47851846652, 12179.878201217874, 12271.877281227073, 12313.56902508408], "value": 9751.397222232692, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.4183063984494, 1502.0386952350498, 1535.654477742508, 1703.9659206815863, 1879.304657276807, 7662.731804386847, 8099.55452450115, 8115.14790946951, 8601.784955376119, 8838.116188381158, 8697.173768491999, 8774.517401542913, 8779.2098711116, 8784.692535761249, 8794.6921857735, 2279.2592407467596, 2334.661474086265, 2336.042222688698, 2337.357226762637, 2342.0982921575146, 2279.1453205048106, 2308.8960996755186, 2326.5463234669246, 2332.8367014309006, 2335.3577766114286, 2285.885705714716, 2313.618252988255, 2318.2002209237785, 2326.2672258238686, 2330.545543618995, 2277.908883644652, 2297.414159389362, 2300.2179259051836, 2304.331743794298, 2309.2956648222203, 2288.4507718147693, 2293.8508996915148, 2296.9310920672406, 2299.4596269876565, 2300.896459659319, 8718.738437846874, 8849.734507964771, 8864.157904999014, 8875.334349923782, 8904.287656987426, 5083.3391659084455, 5147.794088236466, 5251.186066159751, 5255.710935898524, 5266.315379000744, 2995.445842519141, 3638.5815631202486, 3643.908902277438, 3646.690031347339, 3656.305301992613, 1074.8710154781397, 1079.1906070447164, 1106.7952428800697, 1112.9888701112966, 1116.776644671061, 11482.0, 11614.303141811519, 11617.709557261052, 11618.477168527521, 11628.95339419448, 4353.25994580922, 4432.268675668479, 4510.616597589176, 4575.26795712691, 4596.47140578831, 9133.360664753403, 9150.81698366029, 9152.450852948845, 9153.038930912158, 9154.176124148858, 5154.561862241751, 5154.742262886915, 5156.92264616031, 5159.045576568347, 5160.690358578498, 4096.3855421686385, 4335.783210839445, 4390.385346051556, 4796.832110876103, 4842.055799119138, 3196.5844440223086, 3725.1990821973404, 3767.2842159990064, 3794.430835374716, 3851.1912498375655, 1118.3234143343323, 1124.8425220469142, 1127.3574062784298, 1136.1535655936216, 1140.811766058591, 895.4671970177734, 898.2723993565234, 908.123660667459, 947.7677968897526, 965.2153537045674, 5376.569874410116, 5655.095184770499, 5882.264926359442, 5986.820395388146, 6001.63990160583, 408.1143917698601, 423.1579157476661, 427.21392637547063, 436.77287810338447, 448.4887228559445, 3901.941470877939, 3921.9607803922167, 3926.862559810393, 4041.0503531670033, 4073.368627862686, 10416.114630256361, 10704.983026612459, 10791.460426978776, 10812.567497300177, 10813.351198927932, 10763.83854242187, 10775.191860610328, 10777.461126943621, 10791.0, 10796.568137274578, 7799.64901579419, 8165.591720413956, 8206.0, 8235.917640823514, 8256.463329883658, 5757.683327417032, 5783.681897495678, 6003.669798161143, 6091.208142941389, 6101.542384321282, 8749.65001399937, 9397.483138427453, 9795.0, 10008.899911000797, 10083.445410502492, 9143.222826059593, 9902.455364954709, 10192.980701929748, 10249.846252306072, 10291.794164116825, 7525.285097915689, 7600.695972161052, 7707.0, 7731.922680773345, 7762.4178186637355, 8133.34933205354, 8292.875606866022, 8303.709370172015, 8305.086440491661, 8307.626156823078, 7432.3682486987045, 7549.811254718676, 7565.962170189114, 7660.387169026353, 7687.88468172989, 7384.335409813142, 7420.814479638052, 7452.403807695479, 7455.850882982419, 7492.850142997218, 6912.135983002125, 7058.329458701415, 7078.079849619517, 7086.681099350439, 7087.893681594884, 4956.132676781477, 4996.0, 5005.149124648891, 5083.517065878884, 5141.562967147835, 4194.245035893447, 4950.133726597759, 5020.221865610836, 5140.948590514047, 5264.78940842362], "value": 4430.486810757602, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.12355204657365, 292.0814039844687, 295.2898279637472, 306.4682775384708, 311.2949170129657, 5103.515166059227, 5114.514121158489, 5145.614078944081, 5169.741512924348, 5225.346831646044, 4139.0, 5323.669082729318, 5356.866078348044, 5370.0, 5388.191771234307, 3556.270964452294, 3707.5921648618632, 4045.7572545647354, 4076.8165432555456, 4085.7957102144774, 3456.9629111266518, 4039.333509970851, 4053.574374690659, 4112.424260603518, 4125.690573207006, 2918.5622156676454, 3397.5073614325906, 3897.473841031467, 3960.425738267964, 3998.920021599566, 3903.8873920932397, 4001.9399709004388, 4039.7172197946156, 4046.9595304046875, 4047.575004624516, 2988.923987364547, 3199.40810949975, 3414.146463384154, 3485.285516469143, 3492.7729697569575, 5076.873078173038, 5099.46455622158, 5187.455317191679, 5202.869928251786, 5245.895082098336, 4237.639800616951, 4540.02389486262, 4543.659225558064, 4572.765353354575, 4576.427946506687, 4894.241392584119, 5138.274750805636, 5566.944330556683, 5610.635308704962, 5711.543076553866, 3653.7442379033478, 3765.303418867491, 3783.1487915218854, 3801.4107813288983, 3834.5206849143856, 4928.8767780805765, 5449.945500544944, 5761.423857614247, 5830.9125363119165, 5960.582759206857, 3512.034190597606, 3831.1227498526077, 3863.188730366598, 3887.3391523441082, 3909.4331321958157, 3485.477178423206, 3943.132510847609, 3962.920741585152, 3963.23635981991, 3980.6616437602547, 4242.0, 4520.2315606347, 4544.818207271738, 4603.263477843529, 4619.0, 5563.415841336601, 5569.718964638075, 5606.439356064442, 5620.74706638194, 5690.926899474321, 1322.6561094115407, 1342.89256859451, 1346.9663258418423, 1347.5783048883522, 1348.8246527951303, 685.143570536829, 688.900109484122, 879.4855009819199, 909.736176508805, 917.3945196170496, 5237.6071794615555, 5249.816256431007, 5257.924114393047, 5281.894362112736, 5298.013245033113, 5008.824691135793, 5589.35722391922, 5753.597248192586, 5771.604784014671, 5802.839432113594, 4661.156694416403, 5055.848324550269, 5073.923891141636, 5273.815416460406, 5279.815206467755, 2783.359827239746, 3023.591815104977, 3046.5430185471914, 3059.7552195824283, 3061.249993751545, 4750.786214620351, 5231.712255825966, 5536.75084621193, 5913.368550994852, 5979.282486101695, 5430.456954304538, 5651.9152212716845, 5733.77064917407, 5910.822675319747, 5943.732532036069, 5507.504324610804, 5964.761409543657, 5980.55145864062, 5988.520918326523, 5991.340952495222, 4616.722996620214, 5243.108671525773, 5520.951019306319, 5750.9424905752085, 5794.652320860761, 5038.924416133687, 5158.303629010035, 5411.810586629449, 5584.3857175711455, 5615.073512870331, 5859.267591551056, 5980.910286345621, 5992.310884248367, 5997.31030931431, 6051.878962420815, 3268.4443644580956, 3835.213781174908, 3845.769253844778, 3872.8644497442456, 3875.224955008953, 5483.917741233805, 5755.7697692093525, 5774.527495488624, 5777.364489906191, 5826.82519524415, 4475.776211189363, 5139.820106296262, 5193.480651934777, 5636.549076073986, 5648.491635752802, 5402.378726446352, 5612.663240205601, 5640.464155905182, 5690.743916523684, 5706.28671416073, 4779.173035234368, 5476.67139971603, 5507.06245034802, 5512.4211957743855, 5568.468671115475, 4306.504751953566, 4920.114379411741, 5012.348394708811, 5361.766793637407, 5682.772689092555, 4725.40932383452, 5422.5119739223655, 5516.289950115477, 5544.61187716856, 5585.944140558705], "value": 4060.780423196514, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.6801939127392, 747.85790699767, 763.9991610989604, 775.3138472451882, 853.0744142605273, 14035.649108772286, 15230.0, 16457.259423325948, 16501.927374720635, 16508.587285317873, 14911.92544037281, 14931.850681493182, 14988.175650339253, 15000.249987500636, 15006.94951353403, 678.915135608049, 704.4294121761365, 830.3523251863526, 841.7600983719641, 866.4931015356025, 857.2499063319596, 859.3855393393723, 860.4493124400389, 861.5433820075339, 862.0948004595182, 914.0356923445762, 916.0061333453211, 917.0, 917.8302014127374, 918.4581097152678, 715.6707914359401, 870.9651613935436, 891.0599317719802, 899.1952202778542, 914.1863741270275, 672.0725398949419, 672.9091572637681, 673.293042305582, 673.3401266758603, 673.9865202695919, 14947.626309342244, 14961.476348327866, 14983.775243371358, 14996.100233985995, 15017.474388396464, 13861.306934653227, 14074.22591757453, 14084.647883802885, 14084.647883802987, 14339.42642294307, 11900.523979040829, 12957.546485872948, 13242.404091815795, 13504.662383440493, 13602.387892544859, 4328.372386004012, 4535.909281814378, 4627.330885377157, 4670.813167473297, 4788.233882578806, 118985.99999999914, 119195.80804191934, 119327.0, 119531.0, 120022.0, 10734.302270352402, 11002.0, 11340.6030788922, 11465.713357166138, 11678.474468648932, 22662.433439164153, 22696.205632802772, 22710.0, 22730.318090457313, 22744.886275568515, 7853.332466740391, 7860.489068210548, 7904.723334683258, 8049.315808156255, 8093.433459657767, 3955.802209889494, 4478.888027799266, 4495.57292057254, 4506.1663592235545, 4576.359309696645, 3365.74756893234, 3514.769830559335, 3516.4373700207843, 3517.0, 3522.207503311786, 617.2531237202945, 626.6459450410504, 634.4353525362432, 637.9330170332141, 644.3813938618978, 534.4041393845833, 536.1582315764213, 539.7085573790188, 563.737861894213, 570.4552152694167, 7931.806115816504, 8719.215270625547, 8837.29301655866, 9186.954065229766, 9364.391314564531, 582.1156171171833, 628.8585068359582, 630.250002497027, 632.045611127192, 642.2966851297822, 4368.497622773421, 4400.515943246241, 4438.400815889813, 4489.371487991683, 4549.181147393501, 122993.0, 164001.0, 166606.0, 167544.0, 170312.148439257, 104328.87013389611, 138324.0, 138826.0, 140078.0, 141453.0, 107956.0, 108891.45554272179, 109195.0, 109305.0, 110270.44864775625, 8447.831043379221, 8465.95767021161, 8526.78683032929, 8652.870206946774, 8725.459954825503, 56353.436465636456, 58619.70690146522, 65135.34864651293, 66288.33711662822, 66773.66613166903, 95326.52336738272, 100358.0, 103430.0, 103685.48157259166, 103796.48101759443, 11574.537018519164, 11710.765784684429, 11879.703007424883, 12277.815832762695, 12383.380830958236, 12367.134300598871, 12428.502859885864, 12490.188137771016, 12552.748945020865, 12587.811182832447, 11937.462814173557, 11981.28112313264, 12006.63980080599, 12009.279443233434, 12165.878341216829, 11949.342786146846, 12032.0, 12034.63896083119, 12078.154529182873, 12104.45529951172, 11744.647660570197, 11842.585509507126, 11869.94065029704, 11877.584284550345, 11996.76006479883, 9584.472853993097, 10050.09549140581, 10167.644132455329, 10189.949050254701, 10450.42522661261, 9426.481543515172, 9781.755456113655, 9997.400155990665, 10010.618833723047, 10031.0], "value": 7702.302564648128, "unit": "score"}], "name": "dromaeo_css", "value": 7252.10244005454, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 115.0, 113.0, 115.0, 114.0, 117.0, 162.0, 159.0, 113.0], "value": 122.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 104.0, 170.0, 107.0, 105.0, 135.0, 109.0, 139.0, 107.0], "value": 122.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 185.0, 184.0, 184.0, 184.0, 259.0, 210.0, 186.0, 183.0], "value": 194.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 65.0, 88.0, 59.0, 58.0, 84.0, 88.0, 95.0, 56.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 103.0, 71.0, 74.0, 85.0, 105.0, 70.0, 68.0, 103.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 130.0, 91.0, 88.0, 91.0, 87.0, 91.0, 88.0, 92.0, 87.0], "value": 96.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 113.0, 148.0, 96.0, 95.0, 96.0, 100.0, 94.0], "value": 102.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 84.0, 85.0, 84.0, 85.0, 84.0, 87.0, 84.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 53.0, 55.0, 52.0, 53.0, 53.0, 53.0, 53.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 74.0, 116.0, 110.0, 103.0, 73.0, 74.0, 74.0, 73.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 85.0, 85.0, 86.0, 82.0, 82.0, 91.0, 87.0, 87.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 135.0, 136.0, 137.0, 137.0, 141.0, 123.0, 200.0, 136.0], "value": 141.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 147.0, 220.0, 206.0, 163.0, 165.0, 166.0, 163.0, 164.0, 166.0], "value": 177.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 65.0, 66.0, 68.0, 79.0, 82.0, 83.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1493.2, "alertThreshold": 2.0}]} 17:13:19 INFO - Return code: 0 17:13:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:13:19 INFO - # TBPL SUCCESS # 17:13:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:13:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:13:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:13:19 INFO - 2016-08-11 17:13:19.381 screenresolution[2022:279842] starting screenresolution argv=screenresolution get 17:13:19 INFO - 2016-08-11 17:13:19.384 screenresolution[2022:279842] Display 0: 1600x1200x32@60 17:13:19 INFO - 2016-08-11 17:13:19.390 screenresolution[2023:279845] starting screenresolution argv=screenresolution list 17:13:19 INFO - Available Modes on Display 0 17:13:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:13:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:13:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:13:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:13:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:13:19 INFO - Intel Iris: 17:13:19 INFO - Chipset Model: Intel Iris 17:13:19 INFO - Type: GPU 17:13:19 INFO - Bus: Built-In 17:13:19 INFO - VRAM (Dynamic, Max): 1536 MB 17:13:19 INFO - Vendor: Intel (0x8086) 17:13:19 INFO - Device ID: 0x0a2e 17:13:19 INFO - Revision ID: 0x0009 17:13:19 INFO - Displays: 17:13:19 INFO - Display: 17:13:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:13:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:13:19 INFO - Display Serial Number: 1600x1200 60 17:13:19 INFO - Main Display: Yes 17:13:19 INFO - Mirror: Off 17:13:19 INFO - Online: Yes 17:13:19 INFO - Rotation: Supported 17:13:19 INFO - Return code: 0 17:13:19 INFO - Running post-action listener: _resource_record_post_action 17:13:19 INFO - [mozharness: 2016-08-12 00:13:19.628888Z] Finished run-tests step (success) 17:13:19 INFO - Running post-run listener: _resource_record_post_run 17:13:19 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 9317888; Write bytes: 703537152; Read time: 83; Write time: 2272 17:13:19 INFO - TinderboxPrint: CPU usage
53.7% 17:13:19 INFO - TinderboxPrint: I/O read bytes / time
9,317,888 / 83 17:13:19 INFO - TinderboxPrint: I/O write bytes / time
703,537,152 / 2,272 17:13:19 INFO - TinderboxPrint: CPU idle
2,246.9 (46.3%) 17:13:19 INFO - TinderboxPrint: CPU system
975.1 (20.1%) 17:13:19 INFO - TinderboxPrint: CPU user
1,635.7 (33.7%) 17:13:19 INFO - TinderboxPrint: Swap in / out
422,559,744 / 0 17:13:19 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 404621824; Write bytes: 406672896; Read time: 30979; Write time: 749 17:13:19 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 7818752; Write bytes: 295451136; Read time: 74; Write time: 1515 17:13:20 INFO - Running post-run listener: _upload_blobber_files 17:13:20 INFO - Blob upload gear active. 17:13:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:13:20 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 17:13:20 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'] 17:13:20 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 17:13:20 INFO - (blobuploader) - INFO - Open directory for files ... 17:13:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:13:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:13:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:13:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:13:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:13:21 INFO - (blobuploader) - INFO - Done attempting. 17:13:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:13:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:13:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:13:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:13:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:13:22 INFO - (blobuploader) - INFO - Done attempting. 17:13:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:13:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:13:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:13:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:13:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:13:24 INFO - (blobuploader) - INFO - Done attempting. 17:13:24 INFO - (blobuploader) - INFO - Iteration through files over. 17:13:24 INFO - Return code: 0 17:13:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:13:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:13:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/633d476465034aa8eee39d63e729d8aa2cd9186024598fe4bab98f34340ee855bd7f56fada1d95f5e873088d15b316085306ca5975db2fe7fa086a36b6b43934", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4285652bb6213b25fdc26886dffa236f151f8a6c4097fcaf0438b13b85d39b153c440ea5cdb730ec22e51085eb1bd0a71806902d2bdc258a2ff4d90a61293d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/90f4506a99cd45f510866eed5e289b4106c1ec1e94b679647b371dfa92d2e1943dece4b80308f0cc06bbd70eed6c945d37cae5b1de95f8e29966e439d1275807"} 17:13:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:13:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:13:24 INFO - Contents: 17:13:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/633d476465034aa8eee39d63e729d8aa2cd9186024598fe4bab98f34340ee855bd7f56fada1d95f5e873088d15b316085306ca5975db2fe7fa086a36b6b43934", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4285652bb6213b25fdc26886dffa236f151f8a6c4097fcaf0438b13b85d39b153c440ea5cdb730ec22e51085eb1bd0a71806902d2bdc258a2ff4d90a61293d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/90f4506a99cd45f510866eed5e289b4106c1ec1e94b679647b371dfa92d2e1943dece4b80308f0cc06bbd70eed6c945d37cae5b1de95f8e29966e439d1275807"} 17:13:24 INFO - Running post-run listener: copy_logs_to_upload_dir 17:13:24 INFO - Copying logs to upload dir... 17:13:24 INFO - mkdir: /builds/slave/test/build/upload/logs 17:13:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.908221 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-11 17:13:24.419873) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 17:13:24.423402) ========= 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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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/633d476465034aa8eee39d63e729d8aa2cd9186024598fe4bab98f34340ee855bd7f56fada1d95f5e873088d15b316085306ca5975db2fe7fa086a36b6b43934", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4285652bb6213b25fdc26886dffa236f151f8a6c4097fcaf0438b13b85d39b153c440ea5cdb730ec22e51085eb1bd0a71806902d2bdc258a2ff4d90a61293d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/90f4506a99cd45f510866eed5e289b4106c1ec1e94b679647b371dfa92d2e1943dece4b80308f0cc06bbd70eed6c945d37cae5b1de95f8e29966e439d1275807"} build_url:https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011499 build_url: 'https://queue.taskcluster.net/v1/task/CEfodvLKQOKYldDz1EK82g/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/633d476465034aa8eee39d63e729d8aa2cd9186024598fe4bab98f34340ee855bd7f56fada1d95f5e873088d15b316085306ca5975db2fe7fa086a36b6b43934", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4285652bb6213b25fdc26886dffa236f151f8a6c4097fcaf0438b13b85d39b153c440ea5cdb730ec22e51085eb1bd0a71806902d2bdc258a2ff4d90a61293d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/90f4506a99cd45f510866eed5e289b4106c1ec1e94b679647b371dfa92d2e1943dece4b80308f0cc06bbd70eed6c945d37cae5b1de95f8e29966e439d1275807"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 17:13:24.457806) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 17:13:24.458554) ========= 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.6gCyCIWkZE/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.RlG7UHv29Q/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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.005408 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 17:13:24.508135) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 17:13:24.508405) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 17:13:24.508949) ========= ========= Total master_lag: 0.18 =========