builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0035 starttime: 1470318974.16 results: success (0) buildid: 20160804044521 builduid: 224aad0cbe3549359b338444222101cb revision: 37963736ff85b3df2476cdc9761fe48a47e95a7b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:14.160469) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:14.160847) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:14.161113) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:14.243081) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:14.243368) ========= 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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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-04 06:56:14-- 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% 165M=0s 2016-08-04 06:56:14 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155816 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:14.425461) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:14.425780) ========= 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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.029525 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:14.475219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-04 06:56:14.475585) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 37963736ff85b3df2476cdc9761fe48a47e95a7b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 37963736ff85b3df2476cdc9761fe48a47e95a7b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9LVwSjz0ST/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.FB6I4rlLGX/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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-04 06:56:14,555 truncating revision to first 12 chars 2016-08-04 06:56:14,555 Setting DEBUG logging. 2016-08-04 06:56:14,555 attempt 1/10 2016-08-04 06:56:14,555 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/37963736ff85?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-04 06:56:15,634 unpacking tar archive at: larch-37963736ff85/testing/mozharness/ program finished with exit code 0 elapsedTime=1.345365 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-04 06:56:15.842328) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:15.842597) ========= script_repo_revision: 37963736ff85b3df2476cdc9761fe48a47e95a7b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:15.842966) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:15.843224) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 06:56:15.860909) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-08-04 06:56:15.861169) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9LVwSjz0ST/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.FB6I4rlLGX/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 06:56:16 INFO - MultiFileLogger online at 20160804 06:56:16 in /builds/slave/test 06:56:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 06:56:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:56:16 INFO - {'append_to_log': False, 06:56:16 INFO - 'base_work_dir': '/builds/slave/test', 06:56:16 INFO - 'blob_upload_branch': 'Larch', 06:56:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:56:16 INFO - 'branch': 'Larch', 06:56:16 INFO - 'buildbot_json_path': 'buildprops.json', 06:56:16 INFO - 'config_files': ('talos/mac_config.py',), 06:56:16 INFO - 'default_actions': ('clobber', 06:56:16 INFO - 'read-buildbot-config', 06:56:16 INFO - 'download-and-extract', 06:56:16 INFO - 'populate-webroot', 06:56:16 INFO - 'create-virtualenv', 06:56:16 INFO - 'install', 06:56:16 INFO - 'run-tests'), 06:56:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:56:16 INFO - 'download_minidump_stackwalk': True, 06:56:16 INFO - 'download_symbols': 'ondemand', 06:56:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:56:16 INFO - 'tooltool.py': '/tools/tooltool.py', 06:56:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:56:16 INFO - '/tools/misc-python/virtualenv.py')}, 06:56:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:56:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:56:16 INFO - 'installer_path': 'installer.exe', 06:56:16 INFO - 'log_level': 'info', 06:56:16 INFO - 'log_name': 'talos', 06:56:16 INFO - 'log_to_console': True, 06:56:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:56:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:56:16 INFO - 'opt_config_files': (), 06:56:16 INFO - 'pip_index': False, 06:56:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:56:16 INFO - 'cmd': ('bash', 06:56:16 INFO - '-c', 06:56:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:56:16 INFO - 'enabled': True, 06:56:16 INFO - 'halt_on_failure': False, 06:56:16 INFO - 'name': 'check_screen_resolution'},), 06:56:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:56:16 INFO - 'cmd': ('bash', 06:56:16 INFO - '-c', 06:56:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:56:16 INFO - 'enabled': True, 06:56:16 INFO - 'halt_on_failure': False, 06:56:16 INFO - 'name': 'check_screen_resolution'},), 06:56:16 INFO - 'run_cmd_checks_enabled': True, 06:56:16 INFO - 'sps_profile': False, 06:56:16 INFO - 'sps_profile_interval': 0, 06:56:16 INFO - 'suite': 'dromaeojs', 06:56:16 INFO - 'system_bits': '32', 06:56:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:56:16 INFO - 'title': 't-yosemite-r7-0035', 06:56:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:56:16 INFO - 'use_talos_json': True, 06:56:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:56:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:56:16 INFO - 'work_dir': 'build'} 06:56:16 INFO - ##### 06:56:16 INFO - ##### Running clobber step. 06:56:16 INFO - ##### 06:56:16 INFO - Running pre-action listener: _resource_record_pre_action 06:56:16 INFO - Running main action method: clobber 06:56:16 INFO - rmtree: /builds/slave/test/build 06:56:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:56:18 INFO - Running post-action listener: _resource_record_post_action 06:56:18 INFO - ##### 06:56:18 INFO - ##### Finished clobber step (success) 06:56:18 INFO - ##### 06:56:18 INFO - ##### 06:56:18 INFO - ##### Running read-buildbot-config step. 06:56:18 INFO - ##### 06:56:18 INFO - Running pre-action listener: _resource_record_pre_action 06:56:18 INFO - Running main action method: read_buildbot_config 06:56:18 INFO - Using buildbot properties: 06:56:18 INFO - { 06:56:18 INFO - "project": "", 06:56:18 INFO - "product": "firefox", 06:56:18 INFO - "script_repo_revision": "production", 06:56:18 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 06:56:18 INFO - "repository": "", 06:56:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 06:56:18 INFO - "buildid": "20160804044521", 06:56:18 INFO - "pgo_build": "False", 06:56:18 INFO - "basedir": "/builds/slave/test", 06:56:18 INFO - "buildnumber": 0, 06:56:18 INFO - "slavename": "t-yosemite-r7-0035", 06:56:18 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 06:56:18 INFO - "platform": "yosemite_r7", 06:56:18 INFO - "scheduler": "tests-larch-macosx64-talos", 06:56:18 INFO - "branch": "larch", 06:56:18 INFO - "repo_path": "projects/larch", 06:56:18 INFO - "revision": "37963736ff85b3df2476cdc9761fe48a47e95a7b", 06:56:18 INFO - "stage_platform": "macosx64", 06:56:18 INFO - "builduid": "224aad0cbe3549359b338444222101cb", 06:56:18 INFO - "slavebuilddir": "test" 06:56:18 INFO - } 06:56:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 06:56:18 INFO - Running post-action listener: _resource_record_post_action 06:56:18 INFO - ##### 06:56:18 INFO - ##### Finished read-buildbot-config step (success) 06:56:18 INFO - ##### 06:56:18 INFO - ##### 06:56:18 INFO - ##### Running download-and-extract step. 06:56:18 INFO - ##### 06:56:18 INFO - Running pre-action listener: _resource_record_pre_action 06:56:18 INFO - Running main action method: download_and_extract 06:56:18 INFO - mkdir: /builds/slave/test/build/tests 06:56:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:18 INFO - https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:56:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 06:56:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 06:56:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 06:56:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 06:56:25 INFO - Downloaded 1199 bytes. 06:56:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 06:56:25 INFO - Using the following test package requirements: 06:56:25 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:56:25 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:56:25 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 06:56:25 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:56:25 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 06:56:25 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:56:25 INFO - u'jsshell-mac.zip'], 06:56:25 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:56:25 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 06:56:25 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:56:25 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:56:25 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 06:56:25 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:56:25 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 06:56:25 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:56:25 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 06:56:25 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:56:25 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 06:56:25 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:56:25 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:25 INFO - https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:56:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:56:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:56:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:56:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:56:38 INFO - Downloaded 18789532 bytes. 06:56:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:56:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:56:42 INFO - Return code: 0 06:56:42 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:42 INFO - https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:56:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:56:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:56:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:56:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:56:45 INFO - Downloaded 18789532 bytes. 06:56:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:56:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:56:48 INFO - Return code: 0 06:56:48 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:48 INFO - https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:56:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:56:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:56:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:56:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:56:50 INFO - Downloaded 14405242 bytes. 06:56:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:56:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 06:56:51 INFO - Return code: 0 06:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:51 INFO - https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:56:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:56:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:56:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 06:56:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 06:56:55 INFO - Downloaded 112477054 bytes. 06:56:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:56:55 INFO - mkdir: /builds/slave/test/properties 06:56:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:56:55 INFO - Writing to file /builds/slave/test/properties/build_url 06:56:55 INFO - Contents: 06:56:55 INFO - build_url:https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:56:55 INFO - Running post-action listener: _resource_record_post_action 06:56:55 INFO - Running post-action listener: set_extra_try_arguments 06:56:55 INFO - ##### 06:56:55 INFO - ##### Finished download-and-extract step (success) 06:56:55 INFO - ##### 06:56:55 INFO - ##### 06:56:55 INFO - ##### Running populate-webroot step. 06:56:55 INFO - ##### 06:56:55 INFO - Running pre-action listener: _resource_record_pre_action 06:56:55 INFO - Running main action method: populate_webroot 06:56:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:56:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:56:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:56:55 INFO - '--tppagecycles', 06:56:55 INFO - '7'], 06:56:55 INFO - 'tests': ['tsvgm']}}, 06:56:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:56:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:56:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:56:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:56:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:56:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:56:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:55 INFO - 'talos_options': ['--disable-e10s'], 06:56:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:56:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:56:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:55 INFO - 'talos_options': ['--disable-e10s'], 06:56:55 INFO - 'tests': ['damp', 'tps']}, 06:56:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:55 INFO - 'tests': ['damp', 'tps']}, 06:56:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:56:55 INFO - 'tests': ['dromaeo_dom']}, 06:56:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:56:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:56:55 INFO - 'tests': ['basic_compositor_video']}, 06:56:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:56:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:56:55 INFO - 'tests': ['a11yr', 06:56:55 INFO - 'ts_paint', 06:56:55 INFO - 'tpaint', 06:56:55 INFO - 'sessionrestore', 06:56:55 INFO - 'sessionrestore_no_auto_restore', 06:56:55 INFO - 'tabpaint']}, 06:56:55 INFO - 'other-e10s': {'tests': ['a11yr', 06:56:55 INFO - 'ts_paint', 06:56:55 INFO - 'tpaint', 06:56:55 INFO - 'sessionrestore', 06:56:55 INFO - 'sessionrestore_no_auto_restore', 06:56:55 INFO - 'tabpaint']}, 06:56:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:56:55 INFO - 'ts_paint', 06:56:55 INFO - 'tpaint', 06:56:55 INFO - 'sessionrestore', 06:56:55 INFO - 'sessionrestore_no_auto_restore', 06:56:55 INFO - 'tabpaint']}, 06:56:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:56:55 INFO - 'ts_paint', 06:56:55 INFO - 'tpaint', 06:56:55 INFO - 'sessionrestore', 06:56:55 INFO - 'sessionrestore_no_auto_restore', 06:56:55 INFO - 'tabpaint']}, 06:56:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:56:55 INFO - 'tests': ['a11yr', 06:56:55 INFO - 'ts_paint', 06:56:55 INFO - 'tpaint', 06:56:55 INFO - 'sessionrestore', 06:56:55 INFO - 'sessionrestore_no_auto_restore', 06:56:55 INFO - 'tabpaint']}, 06:56:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:56:55 INFO - 'tests': ['a11yr', 06:56:55 INFO - 'ts_paint', 06:56:55 INFO - 'tpaint', 06:56:55 INFO - 'sessionrestore', 06:56:55 INFO - 'sessionrestore_no_auto_restore', 06:56:55 INFO - 'tabpaint']}, 06:56:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:56:55 INFO - 'tests': ['tsvgx', 06:56:55 INFO - 'tsvgr_opacity', 06:56:55 INFO - 'tart', 06:56:55 INFO - 'tscrollx', 06:56:55 INFO - 'cart']}, 06:56:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:56:55 INFO - 'tsvgr_opacity', 06:56:55 INFO - 'tart', 06:56:55 INFO - 'tscrollx', 06:56:55 INFO - 'cart']}, 06:56:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:55 INFO - 'talos_options': ['--disable-e10s'], 06:56:55 INFO - 'tests': ['tp5o']}, 06:56:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:55 INFO - 'tests': ['tp5o']}, 06:56:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:55 INFO - 'talos_options': ['--disable-e10s', 06:56:55 INFO - '--xperf_path', 06:56:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:56:55 INFO - 'tests': ['tp5n']}, 06:56:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:55 INFO - 'talos_options': ['--xperf_path', 06:56:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:56:55 INFO - 'tests': ['tp5n']}}, 06:56:55 INFO - 'talos.zip': {'path': '', 06:56:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:56:55 INFO - Running post-action listener: _resource_record_post_action 06:56:55 INFO - ##### 06:56:55 INFO - ##### Finished populate-webroot step (success) 06:56:55 INFO - ##### 06:56:55 INFO - ##### 06:56:55 INFO - ##### Running create-virtualenv step. 06:56:55 INFO - ##### 06:56:55 INFO - Running pre-action listener: _resource_record_pre_action 06:56:55 INFO - Running main action method: create_virtualenv 06:56:55 INFO - Creating virtualenv /builds/slave/test/build/venv 06:56:55 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 06:56:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:56:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:56:56 INFO - Using real prefix '/tools/python27' 06:56:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:56:56 INFO - Installing distribute.............................................................................................................................................................................................done. 06:56:59 INFO - Installing pip.................done. 06:56:59 INFO - Return code: 0 06:56:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:59 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 0x105cc6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb704a0fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c2ad30>, '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 0x10598fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c22b20>, '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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 06:56:59 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 06:56:59 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 06:56:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9LVwSjz0ST/Render', 06:56:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:56:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:56:59 INFO - 'HOME': '/Users/cltbld', 06:56:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:56:59 INFO - 'LOGNAME': 'cltbld', 06:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:59 INFO - 'MOZ_NO_REMOTE': '1', 06:56:59 INFO - 'NO_EM_RESTART': '1', 06:56:59 INFO - 'PAGER': '/bin/cat', 06:56:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:59 INFO - 'PWD': '/builds/slave/test', 06:56:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:56:59 INFO - 'SHELL': '/bin/bash', 06:56:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FB6I4rlLGX/Listeners', 06:56:59 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 06:56:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:56:59 INFO - 'USER': 'cltbld', 06:56:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:56:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:59 INFO - 'XPC_FLAGS': '0x0', 06:56:59 INFO - 'XPC_SERVICE_NAME': '0', 06:56:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:56:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:56:59 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 06:56:59 INFO - Cleaning up... 06:56:59 INFO - Return code: 0 06:56:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:56:59 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 0x105cc6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb704a0fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c2ad30>, '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 0x10598fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c22b20>, '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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 06:56:59 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 06:56:59 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 06:56:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9LVwSjz0ST/Render', 06:56:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:56:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:56:59 INFO - 'HOME': '/Users/cltbld', 06:56:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:56:59 INFO - 'LOGNAME': 'cltbld', 06:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:56:59 INFO - 'MOZ_NO_REMOTE': '1', 06:56:59 INFO - 'NO_EM_RESTART': '1', 06:56:59 INFO - 'PAGER': '/bin/cat', 06:56:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:56:59 INFO - 'PWD': '/builds/slave/test', 06:56:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:56:59 INFO - 'SHELL': '/bin/bash', 06:56:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FB6I4rlLGX/Listeners', 06:56:59 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 06:56:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:56:59 INFO - 'USER': 'cltbld', 06:56:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:56:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:56:59 INFO - 'XPC_FLAGS': '0x0', 06:56:59 INFO - 'XPC_SERVICE_NAME': '0', 06:56:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:00 INFO - Downloading/unpacking psutil>=3.1.1 06:57:00 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 06:57:00 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 06:57:00 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 06:57:00 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 06:57:00 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 06:57:00 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 06:57:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:57:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:57:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:57:03 INFO - Installing collected packages: psutil 06:57:03 INFO - Running setup.py install for psutil 06:57:03 INFO - building 'psutil._psutil_osx' extension 06:57:03 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 06:57:04 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 06:57:04 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 06:57:04 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 06:57:04 INFO - building 'psutil._psutil_posix' extension 06:57:04 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 06:57:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:57:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:57:04 INFO - ^ 06:57:04 INFO - 1 warning generated. 06:57:04 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 06:57:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:57:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:57:04 INFO - Successfully installed psutil 06:57:04 INFO - Cleaning up... 06:57:04 INFO - Return code: 0 06:57:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:57: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105cc6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb704a0fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c2ad30>, '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 0x10598fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c22b20>, '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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 06:57: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:57: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 mozsystemmonitor==0.3 06:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9LVwSjz0ST/Render', 06:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:04 INFO - 'HOME': '/Users/cltbld', 06:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:04 INFO - 'LOGNAME': 'cltbld', 06:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:04 INFO - 'MOZ_NO_REMOTE': '1', 06:57:04 INFO - 'NO_EM_RESTART': '1', 06:57:04 INFO - 'PAGER': '/bin/cat', 06:57:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:04 INFO - 'PWD': '/builds/slave/test', 06:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:04 INFO - 'SHELL': '/bin/bash', 06:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FB6I4rlLGX/Listeners', 06:57:04 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 06:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:04 INFO - 'USER': 'cltbld', 06:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:04 INFO - 'XPC_FLAGS': '0x0', 06:57:04 INFO - 'XPC_SERVICE_NAME': '0', 06:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:57:05 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 06:57:05 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 06:57:05 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 06:57:05 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 06:57:05 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 06:57:05 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 06:57:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:57:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:57:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:57:07 INFO - Installing collected packages: mozsystemmonitor 06:57:07 INFO - Running setup.py install for mozsystemmonitor 06:57:07 INFO - Successfully installed mozsystemmonitor 06:57:07 INFO - Cleaning up... 06:57:07 INFO - Return code: 0 06:57:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:07 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 0x105cc6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb704a0fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c2ad30>, '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 0x10598fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c22b20>, '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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 06:57:07 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 06:57:07 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 06:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9LVwSjz0ST/Render', 06:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:07 INFO - 'HOME': '/Users/cltbld', 06:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:07 INFO - 'LOGNAME': 'cltbld', 06:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:07 INFO - 'MOZ_NO_REMOTE': '1', 06:57:07 INFO - 'NO_EM_RESTART': '1', 06:57:07 INFO - 'PAGER': '/bin/cat', 06:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:07 INFO - 'PWD': '/builds/slave/test', 06:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:07 INFO - 'SHELL': '/bin/bash', 06:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FB6I4rlLGX/Listeners', 06:57:07 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 06:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:07 INFO - 'USER': 'cltbld', 06:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:07 INFO - 'XPC_FLAGS': '0x0', 06:57:07 INFO - 'XPC_SERVICE_NAME': '0', 06:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:08 INFO - Downloading/unpacking jsonschema==2.5.1 06:57:08 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 06:57:08 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 06:57:08 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 06:57:08 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 06:57:08 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 06:57:08 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 06:57:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:57:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:57:10 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 06:57:10 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 06:57:10 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 06:57:10 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 06:57:10 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 06:57:10 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 06:57:10 INFO - Downloading functools32-3.2.3-2.tar.gz 06:57:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:57:11 INFO - warning: no files found matching '*.txt' 06:57:11 INFO - no previously-included directories found matching 'build' 06:57:11 INFO - no previously-included directories found matching 'dist' 06:57:11 INFO - no previously-included directories found matching '.git*' 06:57:11 INFO - Installing collected packages: jsonschema, functools32 06:57:11 INFO - Running setup.py install for functools32 06:57:11 INFO - warning: no files found matching '*.txt' 06:57:11 INFO - no previously-included directories found matching 'build' 06:57:11 INFO - no previously-included directories found matching 'dist' 06:57:11 INFO - no previously-included directories found matching '.git*' 06:57:11 INFO - Successfully installed jsonschema functools32 06:57:11 INFO - Cleaning up... 06:57:11 INFO - Return code: 0 06:57:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:57:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:11 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 0x105cc6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb704a0fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c2ad30>, '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 0x10598fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c22b20>, '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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 06:57:11 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 06:57:11 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 06:57:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9LVwSjz0ST/Render', 06:57:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:11 INFO - 'HOME': '/Users/cltbld', 06:57:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:11 INFO - 'LOGNAME': 'cltbld', 06:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:11 INFO - 'MOZ_NO_REMOTE': '1', 06:57:11 INFO - 'NO_EM_RESTART': '1', 06:57:11 INFO - 'PAGER': '/bin/cat', 06:57:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:11 INFO - 'PWD': '/builds/slave/test', 06:57:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:11 INFO - 'SHELL': '/bin/bash', 06:57:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FB6I4rlLGX/Listeners', 06:57:11 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 06:57:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:11 INFO - 'USER': 'cltbld', 06:57:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:11 INFO - 'XPC_FLAGS': '0x0', 06:57:11 INFO - 'XPC_SERVICE_NAME': '0', 06:57:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:11 INFO - Downloading/unpacking blobuploader==1.2.4 06:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:57:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:14 INFO - Downloading blobuploader-1.2.4.tar.gz 06:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:57:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:57:14 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 06:57:14 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 06:57:14 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 06:57:14 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 06:57:14 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 06:57:14 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 06:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:57:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:57:14 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 06:57:14 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 06:57:14 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 06:57:14 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 06:57:14 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 06:57:14 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 06:57:15 INFO - Downloading docopt-0.6.1.tar.gz 06:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:57:15 INFO - Installing collected packages: blobuploader, requests, docopt 06:57:15 INFO - Running setup.py install for blobuploader 06:57:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:57:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:57:15 INFO - Running setup.py install for requests 06:57:15 INFO - Running setup.py install for docopt 06:57:16 INFO - Successfully installed blobuploader requests docopt 06:57:16 INFO - Cleaning up... 06:57:16 INFO - Return code: 0 06:57:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:16 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 0x105cc6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb704a0fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c2ad30>, '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 0x10598fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c22b20>, '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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 06:57:16 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 06:57:16 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 06:57:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9LVwSjz0ST/Render', 06:57:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:16 INFO - 'HOME': '/Users/cltbld', 06:57:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:16 INFO - 'LOGNAME': 'cltbld', 06:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:16 INFO - 'MOZ_NO_REMOTE': '1', 06:57:16 INFO - 'NO_EM_RESTART': '1', 06:57:16 INFO - 'PAGER': '/bin/cat', 06:57:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:16 INFO - 'PWD': '/builds/slave/test', 06:57:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:16 INFO - 'SHELL': '/bin/bash', 06:57:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FB6I4rlLGX/Listeners', 06:57:16 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 06:57:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:16 INFO - 'USER': 'cltbld', 06:57:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:16 INFO - 'XPC_FLAGS': '0x0', 06:57:16 INFO - 'XPC_SERVICE_NAME': '0', 06:57:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:57:16 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Kg7Q0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:57:16 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-_lU__Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:57:16 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Xx76Fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:57:16 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-zC11SH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:57:16 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-MXEDsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:57:16 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-bZ0caH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:57:17 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-8HPLI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:57:17 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-4_drDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:57:17 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-KbmwEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:57:17 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-PTsAxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:57:17 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-hIZlur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:57:17 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Sdjtt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:57:17 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-rzn__g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:57:17 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-CPclxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:57:17 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-K5JYh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:57:18 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-TFn2XJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:57:18 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-tS73Ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:57:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:57:18 INFO - Running setup.py install for manifestparser 06:57:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:57:18 INFO - Running setup.py install for mozcrash 06:57:18 INFO - Running setup.py install for mozdebug 06:57:18 INFO - Running setup.py install for mozdevice 06:57:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:57:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:57:18 INFO - Running setup.py install for mozfile 06:57:19 INFO - Running setup.py install for mozhttpd 06:57:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:57:19 INFO - Running setup.py install for mozinfo 06:57:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:57:19 INFO - Running setup.py install for mozInstall 06:57:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:57:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:57:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:57:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:57:19 INFO - Running setup.py install for mozleak 06:57:19 INFO - Running setup.py install for mozlog 06:57:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:57:19 INFO - Running setup.py install for moznetwork 06:57:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:57:19 INFO - Running setup.py install for mozprocess 06:57:20 INFO - Running setup.py install for mozprofile 06:57:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:57:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:57:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:57:20 INFO - Running setup.py install for mozrunner 06:57:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:57:20 INFO - Running setup.py install for mozscreenshot 06:57:20 INFO - Running setup.py install for moztest 06:57:20 INFO - Running setup.py install for mozversion 06:57:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:57:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:57:20 INFO - Cleaning up... 06:57:20 INFO - Return code: 0 06:57:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:57:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:20 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 0x105cc6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb704a0fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c2ad30>, '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 0x10598fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c22b20>, '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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 06:57:20 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 06:57:20 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 06:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9LVwSjz0ST/Render', 06:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:20 INFO - 'HOME': '/Users/cltbld', 06:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:20 INFO - 'LOGNAME': 'cltbld', 06:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:20 INFO - 'MOZ_NO_REMOTE': '1', 06:57:20 INFO - 'NO_EM_RESTART': '1', 06:57:20 INFO - 'PAGER': '/bin/cat', 06:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:20 INFO - 'PWD': '/builds/slave/test', 06:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:20 INFO - 'SHELL': '/bin/bash', 06:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FB6I4rlLGX/Listeners', 06:57:20 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 06:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:20 INFO - 'USER': 'cltbld', 06:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:20 INFO - 'XPC_FLAGS': '0x0', 06:57:20 INFO - 'XPC_SERVICE_NAME': '0', 06:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:57:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-_lJo5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:57:21 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)) 06:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:57:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-zSgAUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:57:21 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)) 06:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:57:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-IQQgSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:57:21 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)) 06:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:57:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-5iHr3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:57:21 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)) 06:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:57:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-uUpih8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:57:21 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)) 06:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:57:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-TivYWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:57:21 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)) 06:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:57:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-TmHPZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:57:21 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)) 06:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:57:21 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-GVIFM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:57:22 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)) 06:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:57:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-xYR9HK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:57:22 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)) 06:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:57:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-B8ktku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 06:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:57:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-1WYPCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:57:22 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)) 06:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:57:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-QzYbLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 06:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:57:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-6Chnpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:57:22 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)) 06:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:57:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-LMpqhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 06:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:57:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-TSYi9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:57:22 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)) 06:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:57:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-HIIh5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:57:22 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)) 06:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:57:22 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-imfv1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:57:22 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)) 06:57:22 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)) 06:57:22 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)) 06:57:22 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)) 06:57:22 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)) 06:57:22 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)) 06:57:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:57:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:25 INFO - Downloading blessings-1.6.tar.gz 06:57:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:57:25 INFO - Installing collected packages: blessings 06:57:25 INFO - Running setup.py install for blessings 06:57:26 INFO - Successfully installed blessings 06:57:26 INFO - Cleaning up... 06:57:26 INFO - Return code: 0 06:57:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:57:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:57:26 INFO - Reading from file tmpfile_stdout 06:57:26 INFO - Current package versions: 06:57:26 INFO - blessings == 1.6 06:57:26 INFO - blobuploader == 1.2.4 06:57:26 INFO - docopt == 0.6.1 06:57:26 INFO - functools32 == 3.2.3-2 06:57:26 INFO - jsonschema == 2.5.1 06:57:26 INFO - manifestparser == 1.1 06:57:26 INFO - mozInstall == 1.12 06:57:26 INFO - mozcrash == 0.17 06:57:26 INFO - mozdebug == 0.1 06:57:26 INFO - mozdevice == 0.48 06:57:26 INFO - mozfile == 1.2 06:57:26 INFO - mozhttpd == 0.7 06:57:26 INFO - mozinfo == 0.9 06:57:26 INFO - mozleak == 0.1 06:57:26 INFO - mozlog == 3.2 06:57:26 INFO - moznetwork == 0.27 06:57:26 INFO - mozprocess == 0.22 06:57:26 INFO - mozprofile == 0.28 06:57:26 INFO - mozrunner == 6.11 06:57:26 INFO - mozscreenshot == 0.1 06:57:26 INFO - mozsystemmonitor == 0.3 06:57:26 INFO - moztest == 0.7 06:57:26 INFO - mozversion == 1.4 06:57:26 INFO - psutil == 3.1.1 06:57:26 INFO - requests == 1.2.3 06:57:26 INFO - wsgiref == 0.1.2 06:57:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:26 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 0x105cc6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb704a0fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c2ad30>, '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 0x10598fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c22b20>, '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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 06:57:26 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 06:57:26 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 06:57:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9LVwSjz0ST/Render', 06:57:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:26 INFO - 'HOME': '/Users/cltbld', 06:57:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:26 INFO - 'LOGNAME': 'cltbld', 06:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:26 INFO - 'MOZ_NO_REMOTE': '1', 06:57:26 INFO - 'NO_EM_RESTART': '1', 06:57:26 INFO - 'PAGER': '/bin/cat', 06:57:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:26 INFO - 'PWD': '/builds/slave/test', 06:57:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:26 INFO - 'SHELL': '/bin/bash', 06:57:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FB6I4rlLGX/Listeners', 06:57:26 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 06:57:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:26 INFO - 'USER': 'cltbld', 06:57:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:26 INFO - 'XPC_FLAGS': '0x0', 06:57:26 INFO - 'XPC_SERVICE_NAME': '0', 06:57:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:26 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)) 06:57:26 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)) 06:57:26 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)) 06:57:26 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)) 06:57:26 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)) 06:57:26 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)) 06:57:26 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)) 06:57:26 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)) 06:57:26 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)) 06:57:26 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)) 06:57:26 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)) 06:57:26 INFO - Cleaning up... 06:57:26 INFO - Return code: 0 06:57:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105cc6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb704a0fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c2ad30>, '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 0x10598fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c22b20>, '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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 06:57:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:57:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9LVwSjz0ST/Render', 06:57:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:26 INFO - 'HOME': '/Users/cltbld', 06:57:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:26 INFO - 'LOGNAME': 'cltbld', 06:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:26 INFO - 'MOZ_NO_REMOTE': '1', 06:57:26 INFO - 'NO_EM_RESTART': '1', 06:57:26 INFO - 'PAGER': '/bin/cat', 06:57:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:26 INFO - 'PWD': '/builds/slave/test', 06:57:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:26 INFO - 'SHELL': '/bin/bash', 06:57:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FB6I4rlLGX/Listeners', 06:57:26 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 06:57:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:26 INFO - 'USER': 'cltbld', 06:57:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:26 INFO - 'XPC_FLAGS': '0x0', 06:57:26 INFO - 'XPC_SERVICE_NAME': '0', 06:57:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:57:26 INFO - Cleaning up... 06:57:26 INFO - Return code: 0 06:57:26 INFO - Running post-action listener: _resource_record_post_action 06:57:26 INFO - Running post-action listener: _start_resource_monitoring 06:57:26 INFO - Starting resource monitoring. 06:57:26 INFO - ##### 06:57:27 INFO - ##### Finished create-virtualenv step (success) 06:57:27 INFO - ##### 06:57:27 INFO - ##### 06:57:27 INFO - ##### Running install step. 06:57:27 INFO - ##### 06:57:27 INFO - Running pre-action listener: _resource_record_pre_action 06:57:27 INFO - Running main action method: install 06:57:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:57:27 INFO - Reading from file tmpfile_stdout 06:57:27 INFO - Detecting whether we're running mozinstall >=1.0... 06:57:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:57:27 INFO - Reading from file tmpfile_stdout 06:57:27 INFO - Output received: 06:57:27 INFO - Usage: mozinstall [options] installer 06:57:27 INFO - Options: 06:57:27 INFO - -h, --help show this help message and exit 06:57:27 INFO - -d DEST, --destination=DEST 06:57:27 INFO - Directory to install application into. [default: 06:57:27 INFO - "/builds/slave/test"] 06:57:27 INFO - --app=APP Application being installed. [default: firefox] 06:57:27 INFO - mkdir: /builds/slave/test/build/application 06:57:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:58:01 INFO - Reading from file tmpfile_stdout 06:58:01 INFO - Output received: 06:58:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:58:01 INFO - Running post-action listener: _resource_record_post_action 06:58:01 INFO - ##### 06:58:01 INFO - ##### Finished install step (success) 06:58:01 INFO - ##### 06:58:01 INFO - ##### 06:58:01 INFO - ##### Running run-tests step. 06:58:01 INFO - ##### 06:58:01 INFO - Running pre-action listener: _resource_record_pre_action 06:58:01 INFO - Running main action method: run_tests 06:58:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:58:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:58:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:58:01 INFO - 2016-08-04 06:58:01.660 screenresolution[1821:9187] starting screenresolution argv=screenresolution get 06:58:01 INFO - 2016-08-04 06:58:01.663 screenresolution[1821:9187] Display 0: 1600x1200x32@60 06:58:01 INFO - 2016-08-04 06:58:01.669 screenresolution[1822:9189] starting screenresolution argv=screenresolution list 06:58:01 INFO - Available Modes on Display 0 06:58:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:58:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:58:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:58:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:58:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:58:01 INFO - Intel Iris: 06:58:01 INFO - Chipset Model: Intel Iris 06:58:01 INFO - Type: GPU 06:58:01 INFO - Bus: Built-In 06:58:01 INFO - VRAM (Dynamic, Max): 1536 MB 06:58:01 INFO - Vendor: Intel (0x8086) 06:58:01 INFO - Device ID: 0x0a2e 06:58:01 INFO - Revision ID: 0x0009 06:58:01 INFO - Displays: 06:58:01 INFO - Display: 06:58:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:58:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:58:01 INFO - Display Serial Number: 1600x1200 60 06:58:01 INFO - Main Display: Yes 06:58:01 INFO - Mirror: Off 06:58:01 INFO - Online: Yes 06:58:01 INFO - Rotation: Supported 06:58:01 INFO - Return code: 0 06:58:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:58:01 INFO - Python 2.7.3 06:58:01 INFO - Return code: 0 06:58:01 INFO - grabbing minidump binary from tooltool 06:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:58:01 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 0x105c2ad30>, '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 0x10598fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105c22b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:58:01 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 06:58:01 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 06:58:01 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 06:58:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:58:02 INFO - Return code: 0 06:58:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:58:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:58:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:58:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:58:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:58:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--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 06:58:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0035 --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 06:58:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9LVwSjz0ST/Render', 06:58:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:58:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:58:02 INFO - 'HOME': '/Users/cltbld', 06:58:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:58:02 INFO - 'LOGNAME': 'cltbld', 06:58:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:58:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:58:02 INFO - 'MOZ_NO_REMOTE': '1', 06:58:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:58:02 INFO - 'NO_EM_RESTART': '1', 06:58:02 INFO - 'PAGER': '/bin/cat', 06:58:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:58:02 INFO - 'PWD': '/builds/slave/test', 06:58:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:58:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:58:02 INFO - 'SHELL': '/bin/bash', 06:58:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FB6I4rlLGX/Listeners', 06:58:02 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 06:58:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:58:02 INFO - 'USER': 'cltbld', 06:58:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:58:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:58:02 INFO - 'XPC_FLAGS': '0x0', 06:58:02 INFO - 'XPC_SERVICE_NAME': '0', 06:58:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:58:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--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 06:58:02 INFO - mozversion application_buildid: 20160804044521 06:58:02 INFO - mozversion application_changeset: 37963736ff85b3df2476cdc9761fe48a47e95a7b 06:58:02 INFO - mozversion application_display_name: Nightly 06:58:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:58:02 INFO - mozversion application_name: Firefox 06:58:02 INFO - mozversion application_remotingname: firefox 06:58:02 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 06:58:02 INFO - mozversion application_vendor: Mozilla 06:58:02 INFO - mozversion application_version: 50.0a1 06:58:02 INFO - mozversion platform_buildid: 20160804044521 06:58:02 INFO - mozversion platform_changeset: 37963736ff85b3df2476cdc9761fe48a47e95a7b 06:58:02 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 06:58:02 INFO - mozversion platform_version: 50.0a1 06:58:02 INFO - using testdate: 1470319082 06:58:02 INFO - actual date: 1470319082 06:58:02 INFO - starting webserver on 'localhost:49271' 06:58:02 INFO - SUITE-START | Running 2 tests 06:58:02 INFO - TEST-START | dromaeo_css 06:58:02 INFO - operating with platform_type : mac_ 06:58:02 INFO - Initialising browser for dromaeo_css test... 06:58:02 INFO - TEST-INFO | started process 1832 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpxIqHSK/profile http://localhost:49271/getInfo.html) 06:58:04 INFO - PROCESS | 1832 | __metrics Screen width/height:1600/1200 06:58:04 INFO - PROCESS | 1832 | colorDepth:24 06:58:04 INFO - PROCESS | 1832 | Browser inner width/height: 1024/689 06:58:04 INFO - PROCESS | 1832 | __metrics 06:58:04 INFO - PROCESS | 1832 | 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" 06:58:04 INFO - PROCESS | 1832 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:58:05 INFO - TEST-INFO | 1832: exit 0 06:58:05 INFO - Browser initialized. 06:58:05 INFO - Running cycle 1/1 for dromaeo_css test... 06:58:05 INFO - TEST-INFO | started process 1835 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpxIqHSK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:58:17 INFO - PROCESS | 1835 | RSS: Main: 245755904 06:58:17 INFO - PROCESS | 1835 | 06:59:08 INFO - PROCESS | 1835 | 1470319148743 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] 07:01:19 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 07:01:21 INFO - PROCESS | 1835 | RSS: Main: 334508032 07:01:21 INFO - PROCESS | 1835 | 07:04:24 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 07:04:24 INFO - PROCESS | 1835 | RSS: Main: 378232832 07:04:24 INFO - PROCESS | 1835 | 07:07:26 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 07:07:27 INFO - PROCESS | 1835 | RSS: Main: 366993408 07:07:27 INFO - PROCESS | 1835 | 07:10:29 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 07:10:30 INFO - PROCESS | 1835 | RSS: Main: 371515392 07:10:30 INFO - PROCESS | 1835 | 07:13:33 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 07:13:34 INFO - PROCESS | 1835 | RSS: Main: 336027648 07:13:34 INFO - PROCESS | 1835 | 07:16:37 INFO - PROCESS | 1835 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 07:16:37 INFO - PROCESS | 1835 | RSS: Main: 444575744 07:16:37 INFO - PROCESS | 1835 | 07:16:37 INFO - PROCESS | 1835 | __start_tp_report 07:16:37 INFO - PROCESS | 1835 | _x_x_mozilla_page_load 07:16:37 INFO - PROCESS | 1835 | _x_x_mozilla_page_load_details 07:16:37 INFO - PROCESS | 1835 | |i|pagename|runs| 07:16:37 INFO - PROCESS | 1835 | |0;dojo.html;3159.5260710893363;3353.6814002669753;3395.3548825723096;3410.7782994105382;3419.4699821527656;5611.214429979796;5636.830895073149;5685.886282274352;5741.282339707542;5789;5675.375708672053;5693.516051135658;5793.507551858087;5809.01246788046;5840;5109.386873575176;5120.92318615221;5192.454792246816;5195.818146364878;5288.101022826128;5257;5284.39229488608;5290.841274761763;5315.0698627740185;5331.360236771593;5123;5169.612279079084;5170.991656626921;5222.190560463116;5224.686518808846;5217.34783152106;5220.190870415092;5223.08595995703;5226.111561034595;5232.555232805216;5121.7439128043825;5181.81863634771;5191.299174611455;5210.400803907562;5220.895582088375;5646.915296270559;5652;5653.9151912721345;5755.8561035974435;5778.191053252548;6207.224096987877;6243.032329988859;6278.560500764948;6280.717367718464;6302.338254483305;6143.87712245757;6228.47058000066;6290.4024117709205;6328.11406403104;6334.049892516114;5865.73604187804;5870.882582348329;5897.557683173779;5906.497947674453;5907.556933230025;6316.589421687576;6326.588771729823;6329.1139240505445;6330.746770129235;6341.334159913143;5999.940000600025;6037.456628903332;6128.747575574743;6185.969070154711;6198.411150940653;5337;6262.3111457739615;6310.242770867525;6313.3686631336495;6314.368563143649;6240.687965601702;6256.937430625727;6259;6264.9373506265265;6273.686315684289;6059.878802423927;6132.967444181115;6141.039029825737;6228.314885362606;6284.780032698925;4945.8021679132435;4982.701037937735;4985.476524964899;4989.201727723546;5025.924611130836;4429.114177164515;4439.889002774957;4470.240059189946;4483.125790470892;4485.842995495207;5647.350554686182;5689.829305120853;5707;5733.197352370671;5766.163906233573;6310.742259239762;6313.273973493107;6317.6209427434505;6330.841728956813;6347.428731414195;5781.53747700183;5787.739551720183;5789.710514474259;5806.390329015477;5848.912266316009;5322.361316642027;5326.467353264643;5381.865453363697;5387.0841956867425;5444.428335024844;5364.2490051393615;6286.968565157236;6291.811245662638;6351.936480635134;6355.841103972252;5123.436421993503;6337.904931426125;6342.365763423621;6343.524235682435;6361.268454127835;6250.4687101595055;6260.968695156495;6295.527835412454;6316.6841657916;6322.936770632235;6196.628202307876;6198.690065496798;6210.130581718473;6212.502999759918;6234.1583886174785;6165.167702360124;6251.53113516479;6281.560290779602;6284.905726414199;6297.1813664223355;5927.555433342431;5994.580379373402;6023.608465449732;6077.878442431215;6117.357677443804;5905.320888097924;6068.969655151696;6102;6112.144299798121;6113.327533971348;5947.226860508106;5964.493018093511;5968.22413086296;5982.923073846575;6018.187544681412;5333.626646134889;5919.4968427684125;5981.401859814157;6023.1868697726995;6032.215811944595;5948.553858460719;5980.222571065734;5997.430244126801;6000.5799594028;6013.218281623361 07:16:37 INFO - PROCESS | 1835 | |1;ext.html;462.937503437036;483.42231033914464;506.56941599640334;516.1251678405105;548.4953842464931;28557.857210713944;30824.53763193554;30832.691673083318;30908.69091309089;30922.69077309226;40377;41545.584544154546;41572.79213603928;44266;44417;9663.5168241588;9793.21654267657;9800.90199098007;9805.90194098057;9818.50907454628;8680.262177714902;8913.866292005625;9718.902810971871;9810.46042467664;9817.165540929029;11735.647930562096;11978.70053248667;11980.880191198064;12240.387980600934;12255.69360765979;12048.698782530419;12114.273143611323;12132.939335303266;12136.878631213664;12144.757104857941;8554.572271386469;11447.713807154805;11782.116341274354;11908.285502869769;11981.820272695917;40329.39505907414;40471;40516.39225411621;40587.391189132184;40637;3432.673895979878;3547.379208638478;3661.560612726463;3745.550533935945;4247.787610619456;30928.381432371225;32087.518687219712;32300.838495807606;32360.352792944246;32705.50941735876;3253.209817155721;3288.57248557686;3304.157439852846;3309.5697559317377;3371.9494207586904;493955;496376;497813;498524;499053;57747.71126144342;57891.71054144702;57933;57964;58043;60744.39255607476;60847.783044339754;60914;61082.38917610856;61092;15559.377624895104;16014.119223442822;16020.839791601935;16061.357545698042;16095.03429794216;12489.50041998328;12554.560590379293;12556.246625202517;12557.497700091893;12563;13496.865031349756;13789.862101379058;15041.323140458706;15367;15446.382144714311;5765.827025189251;6699.732010719614;6786.8303292417095;6865.244823069458;6896.655167241618;899.4333569851083;924.1497822003772;1000.7698229407277;1030.5465595138264;1080.438172150493;26508.072217472294;29850.85074574613;29917.70082299149;30007.549886751716;31929.680703193135;1077.4774234496263;1078.9676309710721;1081.967540973772;1096.572336788653;1115.3363748569589;15623.843761562466;15692.843071569367;15693.843061569467;15709.371625135094;16065.518034458984;659340;662503;662623;666889;667551;58047.83904321974;58284.4171558279;58352.708236458544;58425.70787146122;58488.41511584915;1376.160542069337;1392.6518370407398;1424.2593851197319;1428.457186269211;1433.1902475101533;26599.468010640066;28511;29913.252168695955;30045.3990920176;30631.846840765655;49517;49633.99999999855;49733.75133124311;50053.24920126128;50064.49935500598;574697;594274;594643;595129;595719;31986;33956.49065263974;34199.487007694406;40360.59639403568;40799.59200408077;44458;47541.76229118832;47551.28673069976;48435.27347089867;48530.5146948526;37646.62353376431;38903.80548097241;39263.803680981415;40255.798721006206;40272.395914060726;38794.61205388023;38873.92949414091;39406.81779546618;39991;40355.78932632026;38215.42676859905;38486.037849053995;38563.03592410212;38629.806850965564;38726.80636596799;33011;33527;34157.48763768496;34957.30085398329;35477.29045419129;23260.604363738228;24223.788810559756;25261.74738252594;25507.23478295654;26253.21240362792 07:16:37 INFO - PROCESS | 1835 | |2;jquery.html;380.7468033757551;384.2813937936059;385.4237914318093;396.87498437992014;407.14499550943026;19628.509287267825;22079.779202207974;22242.88878555609;22268.55045944949;22671.546569068607;20982.16071357144;21379;21390.88633348958;21401.46496337592;21451.356459306247;5709.600327977043;5950.79172228972;6068.605540639866;6082.665453400061;6113.358097399776;4207.810648520824;4430.689851710382;5914.822555323347;6422.807315780511;6726.394624483795;6621.106150669646;6730.764423245188;6736.090627765262;6737.696803643823;6738.93261067388;6570.44151247147;6698.83252918676;6724.966375168093;6746.19045714515;6752.257251702334;5063.848084557432;5357.464253574651;5360.490753378423;5818.389069147721;5845.85385365365;13354.532591359255;13454.865451345458;13596.38816253271;13615.727685446236;13688.589342319747;2105.2736805801974;2293.495431005193;2315.386422598013;2395.820313476479;2407.6629271901948;10281.794364112677;10308.433036183007;10333.844992325121;10387.169026477864;10437.530311136017;1833.1842330163083;1835.862310326731;1850.2968871828753;1853.5366158460386;1865.598896237302;112664.18339541495;120996;128887;130025;131031.34484327612;11635.88364116348;11724.88275117255;11828.876256755595;11857.11071669611;11861;14742.189179595225;14856.548669469417;14881.404743810343;14885.776713349309;14906.4782732606;6980.860382792316;8564.357673174412;8623.78440538979;8741.256993155526;8776.341774366949;8538.87191692125;8546.871796923051;8549.145085491402;8632.697855575025;8688.826223475495;4512.473015111548;4603.424571928509;4668.906621867543;4687;4913.533214344631;1676.5892356372767;1741.4601473543241;1784.236982278587;1897.795004392124;1936.3029309448586;828.2545708862073;839.4963022186761;853.9231469167805;915.7362839281749;944.5607792376578;20581.89709051475;21699.528075507842;22386.888065559568;22615;22617.773822261566;884.0540621534896;908.5230254116642;913.9040400757958;920.5903372999085;942.7124726958207;11536.538538458535;11724.00345970585;11953.820692689616;11987.40062996864;11994.700132496757;594311;602139.8823064686;608502;615950;623241;148884;153971;154493;155529.11296209384;156798;917.5137177296044;946.3470205558143;963.5808423335872;964.4454438697777;987.4075554667143;17388.82611173872;17956.820431795513;19772.011399429684;20555.794442055387;20685.379438616867;51537;58702;59741.402585975324;60592.39407606044;60861.69569152126;85897;86046;86287;86576.56711716653;86806.1319386798;7683.9231607683205;8004.679227927327;8231.259186673227;8352.791180220544;8380.287675547646;7701.4608977371045;11221.943890280496;11324.660260192208;12657.556985505464;13798.17210967345;11151.721206969567;11628.418579070843;11663;11773.882261177621;11884.10869184832;9675.951620241854;11509;11602.245854019462;11683.299002059903;11736.765264694488;5796.855078623068;10605.151587873106;11362.772744545227;12006.699832504257;12051.457684404048;8596.312295016258;8629.180227878342;8673.91326086731;8700.216980471534;8702.390832641655;8575.95712021436;9392.436453812794;11169.497372618089;11820.349880756641;12001.39993000364 07:16:37 INFO - PROCESS | 1835 | |3;mootools.html;1476.2913801375344;1541.3603354607837;1709.7264437689971;1847.8429333506667;1874.0348720408992;8475.660973561058;8800.383973121883;8925.509096999662;8931.196192342688;8936.195742383185;7787.377009839213;8008.55952922589;8371.121032291612;8567.314614830813;8705.7823554411;2356.634721618152;2383.07060246504;2383.4398916254668;2383.6901202843605;2385.9045638174453;2354.9646755298686;2367.4554852383962;2386.844855084414;2396.940076498093;2400.2679182849224;2076.9169233230655;2153.5154590217153;2214.357836227492;2389.3190440724397;2396.0535588442553;2354.3525530479083;2368.4670949036417;2385.6183010718287;2389.629607410854;2394.2697477269426;2359.362971997551;2372.1934542255717;2380.0122948976327;2380.2502211803226;2383.2016274548046;8840.602172902234;8903.777405564913;8931.955340223321;8934.463932164092;8967.551622418852;5243.842684719464;5358.035553600351;5397.757100930428;5414.593905457067;5422.5119739223655;3573.4997100405703;3674.889753307405;3725.403935370328;3751.9437208441896;3756.586775454698;1092.7541303206795;1093.3549205968516;1102.614085070219;1118.7650593375402;1120.9831852522218;11564.595239166589;11683.7079073023;11701.590444334493;11709.590164344207;11815.940920295343;4398.560143985575;4400.185965596375;4561.589456948825;4571.085782843445;4623.791929363119;9129.81740365189;9137.81724365509;9156.359054866096;9159.038300978435;9193.724188274362;5255.159174532057;5257.5005374489365;5260.395054568697;5261.736913154327;5270.472952704698;4821.686590371684;4912.754362281871;4918.901621967612;4929.359183306147;4948.233023881232;3649.5255616770182;3690.040589446763;3697.5932647408763;3749.1189570450924;3752.681022113151;1076.8707755069443;1120.467777805552;1123.4102096399456;1132.8583927009124;1142.6686260984222;760.5702777930452;895.1853813030111;914.2457472585011;923.5197697197419;940.0646356874953;5897.498712609477;5939.406059394112;6011.5190784737115;6014.338422773491;6147.784827531015;445.3809205204768;468.62041746185696;489.9951000490021;494.0933387234465;581.1921429213397;3638.6543278388513;3826.0052386379743;4006.0185254612807;4017.5178978522763;4081.0613558881623;10398.636047738293;10712.464376781285;10829.971152740478;10832.78334433307;10837.133029357632;9763.658271960447;10234.33476824004;10673.146148308117;10755.085817705427;10816.837747433794;8089.878651820228;8203.671853125927;8244.093149753522;8254.793630159174;8259.628316725762;5882.441168089024;5952.761889524544;6088.665123418255;6110.7861224856915;6130.233720784902;9708.320417570701;10076.395416275047;10092.293539452461;10110.03954624339;10120.443375614412;9512.191463725661;10017.899821001696;10034.247431442525;10136.746581335525;10140.340877843211;7256.0567126273245;7469.887951680837;7587.279208475186;7612.276833700789;7666;8440.282575981111;8483.533405662502;8541.572921354033;8564.05795362522;8582.82834343322;7442.468254047275;7622.58075805836;7660.673249953793;7775.22247775218;7798.454108212371;7389.334959853639;7419.554826710415;7482.139553951365;7577.5453472791805;7593.202713714981;7063.364297213276;7108.609026503385;7119.430445564239;7131.821704457223;7134.108236470442;4053.290674131956;4237.152569486177;5021.347224860745;5061.342025536804;5098.541131298052;4520.977395113004;4892.412910450626;5028.446870844277;5090.898182036264;5193.61047921415 07:16:37 INFO - PROCESS | 1835 | |4;prototype.html;295.79146701575394;311.07610397120544;313.72549019607834;316.55365934033006;321.13934655124285;5332.067362950376;5369.463053694633;5392.865178370543;5396.865078373043;5407.945920540794;4802.895334073165;5137.897242055156;5380.946190538093;5393.197795554135;5415.512603865652;3348.9162770930798;3856.3636999894984;3970.523537175543;3980.820863061155;4111.342185250361;3075.600171977651;3376.409128402532;3701.833417496219;4037.636612704856;4071.4503542021894;3378.9493157602533;3712.424574191005;4030.576789437111;4045.4134150548152;4060.167665628539;3790.58303586608;3867.5552311484257;4000.239954408653;4084.1831633673382;4093.3655283431117;3069.5856059431912;3528.941317604734;3529.082438565991;3544.680978711928;3546.5744110706623;5162.883217880689;5206.505381988705;5231.398389185254;5233.659812112239;5260.92108618371;4453.888652783674;4650.790714417825;4669.463011753658;4692.436907571079;4715.386999690053;4866.513348665105;5209;5290.867728306785;5588.3573389060375;5619.471237942901;3951.9449886059374;3953.782541960191;3959.723159183481;3967.6429121378924;3971.1660551284267;5679.318481782171;5816.592838501307;5843.970780146157;5913.526917846562;5928.525717942554;3959.742616729903;3962.881113566598;3968;3984.442178095069;3989.880303590897;3347.3138006709055;4027.859024934113;4028.5165780106577;4047.4738284022887;4065.4918135233097;3928.4500169976227;4410.154207405541;4613.423322084739;4683.424116735781;4703.7883295251795;4963.429205641398;5177.954707890362;5690.971545142248;5711.571632127607;5731.140328950691;1284.2808027504627;1363.8227030485973;1373.2790285100402;1380.6055883557678;1382.0187666756715;697.6546609428366;795.9602019898982;817.7424111404888;857.3012994409536;868.4094815525503;4404.9207944053915;4994.775235114356;5100.276797937527;5173.922391164136;5214.243934629458;4758.381410416693;5679.602427830095;5758.856028599319;5796.275465566804;5797.594168408171;4954.3311652926395;5235.816746413933;5237.36003477587;5243.318368612056;5256.763445644955;2792.5392310268985;2849.860055977584;2974.0929016649698;3012.3372857971212;3026.7753201396954;4718.811247550128;4764.523547645277;5877.23595932526;5979.222701048836;6003.249593800775;4817.879553011203;5708.827052336676;5922.822315330547;5925.911111333334;5933.753911678509;5810.854728631733;5855.209546711139;5856.853578660568;5910.438508341701;5940.7722104210925;5236.2407450918645;5532.612717109763;5660.773569057192;5708.315002199763;5711.400302968294;5656.8020119295625;5656.858578535405;5676.631018983753;5702.230198923092;5711.8000869969355;4706.717596944194;5443.918341224964;5841.269841269841;5922.60818707612;5944.3164036136395;2848.5442329227226;3168.6514483406345;3375.341808347349;3399.6770306820813;3419.1623052352334;4163.687723420695;4430.490493593278;4815.6147508198555;4864.586510146677;4947.703137811743;4266.829326826892;4300.709956882924;4722.5513576210205;4731.905361892812;4770.904581908412;4194.916101678011;4696.262382918206;4714;4734.905301894012;4794.74646915949;3864.632859878307;4175.434212170436;4232.555581663881;4284.978575107105;4342.674299427619;5303.893922121613;5526.392096869422;5561.694106824001;5568.387477377566;5573.665580065209;4873.415190177201;5177.223416487557;5299.6290259681455;5429.864253393696;5591.099026331067 07:16:37 INFO - PROCESS | 1835 | |5;yui.html;651.5862427358627;792.2473650032471;813.8209593889342;827.9130691277419;837.9958100209498;15021.924890375547;16234.83765162348;16235.91882040591;16291.51125466235;16298.83701162988;11524.827127593093;12374.133810633259;12377.381130943439;12378.50485980562;12383.56657516987;834.5034704350917;856.1994535109661;856.3448961544424;858.5535521528801;858.7423772868145;717.4081382859137;824.2128767027505;838.2078935406043;846.5132548784443;847.8431490174306;896.7982204004112;897.2149369301861;898.2589363774879;899.9370044096916;901.0538934119181;891.31814162166;891.8974317953389;893.9240164586018;894.4722613657908;895.8029233568637;667.3093348384399;667.6528205333248;668.2882729892698;669.0900375489331;670.3263220463451;11002.614908478165;12927.935360323138;12958.611241662766;12977.675558111028;13338.533151339754;12027.278363298143;12030.63908082759;12175.878241217562;12295.631131066082;12322.383880805924;9653.696750938603;10810.567577296899;10812;11091.500882460308;11506.712332191679;3742.251549690073;3775.6979441644603;3884.2231553689094;3979.462772525701;4018.9196216075807;68740.65629671869;101870.49064754703;103533;103580.48209758979;115417.99999999916;11077.723056923642;11218.439078046065;11315;11396.202265841477;11797.115216358807;18300.81699182991;22169.334919952427;22328.88835555812;22353.776462235168;22528.887355563118;8177.836443271101;8191.221833925768;8237.588120593946;8319.168083191753;8376.162383761697;4386.342048692658;4524.705894116872;4569.977150114295;4618.03021365517;4720.834770783006;3384.7969121852766;3477.6000759912936;3479.913002174966;3480.3735327641266;3481.5299934509026;589.2693060604819;673.6598018000878;684.4934748286232;691.4261163234503;692.2627401817149;563.4421922296897;606.1816547660711;626.8276224038335;640.4396153365805;725.6045455226914;8839.734807955772;8875;9352.438853668802;9462.101100395452;9580.233581313478;618.6226401894841;626.6929204689669;668.3884245914934;678.1861765880963;688.469878193788;4482.5069242383315;4629.051044535862;4637.443506779208;4638.443386793607;4649.69776964503;89710;89905;90112;141014.58985410014;161516.19241903716;85873.57063214644;86751;102548.97451025543;134437;142683;117017;117653;119575.40212298883;119644.8035519651;120896;9141.725748227564;9306.767330816783;9610.279229057709;9634.277429192698;9694.982026887075;54364;60344;60788.08817867824;64315.392115197494;67666;100150.49924750329;100427;101264.4810327831;103710.4814475953;103777.48111259396;13325.466981320638;13407.994400419813;13466.711081722326;13559.79660305116;14080.203818613343;15156.696866062837;15322.157281349633;15398.965488001375;15551.84448155504;15575.221238938235;14128.222947737973;14573.635659108182;14606.926965365104;14745.483908063596;14825.258737062888;13738.931305343409;14240.074395164282;14241;14251.28743562797;14302.642433939234;13528.79706804379;13883.375248114062;13889.513867014606;14294.070885392417;14627.195504246944;10487.81338608956;11308.038816700673;11426.714332141762;11533.384145581995;12120.151589388659;11117.388543630179;11237.157213209206;11460.312381257152;11586.246893951866;11629.941850290694 07:16:37 INFO - PROCESS | 1835 | __end_tp_report 07:16:37 INFO - PROCESS | 1835 | __start_cc_report 07:16:37 INFO - PROCESS | 1835 | _x_x_mozilla_cycle_collect,1311 07:16:37 INFO - PROCESS | 1835 | __end_cc_report 07:16:37 INFO - PROCESS | 1835 | __startTimestamp1470320197528__endTimestamp 07:16:37 INFO - PROCESS | 1835 | 07:16:37 INFO - PROCESS | 1835 | ------- Summary: start ------- 07:16:37 INFO - PROCESS | 1835 | Number of tests: 6 07:16:37 INFO - PROCESS | 1835 | 07:16:37 INFO - PROCESS | 1835 | [#0] dojo.html Cycles:165 Average:5742.86 Median:5947.23 stddev:641.86 (10.8%) stddev-sans-first:611.00 07:16:37 INFO - PROCESS | 1835 | Values: 3159.5 3353.7 3395.4 3410.8 3419.5 5611.2 5636.8 5685.9 5741.3 5789.0 5675.4 5693.5 5793.5 5809.0 5840.0 5109.4 5120.9 5192.5 5195.8 5288.1 5257.0 5284.4 5290.8 5315.1 5331.4 5123.0 5169.6 5171.0 5222.2 5224.7 5217.3 5220.2 5223.1 5226.1 5232.6 5121.7 5181.8 5191.3 5210.4 5220.9 5646.9 5652.0 5653.9 5755.9 5778.2 6207.2 6243.0 6278.6 6280.7 6302.3 6143.9 6228.5 6290.4 6328.1 6334.0 5865.7 5870.9 5897.6 5906.5 5907.6 6316.6 6326.6 6329.1 6330.7 6341.3 5999.9 6037.5 6128.7 6186.0 6198.4 5337.0 6262.3 6310.2 6313.4 6314.4 6240.7 6256.9 6259.0 6264.9 6273.7 6059.9 6133.0 6141.0 6228.3 6284.8 4945.8 4982.7 4985.5 4989.2 5025.9 4429.1 4439.9 4470.2 4483.1 4485.8 5647.4 5689.8 5707.0 5733.2 5766.2 6310.7 6313.3 6317.6 6330.8 6347.4 5781.5 5787.7 5789.7 5806.4 5848.9 5322.4 5326.5 5381.9 5387.1 5444.4 5364.2 6287.0 6291.8 6351.9 6355.8 5123.4 6337.9 6342.4 6343.5 6361.3 6250.5 6261.0 6295.5 6316.7 6322.9 6196.6 6198.7 6210.1 6212.5 6234.2 6165.2 6251.5 6281.6 6284.9 6297.2 5927.6 5994.6 6023.6 6077.9 6117.4 5905.3 6069.0 6102.0 6112.1 6113.3 5947.2 5964.5 5968.2 5982.9 6018.2 5333.6 5919.5 5981.4 6023.2 6032.2 5948.6 5980.2 5997.4 6000.6 6013.2 07:16:37 INFO - PROCESS | 1835 | 07:16:37 INFO - PROCESS | 1835 | [#1] ext.html Cycles:180 Average:71498.08 Median:38350.73 stddev:157186.94 (409.9%) stddev-sans-first:157537.41 07:16:37 INFO - PROCESS | 1835 | Values: 462.9 483.4 506.6 516.1 548.5 28557.9 30824.5 30832.7 30908.7 30922.7 40377.0 41545.6 41572.8 44266.0 44417.0 9663.5 9793.2 9800.9 9805.9 9818.5 8680.3 8913.9 9718.9 9810.5 9817.2 11735.6 11978.7 11980.9 12240.4 12255.7 12048.7 12114.3 12132.9 12136.9 12144.8 8554.6 11447.7 11782.1 11908.3 11981.8 40329.4 40471.0 40516.4 40587.4 40637.0 3432.7 3547.4 3661.6 3745.6 4247.8 30928.4 32087.5 32300.8 32360.4 32705.5 3253.2 3288.6 3304.2 3309.6 3371.9 493955.0 496376.0 497813.0 498524.0 499053.0 57747.7 57891.7 57933.0 57964.0 58043.0 60744.4 60847.8 60914.0 61082.4 61092.0 15559.4 16014.1 16020.8 16061.4 16095.0 12489.5 12554.6 12556.2 12557.5 12563.0 13496.9 13789.9 15041.3 15367.0 15446.4 5765.8 6699.7 6786.8 6865.2 6896.7 899.4 924.1 1000.8 1030.5 1080.4 26508.1 29850.9 29917.7 30007.5 31929.7 1077.5 1079.0 1082.0 1096.6 1115.3 15623.8 15692.8 15693.8 15709.4 16065.5 659340.0 662503.0 662623.0 666889.0 667551.0 58047.8 58284.4 58352.7 58425.7 58488.4 1376.2 1392.7 1424.3 1428.5 1433.2 26599.5 28511.0 29913.3 30045.4 30631.8 49517.0 49634.0 49733.8 50053.2 50064.5 574697.0 594274.0 594643.0 595129.0 595719.0 31986.0 33956.5 34199.5 40360.6 40799.6 44458.0 47541.8 47551.3 48435.3 48530.5 37646.6 38903.8 39263.8 40255.8 40272.4 38794.6 38873.9 39406.8 39991.0 40355.8 38215.4 38486.0 38563.0 38629.8 38726.8 33011.0 33527.0 34157.5 34957.3 35477.3 23260.6 24223.8 25261.7 25507.2 26253.2 07:16:37 INFO - PROCESS | 1835 | 07:16:37 INFO - PROCESS | 1835 | [#2] jquery.html Cycles:180 Average:36441.38 Median:382.51 stddev:102598.25 (26822.1%) stddev-sans-first:102850.34 07:16:37 INFO - PROCESS | 1835 | Values: 380.7 384.3 385.4 396.9 407.1 19628.5 22079.8 22242.9 22268.6 22671.5 20982.2 21379.0 21390.9 21401.5 21451.4 5709.6 5950.8 6068.6 6082.7 6113.4 4207.8 4430.7 5914.8 6422.8 6726.4 6621.1 6730.8 6736.1 6737.7 6738.9 6570.4 6698.8 6725.0 6746.2 6752.3 5063.8 5357.5 5360.5 5818.4 5845.9 13354.5 13454.9 13596.4 13615.7 13688.6 2105.3 2293.5 2315.4 2395.8 2407.7 10281.8 10308.4 10333.8 10387.2 10437.5 1833.2 1835.9 1850.3 1853.5 1865.6 112664.2 120996.0 128887.0 130025.0 131031.3 11635.9 11724.9 11828.9 11857.1 11861.0 14742.2 14856.5 14881.4 14885.8 14906.5 6980.9 8564.4 8623.8 8741.3 8776.3 8538.9 8546.9 8549.1 8632.7 8688.8 4512.5 4603.4 4668.9 4687.0 4913.5 1676.6 1741.5 1784.2 1897.8 1936.3 828.3 839.5 853.9 915.7 944.6 20581.9 21699.5 22386.9 22615.0 22617.8 884.1 908.5 913.9 920.6 942.7 11536.5 11724.0 11953.8 11987.4 11994.7 594311.0 602139.9 608502.0 615950.0 623241.0 148884.0 153971.0 154493.0 155529.1 156798.0 917.5 946.3 963.6 964.4 987.4 17388.8 17956.8 19772.0 20555.8 20685.4 51537.0 58702.0 59741.4 60592.4 60861.7 85897.0 86046.0 86287.0 86576.6 86806.1 7683.9 8004.7 8231.3 8352.8 8380.3 7701.5 11221.9 11324.7 12657.6 13798.2 11151.7 11628.4 11663.0 11773.9 11884.1 9676.0 11509.0 11602.2 11683.3 11736.8 5796.9 10605.2 11362.8 12006.7 12051.5 8596.3 8629.2 8673.9 8700.2 8702.4 8576.0 9392.4 11169.5 11820.3 12001.4 07:16:37 INFO - PROCESS | 1835 | 07:16:37 INFO - PROCESS | 1835 | [#3] mootools.html Cycles:180 Average:5684.40 Median:4915.83 stddev:3199.73 (65.1%) stddev-sans-first:3193.08 07:16:37 INFO - PROCESS | 1835 | Values: 1476.3 1541.4 1709.7 1847.8 1874.0 8475.7 8800.4 8925.5 8931.2 8936.2 7787.4 8008.6 8371.1 8567.3 8705.8 2356.6 2383.1 2383.4 2383.7 2385.9 2355.0 2367.5 2386.8 2396.9 2400.3 2076.9 2153.5 2214.4 2389.3 2396.1 2354.4 2368.5 2385.6 2389.6 2394.3 2359.4 2372.2 2380.0 2380.3 2383.2 8840.6 8903.8 8932.0 8934.5 8967.6 5243.8 5358.0 5397.8 5414.6 5422.5 3573.5 3674.9 3725.4 3751.9 3756.6 1092.8 1093.4 1102.6 1118.8 1121.0 11564.6 11683.7 11701.6 11709.6 11815.9 4398.6 4400.2 4561.6 4571.1 4623.8 9129.8 9137.8 9156.4 9159.0 9193.7 5255.2 5257.5 5260.4 5261.7 5270.5 4821.7 4912.8 4918.9 4929.4 4948.2 3649.5 3690.0 3697.6 3749.1 3752.7 1076.9 1120.5 1123.4 1132.9 1142.7 760.6 895.2 914.2 923.5 940.1 5897.5 5939.4 6011.5 6014.3 6147.8 445.4 468.6 490.0 494.1 581.2 3638.7 3826.0 4006.0 4017.5 4081.1 10398.6 10712.5 10830.0 10832.8 10837.1 9763.7 10234.3 10673.1 10755.1 10816.8 8089.9 8203.7 8244.1 8254.8 8259.6 5882.4 5952.8 6088.7 6110.8 6130.2 9708.3 10076.4 10092.3 10110.0 10120.4 9512.2 10017.9 10034.2 10136.7 10140.3 7256.1 7469.9 7587.3 7612.3 7666.0 8440.3 8483.5 8541.6 8564.1 8582.8 7442.5 7622.6 7660.7 7775.2 7798.5 7389.3 7419.6 7482.1 7577.5 7593.2 7063.4 7108.6 7119.4 7131.8 7134.1 4053.3 4237.2 5021.3 5061.3 5098.5 4521.0 4892.4 5028.4 5090.9 5193.6 07:16:37 INFO - PROCESS | 1835 | 07:16:37 INFO - PROCESS | 1835 | [#4] prototype.html Cycles:180 Average:4420.13 Median:4782.83 stddev:1373.67 (28.7%) stddev-sans-first:1342.19 07:16:37 INFO - PROCESS | 1835 | Values: 295.8 311.1 313.7 316.6 321.1 5332.1 5369.5 5392.9 5396.9 5407.9 4802.9 5137.9 5380.9 5393.2 5415.5 3348.9 3856.4 3970.5 3980.8 4111.3 3075.6 3376.4 3701.8 4037.6 4071.5 3378.9 3712.4 4030.6 4045.4 4060.2 3790.6 3867.6 4000.2 4084.2 4093.4 3069.6 3528.9 3529.1 3544.7 3546.6 5162.9 5206.5 5231.4 5233.7 5260.9 4453.9 4650.8 4669.5 4692.4 4715.4 4866.5 5209.0 5290.9 5588.4 5619.5 3951.9 3953.8 3959.7 3967.6 3971.2 5679.3 5816.6 5844.0 5913.5 5928.5 3959.7 3962.9 3968.0 3984.4 3989.9 3347.3 4027.9 4028.5 4047.5 4065.5 3928.5 4410.2 4613.4 4683.4 4703.8 4963.4 5178.0 5691.0 5711.6 5731.1 1284.3 1363.8 1373.3 1380.6 1382.0 697.7 796.0 817.7 857.3 868.4 4404.9 4994.8 5100.3 5173.9 5214.2 4758.4 5679.6 5758.9 5796.3 5797.6 4954.3 5235.8 5237.4 5243.3 5256.8 2792.5 2849.9 2974.1 3012.3 3026.8 4718.8 4764.5 5877.2 5979.2 6003.2 4817.9 5708.8 5922.8 5925.9 5933.8 5810.9 5855.2 5856.9 5910.4 5940.8 5236.2 5532.6 5660.8 5708.3 5711.4 5656.8 5656.9 5676.6 5702.2 5711.8 4706.7 5443.9 5841.3 5922.6 5944.3 2848.5 3168.7 3375.3 3399.7 3419.2 4163.7 4430.5 4815.6 4864.6 4947.7 4266.8 4300.7 4722.6 4731.9 4770.9 4194.9 4696.3 4714.0 4734.9 4794.7 3864.6 4175.4 4232.6 4285.0 4342.7 5303.9 5526.4 5561.7 5568.4 5573.7 4873.4 5177.2 5299.6 5429.9 5591.1 07:16:37 INFO - PROCESS | 1835 | 07:16:37 INFO - PROCESS | 1835 | [#5] yui.html Cycles:180 Average:23539.76 Median:3480.14 stddev:36696.59 (1054.5%) stddev-sans-first:36759.29 07:16:37 INFO - PROCESS | 1835 | Values: 651.6 792.2 813.8 827.9 838.0 15021.9 16234.8 16235.9 16291.5 16298.8 11524.8 12374.1 12377.4 12378.5 12383.6 834.5 856.2 856.3 858.6 858.7 717.4 824.2 838.2 846.5 847.8 896.8 897.2 898.3 899.9 901.1 891.3 891.9 893.9 894.5 895.8 667.3 667.7 668.3 669.1 670.3 11002.6 12927.9 12958.6 12977.7 13338.5 12027.3 12030.6 12175.9 12295.6 12322.4 9653.7 10810.6 10812.0 11091.5 11506.7 3742.3 3775.7 3884.2 3979.5 4018.9 68740.7 101870.5 103533.0 103580.5 115418.0 11077.7 11218.4 11315.0 11396.2 11797.1 18300.8 22169.3 22328.9 22353.8 22528.9 8177.8 8191.2 8237.6 8319.2 8376.2 4386.3 4524.7 4570.0 4618.0 4720.8 3384.8 3477.6 3479.9 3480.4 3481.5 589.3 673.7 684.5 691.4 692.3 563.4 606.2 626.8 640.4 725.6 8839.7 8875.0 9352.4 9462.1 9580.2 618.6 626.7 668.4 678.2 688.5 4482.5 4629.1 4637.4 4638.4 4649.7 89710.0 89905.0 90112.0 141014.6 161516.2 85873.6 86751.0 102549.0 134437.0 142683.0 117017.0 117653.0 119575.4 119644.8 120896.0 9141.7 9306.8 9610.3 9634.3 9695.0 54364.0 60344.0 60788.1 64315.4 67666.0 100150.5 100427.0 101264.5 103710.5 103777.5 13325.5 13408.0 13466.7 13559.8 14080.2 15156.7 15322.2 15399.0 15551.8 15575.2 14128.2 14573.6 14606.9 14745.5 14825.3 13738.9 14240.1 14241.0 14251.3 14302.6 13528.8 13883.4 13889.5 14294.1 14627.2 10487.8 11308.0 11426.7 11533.4 12120.2 11117.4 11237.2 11460.3 11586.2 11629.9 07:16:37 INFO - PROCESS | 1835 | -------- Summary: end -------- 07:16:37 INFO - PROCESS | 1835 | 07:16:38 INFO - TEST-INFO | 1835: exit 0 07:16:38 INFO - TEST-OK | dromaeo_css | took 1116033ms 07:16:38 INFO - TEST-START | kraken 07:16:38 INFO - operating with platform_type : mac_ 07:16:38 INFO - Initialising browser for kraken test... 07:16:38 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpJhJaV8/profile http://localhost:49271/getInfo.html) 07:16:40 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 07:16:40 INFO - PROCESS | 1952 | colorDepth:24 07:16:40 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 07:16:40 INFO - PROCESS | 1952 | __metrics 07:16:40 INFO - PROCESS | 1952 | 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" 07:16:40 INFO - PROCESS | 1952 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:16:41 INFO - TEST-INFO | 1952: exit 0 07:16:41 INFO - Browser initialized. 07:16:41 INFO - Running cycle 1/1 for kraken test... 07:16:41 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpJhJaV8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:16:53 INFO - PROCESS | 1953 | RSS: Main: 245964800 07:16:53 INFO - PROCESS | 1953 | 07:16:56 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 07:16:56 INFO - PROCESS | 1953 | RSS: Main: 295649280 07:16:56 INFO - PROCESS | 1953 | 07:16:59 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 07:17:00 INFO - PROCESS | 1953 | RSS: Main: 305397760 07:17:00 INFO - PROCESS | 1953 | 07:17:03 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 07:17:04 INFO - PROCESS | 1953 | RSS: Main: 390537216 07:17:04 INFO - PROCESS | 1953 | 07:17:06 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 07:17:07 INFO - PROCESS | 1953 | RSS: Main: 293048320 07:17:07 INFO - PROCESS | 1953 | 07:17:09 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:17:09 INFO - PROCESS | 1953 | RSS: Main: 296747008 07:17:09 INFO - PROCESS | 1953 | 07:17:12 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 07:17:13 INFO - PROCESS | 1953 | RSS: Main: 333484032 07:17:13 INFO - PROCESS | 1953 | 07:17:15 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 07:17:16 INFO - PROCESS | 1953 | RSS: Main: 334737408 07:17:16 INFO - PROCESS | 1953 | 07:17:19 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 07:17:19 INFO - PROCESS | 1953 | RSS: Main: 336265216 07:17:19 INFO - PROCESS | 1953 | 07:17:21 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:17:22 INFO - PROCESS | 1953 | RSS: Main: 315920384 07:17:22 INFO - PROCESS | 1953 | 07:17:24 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:17:24 INFO - PROCESS | 1953 | RSS: Main: 323182592 07:17:24 INFO - PROCESS | 1953 | 07:17:26 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:17:27 INFO - PROCESS | 1953 | RSS: Main: 322056192 07:17:27 INFO - PROCESS | 1953 | 07:17:30 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:17:31 INFO - PROCESS | 1953 | RSS: Main: 329306112 07:17:31 INFO - PROCESS | 1953 | 07:17:34 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:17:34 INFO - PROCESS | 1953 | RSS: Main: 330895360 07:17:34 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 07:17:36 INFO - PROCESS | 1953 | RSS: Main: 373669888 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | __start_tp_report 07:17:36 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 07:17:36 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 07:17:36 INFO - PROCESS | 1953 | |i|pagename|runs| 07:17:36 INFO - PROCESS | 1953 | |0;ai-astar;102;111;111;111;113;111;112;112;112;112 07:17:36 INFO - PROCESS | 1953 | |1;audio-beat-detection;142;108;106;160;107;107;126;116;141;106 07:17:36 INFO - PROCESS | 1953 | |2;audio-dft;182;185;184;182;185;179;185;184;181;184 07:17:36 INFO - PROCESS | 1953 | |3;audio-fft;77;73;58;123;82;80;76;66;85;57 07:17:36 INFO - PROCESS | 1953 | |4;audio-oscillator;85;88;93;72;79;69;95;72;67;99 07:17:36 INFO - PROCESS | 1953 | |5;imaging-gaussian-blur;86;84;86;84;87;84;88;84;87;83 07:17:36 INFO - PROCESS | 1953 | |6;imaging-darkroom;92;91;92;93;93;92;93;94;91;92 07:17:36 INFO - PROCESS | 1953 | |7;imaging-desaturate;86;87;87;84;83;86;83;84;86;85 07:17:36 INFO - PROCESS | 1953 | |8;json-parse-financial;55;54;55;54;54;54;55;54;54;55 07:17:36 INFO - PROCESS | 1953 | |9;json-stringify-tinderbox;67;77;66;75;88;101;102;83;71;71 07:17:36 INFO - PROCESS | 1953 | |10;stanford-crypto-aes;84;82;84;85;83;80;83;83;85;79 07:17:36 INFO - PROCESS | 1953 | |11;stanford-crypto-ccm;138;139;137;152;168;142;147;145;184;147 07:17:36 INFO - PROCESS | 1953 | |12;stanford-crypto-pbkdf2;162;164;165;163;161;161;164;163;162;163 07:17:36 INFO - PROCESS | 1953 | |13;stanford-crypto-sha256-iterative;67;68;66;65;67;68;85;87;84;85 07:17:36 INFO - PROCESS | 1953 | __end_tp_report 07:17:36 INFO - PROCESS | 1953 | __start_cc_report 07:17:36 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,2057 07:17:36 INFO - PROCESS | 1953 | __end_cc_report 07:17:36 INFO - PROCESS | 1953 | __startTimestamp1470320256829__endTimestamp 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | ------- Summary: start ------- 07:17:36 INFO - PROCESS | 1953 | Number of tests: 14 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:3.13 (2.8%) stddev-sans-first:0.71 07:17:36 INFO - PROCESS | 1953 | Values: 102.0 111.0 111.0 111.0 113.0 111.0 112.0 112.0 112.0 112.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#1] audio-beat-detection Cycles:10 Average:121.90 Median:121.00 stddev:19.48 (16.1%) stddev-sans-first:19.25 07:17:36 INFO - PROCESS | 1953 | Values: 142.0 108.0 106.0 160.0 107.0 107.0 126.0 116.0 141.0 106.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:2.02 (1.1%) stddev-sans-first:2.11 07:17:36 INFO - PROCESS | 1953 | Values: 182.0 185.0 184.0 182.0 185.0 179.0 185.0 184.0 181.0 184.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#3] audio-fft Cycles:10 Average:77.70 Median:76.50 stddev:18.58 (24.3%) stddev-sans-first:19.71 07:17:36 INFO - PROCESS | 1953 | Values: 77.0 73.0 58.0 123.0 82.0 80.0 76.0 66.0 85.0 57.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:86.50 stddev:11.68 (13.5%) stddev-sans-first:12.33 07:17:36 INFO - PROCESS | 1953 | Values: 85.0 88.0 93.0 72.0 79.0 69.0 95.0 72.0 67.0 99.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:1.70 (2.0%) stddev-sans-first:1.79 07:17:36 INFO - PROCESS | 1953 | Values: 86.0 84.0 86.0 84.0 87.0 84.0 88.0 84.0 87.0 83.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#6] imaging-darkroom Cycles:10 Average:92.30 Median:92.50 stddev:0.95 (1.0%) stddev-sans-first:1.00 07:17:36 INFO - PROCESS | 1953 | Values: 92.0 91.0 92.0 93.0 93.0 92.0 93.0 94.0 91.0 92.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:86.00 stddev:1.52 (1.8%) stddev-sans-first:1.58 07:17:36 INFO - PROCESS | 1953 | Values: 86.0 87.0 87.0 84.0 83.0 86.0 83.0 84.0 86.0 85.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:0.52 (0.9%) stddev-sans-first:0.50 07:17:36 INFO - PROCESS | 1953 | Values: 55.0 54.0 55.0 54.0 54.0 54.0 55.0 54.0 54.0 55.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#9] json-stringify-tinderbox Cycles:10 Average:80.10 Median:73.00 stddev:13.16 (18.0%) stddev-sans-first:13.08 07:17:36 INFO - PROCESS | 1953 | Values: 67.0 77.0 66.0 75.0 88.0 101.0 102.0 83.0 71.0 71.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.50 stddev:1.99 (2.4%) stddev-sans-first:2.06 07:17:36 INFO - PROCESS | 1953 | Values: 84.0 82.0 84.0 85.0 83.0 80.0 83.0 83.0 85.0 79.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:147.00 stddev:15.00 (10.2%) stddev-sans-first:15.28 07:17:36 INFO - PROCESS | 1953 | Values: 138.0 139.0 137.0 152.0 168.0 142.0 147.0 145.0 184.0 147.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.80 Median:163.00 stddev:1.32 (0.8%) stddev-sans-first:1.36 07:17:36 INFO - PROCESS | 1953 | Values: 162.0 164.0 165.0 163.0 161.0 161.0 164.0 163.0 162.0 163.0 07:17:36 INFO - PROCESS | 1953 | 07:17:36 INFO - PROCESS | 1953 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:76.00 stddev:9.58 (12.6%) stddev-sans-first:9.80 07:17:36 INFO - PROCESS | 1953 | Values: 67.0 68.0 66.0 65.0 67.0 68.0 85.0 87.0 84.0 85.0 07:17:36 INFO - PROCESS | 1953 | -------- Summary: end -------- 07:17:36 INFO - PROCESS | 1953 | 07:17:37 INFO - TEST-INFO | 1953: exit 0 07:17:37 INFO - TEST-OK | kraken | took 59363ms 07:17:37 INFO - SUITE-END | took 1175s 07:17:37 INFO - Completed test suite (00:19:35) 07:17:37 INFO - javascript benchmark 07:17:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3159.5260710893363, 3353.6814002669753, 3395.3548825723096, 3410.7782994105382, 3419.4699821527656, 5611.214429979796, 5636.830895073149, 5685.886282274352, 5741.282339707542, 5789.0, 5675.375708672053, 5693.516051135658, 5793.507551858087, 5809.01246788046, 5840.0, 5109.386873575176, 5120.92318615221, 5192.454792246816, 5195.818146364878, 5288.101022826128, 5257.0, 5284.39229488608, 5290.841274761763, 5315.0698627740185, 5331.360236771593, 5123.0, 5169.612279079084, 5170.991656626921, 5222.190560463116, 5224.686518808846, 5217.34783152106, 5220.190870415092, 5223.08595995703, 5226.111561034595, 5232.555232805216, 5121.7439128043825, 5181.81863634771, 5191.299174611455, 5210.400803907562, 5220.895582088375, 5646.915296270559, 5652.0, 5653.9151912721345, 5755.8561035974435, 5778.191053252548, 6207.224096987877, 6243.032329988859, 6278.560500764948, 6280.717367718464, 6302.338254483305, 6143.87712245757, 6228.47058000066, 6290.4024117709205, 6328.11406403104, 6334.049892516114, 5865.73604187804, 5870.882582348329, 5897.557683173779, 5906.497947674453, 5907.556933230025, 6316.589421687576, 6326.588771729823, 6329.1139240505445, 6330.746770129235, 6341.334159913143, 5999.940000600025, 6037.456628903332, 6128.747575574743, 6185.969070154711, 6198.411150940653, 5337.0, 6262.3111457739615, 6310.242770867525, 6313.3686631336495, 6314.368563143649, 6240.687965601702, 6256.937430625727, 6259.0, 6264.9373506265265, 6273.686315684289, 6059.878802423927, 6132.967444181115, 6141.039029825737, 6228.314885362606, 6284.780032698925, 4945.8021679132435, 4982.701037937735, 4985.476524964899, 4989.201727723546, 5025.924611130836, 4429.114177164515, 4439.889002774957, 4470.240059189946, 4483.125790470892, 4485.842995495207, 5647.350554686182, 5689.829305120853, 5707.0, 5733.197352370671, 5766.163906233573, 6310.742259239762, 6313.273973493107, 6317.6209427434505, 6330.841728956813, 6347.428731414195, 5781.53747700183, 5787.739551720183, 5789.710514474259, 5806.390329015477, 5848.912266316009, 5322.361316642027, 5326.467353264643, 5381.865453363697, 5387.0841956867425, 5444.428335024844, 5364.2490051393615, 6286.968565157236, 6291.811245662638, 6351.936480635134, 6355.841103972252, 5123.436421993503, 6337.904931426125, 6342.365763423621, 6343.524235682435, 6361.268454127835, 6250.4687101595055, 6260.968695156495, 6295.527835412454, 6316.6841657916, 6322.936770632235, 6196.628202307876, 6198.690065496798, 6210.130581718473, 6212.502999759918, 6234.1583886174785, 6165.167702360124, 6251.53113516479, 6281.560290779602, 6284.905726414199, 6297.1813664223355, 5927.555433342431, 5994.580379373402, 6023.608465449732, 6077.878442431215, 6117.357677443804, 5905.320888097924, 6068.969655151696, 6102.0, 6112.144299798121, 6113.327533971348, 5947.226860508106, 5964.493018093511, 5968.22413086296, 5982.923073846575, 6018.187544681412, 5333.626646134889, 5919.4968427684125, 5981.401859814157, 6023.1868697726995, 6032.215811944595, 5948.553858460719, 5980.222571065734, 5997.430244126801, 6000.5799594028, 6013.218281623361], "value": 5702.202418759445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.937503437036, 483.42231033914464, 506.56941599640334, 516.1251678405105, 548.4953842464931, 28557.857210713944, 30824.53763193554, 30832.691673083318, 30908.69091309089, 30922.69077309226, 40377.0, 41545.584544154546, 41572.79213603928, 44266.0, 44417.0, 9663.5168241588, 9793.21654267657, 9800.90199098007, 9805.90194098057, 9818.50907454628, 8680.262177714902, 8913.866292005625, 9718.902810971871, 9810.46042467664, 9817.165540929029, 11735.647930562096, 11978.70053248667, 11980.880191198064, 12240.387980600934, 12255.69360765979, 12048.698782530419, 12114.273143611323, 12132.939335303266, 12136.878631213664, 12144.757104857941, 8554.572271386469, 11447.713807154805, 11782.116341274354, 11908.285502869769, 11981.820272695917, 40329.39505907414, 40471.0, 40516.39225411621, 40587.391189132184, 40637.0, 3432.673895979878, 3547.379208638478, 3661.560612726463, 3745.550533935945, 4247.787610619456, 30928.381432371225, 32087.518687219712, 32300.838495807606, 32360.352792944246, 32705.50941735876, 3253.209817155721, 3288.57248557686, 3304.157439852846, 3309.5697559317377, 3371.9494207586904, 493955.0, 496376.0, 497813.0, 498524.0, 499053.0, 57747.71126144342, 57891.71054144702, 57933.0, 57964.0, 58043.0, 60744.39255607476, 60847.783044339754, 60914.0, 61082.38917610856, 61092.0, 15559.377624895104, 16014.119223442822, 16020.839791601935, 16061.357545698042, 16095.03429794216, 12489.50041998328, 12554.560590379293, 12556.246625202517, 12557.497700091893, 12563.0, 13496.865031349756, 13789.862101379058, 15041.323140458706, 15367.0, 15446.382144714311, 5765.827025189251, 6699.732010719614, 6786.8303292417095, 6865.244823069458, 6896.655167241618, 899.4333569851083, 924.1497822003772, 1000.7698229407277, 1030.5465595138264, 1080.438172150493, 26508.072217472294, 29850.85074574613, 29917.70082299149, 30007.549886751716, 31929.680703193135, 1077.4774234496263, 1078.9676309710721, 1081.967540973772, 1096.572336788653, 1115.3363748569589, 15623.843761562466, 15692.843071569367, 15693.843061569467, 15709.371625135094, 16065.518034458984, 659340.0, 662503.0, 662623.0, 666889.0, 667551.0, 58047.83904321974, 58284.4171558279, 58352.708236458544, 58425.70787146122, 58488.41511584915, 1376.160542069337, 1392.6518370407398, 1424.2593851197319, 1428.457186269211, 1433.1902475101533, 26599.468010640066, 28511.0, 29913.252168695955, 30045.3990920176, 30631.846840765655, 49517.0, 49633.99999999855, 49733.75133124311, 50053.24920126128, 50064.49935500598, 574697.0, 594274.0, 594643.0, 595129.0, 595719.0, 31986.0, 33956.49065263974, 34199.487007694406, 40360.59639403568, 40799.59200408077, 44458.0, 47541.76229118832, 47551.28673069976, 48435.27347089867, 48530.5146948526, 37646.62353376431, 38903.80548097241, 39263.803680981415, 40255.798721006206, 40272.395914060726, 38794.61205388023, 38873.92949414091, 39406.81779546618, 39991.0, 40355.78932632026, 38215.42676859905, 38486.037849053995, 38563.03592410212, 38629.806850965564, 38726.80636596799, 33011.0, 33527.0, 34157.48763768496, 34957.30085398329, 35477.29045419129, 23260.604363738228, 24223.788810559756, 25261.74738252594, 25507.23478295654, 26253.21240362792], "value": 20255.646341784177, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.7468033757551, 384.2813937936059, 385.4237914318093, 396.87498437992014, 407.14499550943026, 19628.509287267825, 22079.779202207974, 22242.88878555609, 22268.55045944949, 22671.546569068607, 20982.16071357144, 21379.0, 21390.88633348958, 21401.46496337592, 21451.356459306247, 5709.600327977043, 5950.79172228972, 6068.605540639866, 6082.665453400061, 6113.358097399776, 4207.810648520824, 4430.689851710382, 5914.822555323347, 6422.807315780511, 6726.394624483795, 6621.106150669646, 6730.764423245188, 6736.090627765262, 6737.696803643823, 6738.93261067388, 6570.44151247147, 6698.83252918676, 6724.966375168093, 6746.19045714515, 6752.257251702334, 5063.848084557432, 5357.464253574651, 5360.490753378423, 5818.389069147721, 5845.85385365365, 13354.532591359255, 13454.865451345458, 13596.38816253271, 13615.727685446236, 13688.589342319747, 2105.2736805801974, 2293.495431005193, 2315.386422598013, 2395.820313476479, 2407.6629271901948, 10281.794364112677, 10308.433036183007, 10333.844992325121, 10387.169026477864, 10437.530311136017, 1833.1842330163083, 1835.862310326731, 1850.2968871828753, 1853.5366158460386, 1865.598896237302, 112664.18339541495, 120996.0, 128887.0, 130025.0, 131031.34484327612, 11635.88364116348, 11724.88275117255, 11828.876256755595, 11857.11071669611, 11861.0, 14742.189179595225, 14856.548669469417, 14881.404743810343, 14885.776713349309, 14906.4782732606, 6980.860382792316, 8564.357673174412, 8623.78440538979, 8741.256993155526, 8776.341774366949, 8538.87191692125, 8546.871796923051, 8549.145085491402, 8632.697855575025, 8688.826223475495, 4512.473015111548, 4603.424571928509, 4668.906621867543, 4687.0, 4913.533214344631, 1676.5892356372767, 1741.4601473543241, 1784.236982278587, 1897.795004392124, 1936.3029309448586, 828.2545708862073, 839.4963022186761, 853.9231469167805, 915.7362839281749, 944.5607792376578, 20581.89709051475, 21699.528075507842, 22386.888065559568, 22615.0, 22617.773822261566, 884.0540621534896, 908.5230254116642, 913.9040400757958, 920.5903372999085, 942.7124726958207, 11536.538538458535, 11724.00345970585, 11953.820692689616, 11987.40062996864, 11994.700132496757, 594311.0, 602139.8823064686, 608502.0, 615950.0, 623241.0, 148884.0, 153971.0, 154493.0, 155529.11296209384, 156798.0, 917.5137177296044, 946.3470205558143, 963.5808423335872, 964.4454438697777, 987.4075554667143, 17388.82611173872, 17956.820431795513, 19772.011399429684, 20555.794442055387, 20685.379438616867, 51537.0, 58702.0, 59741.402585975324, 60592.39407606044, 60861.69569152126, 85897.0, 86046.0, 86287.0, 86576.56711716653, 86806.1319386798, 7683.9231607683205, 8004.679227927327, 8231.259186673227, 8352.791180220544, 8380.287675547646, 7701.4608977371045, 11221.943890280496, 11324.660260192208, 12657.556985505464, 13798.17210967345, 11151.721206969567, 11628.418579070843, 11663.0, 11773.882261177621, 11884.10869184832, 9675.951620241854, 11509.0, 11602.245854019462, 11683.299002059903, 11736.765264694488, 5796.855078623068, 10605.151587873106, 11362.772744545227, 12006.699832504257, 12051.457684404048, 8596.312295016258, 8629.180227878342, 8673.91326086731, 8700.216980471534, 8702.390832641655, 8575.95712021436, 9392.436453812794, 11169.497372618089, 11820.349880756641, 12001.39993000364], "value": 9616.136277245612, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1476.2913801375344, 1541.3603354607837, 1709.7264437689971, 1847.8429333506667, 1874.0348720408992, 8475.660973561058, 8800.383973121883, 8925.509096999662, 8931.196192342688, 8936.195742383185, 7787.377009839213, 8008.55952922589, 8371.121032291612, 8567.314614830813, 8705.7823554411, 2356.634721618152, 2383.07060246504, 2383.4398916254668, 2383.6901202843605, 2385.9045638174453, 2354.9646755298686, 2367.4554852383962, 2386.844855084414, 2396.940076498093, 2400.2679182849224, 2076.9169233230655, 2153.5154590217153, 2214.357836227492, 2389.3190440724397, 2396.0535588442553, 2354.3525530479083, 2368.4670949036417, 2385.6183010718287, 2389.629607410854, 2394.2697477269426, 2359.362971997551, 2372.1934542255717, 2380.0122948976327, 2380.2502211803226, 2383.2016274548046, 8840.602172902234, 8903.777405564913, 8931.955340223321, 8934.463932164092, 8967.551622418852, 5243.842684719464, 5358.035553600351, 5397.757100930428, 5414.593905457067, 5422.5119739223655, 3573.4997100405703, 3674.889753307405, 3725.403935370328, 3751.9437208441896, 3756.586775454698, 1092.7541303206795, 1093.3549205968516, 1102.614085070219, 1118.7650593375402, 1120.9831852522218, 11564.595239166589, 11683.7079073023, 11701.590444334493, 11709.590164344207, 11815.940920295343, 4398.560143985575, 4400.185965596375, 4561.589456948825, 4571.085782843445, 4623.791929363119, 9129.81740365189, 9137.81724365509, 9156.359054866096, 9159.038300978435, 9193.724188274362, 5255.159174532057, 5257.5005374489365, 5260.395054568697, 5261.736913154327, 5270.472952704698, 4821.686590371684, 4912.754362281871, 4918.901621967612, 4929.359183306147, 4948.233023881232, 3649.5255616770182, 3690.040589446763, 3697.5932647408763, 3749.1189570450924, 3752.681022113151, 1076.8707755069443, 1120.467777805552, 1123.4102096399456, 1132.8583927009124, 1142.6686260984222, 760.5702777930452, 895.1853813030111, 914.2457472585011, 923.5197697197419, 940.0646356874953, 5897.498712609477, 5939.406059394112, 6011.5190784737115, 6014.338422773491, 6147.784827531015, 445.3809205204768, 468.62041746185696, 489.9951000490021, 494.0933387234465, 581.1921429213397, 3638.6543278388513, 3826.0052386379743, 4006.0185254612807, 4017.5178978522763, 4081.0613558881623, 10398.636047738293, 10712.464376781285, 10829.971152740478, 10832.78334433307, 10837.133029357632, 9763.658271960447, 10234.33476824004, 10673.146148308117, 10755.085817705427, 10816.837747433794, 8089.878651820228, 8203.671853125927, 8244.093149753522, 8254.793630159174, 8259.628316725762, 5882.441168089024, 5952.761889524544, 6088.665123418255, 6110.7861224856915, 6130.233720784902, 9708.320417570701, 10076.395416275047, 10092.293539452461, 10110.03954624339, 10120.443375614412, 9512.191463725661, 10017.899821001696, 10034.247431442525, 10136.746581335525, 10140.340877843211, 7256.0567126273245, 7469.887951680837, 7587.279208475186, 7612.276833700789, 7666.0, 8440.282575981111, 8483.533405662502, 8541.572921354033, 8564.05795362522, 8582.82834343322, 7442.468254047275, 7622.58075805836, 7660.673249953793, 7775.22247775218, 7798.454108212371, 7389.334959853639, 7419.554826710415, 7482.139553951365, 7577.5453472791805, 7593.202713714981, 7063.364297213276, 7108.609026503385, 7119.430445564239, 7131.821704457223, 7134.108236470442, 4053.290674131956, 4237.152569486177, 5021.347224860745, 5061.342025536804, 5098.541131298052, 4520.977395113004, 4892.412910450626, 5028.446870844277, 5090.898182036264, 5193.61047921415], "value": 4494.293991445461, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.79146701575394, 311.07610397120544, 313.72549019607834, 316.55365934033006, 321.13934655124285, 5332.067362950376, 5369.463053694633, 5392.865178370543, 5396.865078373043, 5407.945920540794, 4802.895334073165, 5137.897242055156, 5380.946190538093, 5393.197795554135, 5415.512603865652, 3348.9162770930798, 3856.3636999894984, 3970.523537175543, 3980.820863061155, 4111.342185250361, 3075.600171977651, 3376.409128402532, 3701.833417496219, 4037.636612704856, 4071.4503542021894, 3378.9493157602533, 3712.424574191005, 4030.576789437111, 4045.4134150548152, 4060.167665628539, 3790.58303586608, 3867.5552311484257, 4000.239954408653, 4084.1831633673382, 4093.3655283431117, 3069.5856059431912, 3528.941317604734, 3529.082438565991, 3544.680978711928, 3546.5744110706623, 5162.883217880689, 5206.505381988705, 5231.398389185254, 5233.659812112239, 5260.92108618371, 4453.888652783674, 4650.790714417825, 4669.463011753658, 4692.436907571079, 4715.386999690053, 4866.513348665105, 5209.0, 5290.867728306785, 5588.3573389060375, 5619.471237942901, 3951.9449886059374, 3953.782541960191, 3959.723159183481, 3967.6429121378924, 3971.1660551284267, 5679.318481782171, 5816.592838501307, 5843.970780146157, 5913.526917846562, 5928.525717942554, 3959.742616729903, 3962.881113566598, 3968.0, 3984.442178095069, 3989.880303590897, 3347.3138006709055, 4027.859024934113, 4028.5165780106577, 4047.4738284022887, 4065.4918135233097, 3928.4500169976227, 4410.154207405541, 4613.423322084739, 4683.424116735781, 4703.7883295251795, 4963.429205641398, 5177.954707890362, 5690.971545142248, 5711.571632127607, 5731.140328950691, 1284.2808027504627, 1363.8227030485973, 1373.2790285100402, 1380.6055883557678, 1382.0187666756715, 697.6546609428366, 795.9602019898982, 817.7424111404888, 857.3012994409536, 868.4094815525503, 4404.9207944053915, 4994.775235114356, 5100.276797937527, 5173.922391164136, 5214.243934629458, 4758.381410416693, 5679.602427830095, 5758.856028599319, 5796.275465566804, 5797.594168408171, 4954.3311652926395, 5235.816746413933, 5237.36003477587, 5243.318368612056, 5256.763445644955, 2792.5392310268985, 2849.860055977584, 2974.0929016649698, 3012.3372857971212, 3026.7753201396954, 4718.811247550128, 4764.523547645277, 5877.23595932526, 5979.222701048836, 6003.249593800775, 4817.879553011203, 5708.827052336676, 5922.822315330547, 5925.911111333334, 5933.753911678509, 5810.854728631733, 5855.209546711139, 5856.853578660568, 5910.438508341701, 5940.7722104210925, 5236.2407450918645, 5532.612717109763, 5660.773569057192, 5708.315002199763, 5711.400302968294, 5656.8020119295625, 5656.858578535405, 5676.631018983753, 5702.230198923092, 5711.8000869969355, 4706.717596944194, 5443.918341224964, 5841.269841269841, 5922.60818707612, 5944.3164036136395, 2848.5442329227226, 3168.6514483406345, 3375.341808347349, 3399.6770306820813, 3419.1623052352334, 4163.687723420695, 4430.490493593278, 4815.6147508198555, 4864.586510146677, 4947.703137811743, 4266.829326826892, 4300.709956882924, 4722.5513576210205, 4731.905361892812, 4770.904581908412, 4194.916101678011, 4696.262382918206, 4714.0, 4734.905301894012, 4794.74646915949, 3864.632859878307, 4175.434212170436, 4232.555581663881, 4284.978575107105, 4342.674299427619, 5303.893922121613, 5526.392096869422, 5561.694106824001, 5568.387477377566, 5573.665580065209, 4873.415190177201, 5177.223416487557, 5299.6290259681455, 5429.864253393696, 5591.099026331067], "value": 3985.9832917157896, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.5862427358627, 792.2473650032471, 813.8209593889342, 827.9130691277419, 837.9958100209498, 15021.924890375547, 16234.83765162348, 16235.91882040591, 16291.51125466235, 16298.83701162988, 11524.827127593093, 12374.133810633259, 12377.381130943439, 12378.50485980562, 12383.56657516987, 834.5034704350917, 856.1994535109661, 856.3448961544424, 858.5535521528801, 858.7423772868145, 717.4081382859137, 824.2128767027505, 838.2078935406043, 846.5132548784443, 847.8431490174306, 896.7982204004112, 897.2149369301861, 898.2589363774879, 899.9370044096916, 901.0538934119181, 891.31814162166, 891.8974317953389, 893.9240164586018, 894.4722613657908, 895.8029233568637, 667.3093348384399, 667.6528205333248, 668.2882729892698, 669.0900375489331, 670.3263220463451, 11002.614908478165, 12927.935360323138, 12958.611241662766, 12977.675558111028, 13338.533151339754, 12027.278363298143, 12030.63908082759, 12175.878241217562, 12295.631131066082, 12322.383880805924, 9653.696750938603, 10810.567577296899, 10812.0, 11091.500882460308, 11506.712332191679, 3742.251549690073, 3775.6979441644603, 3884.2231553689094, 3979.462772525701, 4018.9196216075807, 68740.65629671869, 101870.49064754703, 103533.0, 103580.48209758979, 115417.99999999916, 11077.723056923642, 11218.439078046065, 11315.0, 11396.202265841477, 11797.115216358807, 18300.81699182991, 22169.334919952427, 22328.88835555812, 22353.776462235168, 22528.887355563118, 8177.836443271101, 8191.221833925768, 8237.588120593946, 8319.168083191753, 8376.162383761697, 4386.342048692658, 4524.705894116872, 4569.977150114295, 4618.03021365517, 4720.834770783006, 3384.7969121852766, 3477.6000759912936, 3479.913002174966, 3480.3735327641266, 3481.5299934509026, 589.2693060604819, 673.6598018000878, 684.4934748286232, 691.4261163234503, 692.2627401817149, 563.4421922296897, 606.1816547660711, 626.8276224038335, 640.4396153365805, 725.6045455226914, 8839.734807955772, 8875.0, 9352.438853668802, 9462.101100395452, 9580.233581313478, 618.6226401894841, 626.6929204689669, 668.3884245914934, 678.1861765880963, 688.469878193788, 4482.5069242383315, 4629.051044535862, 4637.443506779208, 4638.443386793607, 4649.69776964503, 89710.0, 89905.0, 90112.0, 141014.58985410014, 161516.19241903716, 85873.57063214644, 86751.0, 102548.97451025543, 134437.0, 142683.0, 117017.0, 117653.0, 119575.40212298883, 119644.8035519651, 120896.0, 9141.725748227564, 9306.767330816783, 9610.279229057709, 9634.277429192698, 9694.982026887075, 54364.0, 60344.0, 60788.08817867824, 64315.392115197494, 67666.0, 100150.49924750329, 100427.0, 101264.4810327831, 103710.4814475953, 103777.48111259396, 13325.466981320638, 13407.994400419813, 13466.711081722326, 13559.79660305116, 14080.203818613343, 15156.696866062837, 15322.157281349633, 15398.965488001375, 15551.84448155504, 15575.221238938235, 14128.222947737973, 14573.635659108182, 14606.926965365104, 14745.483908063596, 14825.258737062888, 13738.931305343409, 14240.074395164282, 14241.0, 14251.28743562797, 14302.642433939234, 13528.79706804379, 13883.375248114062, 13889.513867014606, 14294.070885392417, 14627.195504246944, 10487.81338608956, 11308.038816700673, 11426.714332141762, 11533.384145581995, 12120.151589388659, 11117.388543630179, 11237.157213209206, 11460.312381257152, 11586.246893951866, 11629.941850290694], "value": 7734.857444199386, "unit": "score"}], "name": "dromaeo_css", "value": 7320.619906438814, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 111.0, 113.0, 111.0, 112.0, 112.0, 112.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 106.0, 160.0, 107.0, 107.0, 126.0, 116.0, 141.0, 106.0], "value": 121.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 184.0, 182.0, 185.0, 179.0, 185.0, 184.0, 181.0, 184.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 58.0, 123.0, 82.0, 80.0, 76.0, 66.0, 85.0, 57.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 93.0, 72.0, 79.0, 69.0, 95.0, 72.0, 67.0, 99.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 87.0, 84.0, 88.0, 84.0, 87.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 93.0, 93.0, 92.0, 93.0, 94.0, 91.0, 92.0], "value": 92.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 84.0, 83.0, 86.0, 83.0, 84.0, 86.0, 85.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 77.0, 66.0, 75.0, 88.0, 101.0, 102.0, 83.0, 71.0, 71.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 84.0, 85.0, 83.0, 80.0, 83.0, 83.0, 85.0, 79.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 137.0, 152.0, 168.0, 142.0, 147.0, 145.0, 184.0, 147.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 165.0, 163.0, 161.0, 161.0, 164.0, 163.0, 162.0, 163.0], "value": 162.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 65.0, 67.0, 68.0, 85.0, 87.0, 84.0, 85.0], "value": 74.2, "unit": "score"}], "name": "kraken", "value": 1442.2, "alertThreshold": 2.0}]} 07:17:38 INFO - Return code: 0 07:17:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:17:38 INFO - # TBPL SUCCESS # 07:17:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:17:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:17:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:17:38 INFO - 2016-08-04 07:17:38.084 screenresolution[1963:11356] starting screenresolution argv=screenresolution get 07:17:38 INFO - 2016-08-04 07:17:38.087 screenresolution[1963:11356] Display 0: 1600x1200x32@60 07:17:38 INFO - 2016-08-04 07:17:38.093 screenresolution[1964:11359] starting screenresolution argv=screenresolution list 07:17:38 INFO - Available Modes on Display 0 07:17:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:17:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:17:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:17:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:17:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:17:38 INFO - Intel Iris: 07:17:38 INFO - Chipset Model: Intel Iris 07:17:38 INFO - Type: GPU 07:17:38 INFO - Bus: Built-In 07:17:38 INFO - VRAM (Dynamic, Max): 1536 MB 07:17:38 INFO - Vendor: Intel (0x8086) 07:17:38 INFO - Device ID: 0x0a2e 07:17:38 INFO - Revision ID: 0x0009 07:17:38 INFO - Displays: 07:17:38 INFO - Display: 07:17:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:17:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:17:38 INFO - Display Serial Number: 1600x1200 60 07:17:38 INFO - Main Display: Yes 07:17:38 INFO - Mirror: Off 07:17:38 INFO - Online: Yes 07:17:38 INFO - Rotation: Supported 07:17:38 INFO - Return code: 0 07:17:38 INFO - Running post-action listener: _resource_record_post_action 07:17:38 INFO - ##### 07:17:38 INFO - ##### Finished run-tests step (success) 07:17:38 INFO - ##### 07:17:38 INFO - Running post-run listener: _resource_record_post_run 07:17:38 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 33021440; Write bytes: 707599872; Read time: 312; Write time: 1956 07:17:38 INFO - TinderboxPrint: CPU usage
53.4% 07:17:38 INFO - TinderboxPrint: I/O read bytes / time
33,021,440 / 312 07:17:38 INFO - TinderboxPrint: I/O write bytes / time
707,599,872 / 1,956 07:17:38 INFO - TinderboxPrint: CPU idle
2,253.8 (46.6%) 07:17:38 INFO - TinderboxPrint: CPU system
962.7 (19.9%) 07:17:38 INFO - TinderboxPrint: CPU user
1,625.1 (33.6%) 07:17:38 INFO - TinderboxPrint: Swap in / out
436,236,288 / 0 07:17:38 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394123776; Write bytes: 389523456; Read time: 29266; Write time: 786 07:17:38 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31260160; Write bytes: 296003072; Read time: 294; Write time: 1125 07:17:38 INFO - Running post-run listener: _upload_blobber_files 07:17:38 INFO - Blob upload gear active. 07:17:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:17:38 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 07:17:38 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:17:38 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:17:39 INFO - (blobuploader) - INFO - Open directory for files ... 07:17:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:17:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:17:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:17:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:17:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:17:39 INFO - (blobuploader) - INFO - Done attempting. 07:17:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:17:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:17:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:17:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:17:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:17:41 INFO - (blobuploader) - INFO - Done attempting. 07:17:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:17:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:17:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:17:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:17:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:17:43 INFO - (blobuploader) - INFO - Done attempting. 07:17:43 INFO - (blobuploader) - INFO - Iteration through files over. 07:17:43 INFO - Return code: 0 07:17:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:17:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:17:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cb1b7ff6fb913fc4fde9284d787ac0a0b3c63b69bc4022cfb9c37fbd0c6a564298a2112e94a34a726bbe4db5ae8689b58fd08909df877733ad08e8a2f74455a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6e0f4ea8325b6e1f45b99af7a84925835490c3a829c42ac69071b9c1458594415a2aa4ac0f5af94d2f1d33b48ded8eac056d1e3bba51a40e326e22586e40d8d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c8a1b4d7cb285b4086b1f0865a355820f1c9859b762eaff4d9e1c7b71709ef373217cd2923b7130a44bf2e4f723df52fb2422cab1b7a1953a9180d67a6edc20b"} 07:17:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:17:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:17:43 INFO - Contents: 07:17:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cb1b7ff6fb913fc4fde9284d787ac0a0b3c63b69bc4022cfb9c37fbd0c6a564298a2112e94a34a726bbe4db5ae8689b58fd08909df877733ad08e8a2f74455a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6e0f4ea8325b6e1f45b99af7a84925835490c3a829c42ac69071b9c1458594415a2aa4ac0f5af94d2f1d33b48ded8eac056d1e3bba51a40e326e22586e40d8d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c8a1b4d7cb285b4086b1f0865a355820f1c9859b762eaff4d9e1c7b71709ef373217cd2923b7130a44bf2e4f723df52fb2422cab1b7a1953a9180d67a6edc20b"} 07:17:43 INFO - Running post-run listener: copy_logs_to_upload_dir 07:17:43 INFO - Copying logs to upload dir... 07:17:43 INFO - mkdir: /builds/slave/test/build/upload/logs 07:17:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.424503 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-08-04 07:17:43.327256) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 07:17:43.330760) ========= 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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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/Larch/sha512/cb1b7ff6fb913fc4fde9284d787ac0a0b3c63b69bc4022cfb9c37fbd0c6a564298a2112e94a34a726bbe4db5ae8689b58fd08909df877733ad08e8a2f74455a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6e0f4ea8325b6e1f45b99af7a84925835490c3a829c42ac69071b9c1458594415a2aa4ac0f5af94d2f1d33b48ded8eac056d1e3bba51a40e326e22586e40d8d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c8a1b4d7cb285b4086b1f0865a355820f1c9859b762eaff4d9e1c7b71709ef373217cd2923b7130a44bf2e4f723df52fb2422cab1b7a1953a9180d67a6edc20b"} build_url:https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011187 build_url: 'https://queue.taskcluster.net/v1/task/f86S-mI_T2Cnjo4aGmpv2w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cb1b7ff6fb913fc4fde9284d787ac0a0b3c63b69bc4022cfb9c37fbd0c6a564298a2112e94a34a726bbe4db5ae8689b58fd08909df877733ad08e8a2f74455a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6e0f4ea8325b6e1f45b99af7a84925835490c3a829c42ac69071b9c1458594415a2aa4ac0f5af94d2f1d33b48ded8eac056d1e3bba51a40e326e22586e40d8d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c8a1b4d7cb285b4086b1f0865a355820f1c9859b762eaff4d9e1c7b71709ef373217cd2923b7130a44bf2e4f723df52fb2422cab1b7a1953a9180d67a6edc20b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 07:17:43.363754) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 07:17:43.364097) ========= 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.9LVwSjz0ST/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.FB6I4rlLGX/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.005011 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 07:17:43.413428) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 07:17:43.413926) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 07:17:43.414259) ========= ========= Total master_lag: 0.18 =========