builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0095 starttime: 1467924314.84 results: success (0) buildid: 20160707112723 builduid: 7c832758263d46b49cbba756a95a61ae revision: 94cce4e79310565ff5b169f890842499ea713f8a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:14.844077) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:14.844545) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:14.844832) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:14.869401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:14.869720) ========= 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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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-07-07 13:45:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 27.1M=0s 2016-07-07 13:45:15 (27.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129371 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:15.033915) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:15.034252) ========= 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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.027991 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:15.082457) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-07 13:45:15.082811) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 94cce4e79310565ff5b169f890842499ea713f8a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 94cce4e79310565ff5b169f890842499ea713f8a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iDoPXS6tfB/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.oUm1HL0bdc/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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-07-07 13:45:15,153 truncating revision to first 12 chars 2016-07-07 13:45:15,153 Setting DEBUG logging. 2016-07-07 13:45:15,154 attempt 1/10 2016-07-07 13:45:15,154 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/94cce4e79310?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 13:45:16,871 unpacking tar archive at: mozilla-central-94cce4e79310/testing/mozharness/ program finished with exit code 0 elapsedTime=1.967086 ========= master_lag: 0.26 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-07 13:45:17.304922) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:17.305313) ========= script_repo_revision: 94cce4e79310565ff5b169f890842499ea713f8a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:17.305800) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:17.306153) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 13:45:17.325279) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-07 13:45:17.325960) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iDoPXS6tfB/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.oUm1HL0bdc/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 13:45:17 INFO - MultiFileLogger online at 20160707 13:45:17 in /builds/slave/test 13:45:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 13:45:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:45:17 INFO - {'append_to_log': False, 13:45:17 INFO - 'base_work_dir': '/builds/slave/test', 13:45:17 INFO - 'blob_upload_branch': 'Firefox', 13:45:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:45:17 INFO - 'branch': 'Firefox', 13:45:17 INFO - 'buildbot_json_path': 'buildprops.json', 13:45:17 INFO - 'config_files': ('talos/mac_config.py',), 13:45:17 INFO - 'default_actions': ('clobber', 13:45:17 INFO - 'read-buildbot-config', 13:45:17 INFO - 'download-and-extract', 13:45:17 INFO - 'populate-webroot', 13:45:17 INFO - 'create-virtualenv', 13:45:17 INFO - 'install', 13:45:17 INFO - 'run-tests'), 13:45:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:45:17 INFO - 'download_minidump_stackwalk': True, 13:45:17 INFO - 'download_symbols': 'ondemand', 13:45:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:45:17 INFO - 'tooltool.py': '/tools/tooltool.py', 13:45:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:45:17 INFO - '/tools/misc-python/virtualenv.py')}, 13:45:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:45:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:45:17 INFO - 'installer_path': 'installer.exe', 13:45:17 INFO - 'log_level': 'info', 13:45:17 INFO - 'log_name': 'talos', 13:45:17 INFO - 'log_to_console': True, 13:45:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:45:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:45:17 INFO - 'opt_config_files': (), 13:45:17 INFO - 'pip_index': False, 13:45:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:45:17 INFO - 'cmd': ('bash', 13:45:17 INFO - '-c', 13:45:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:45:17 INFO - 'enabled': True, 13:45:17 INFO - 'halt_on_failure': False, 13:45:17 INFO - 'name': 'check_screen_resolution'},), 13:45:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:45:17 INFO - 'cmd': ('bash', 13:45:17 INFO - '-c', 13:45:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:45:17 INFO - 'enabled': True, 13:45:17 INFO - 'halt_on_failure': False, 13:45:17 INFO - 'name': 'check_screen_resolution'},), 13:45:17 INFO - 'run_cmd_checks_enabled': True, 13:45:17 INFO - 'sps_profile': False, 13:45:17 INFO - 'sps_profile_interval': 0, 13:45:17 INFO - 'suite': 'dromaeojs', 13:45:17 INFO - 'system_bits': '32', 13:45:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:45:17 INFO - 'title': 't-yosemite-r7-0095', 13:45:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:45:17 INFO - 'use_talos_json': True, 13:45:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:45:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:45:17 INFO - 'work_dir': 'build'} 13:45:17 INFO - ##### 13:45:17 INFO - ##### Running clobber step. 13:45:17 INFO - ##### 13:45:17 INFO - Running pre-action listener: _resource_record_pre_action 13:45:17 INFO - Running main action method: clobber 13:45:17 INFO - rmtree: /builds/slave/test/build 13:45:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:45:19 INFO - Running post-action listener: _resource_record_post_action 13:45:19 INFO - ##### 13:45:19 INFO - ##### Finished clobber step (success) 13:45:19 INFO - ##### 13:45:19 INFO - ##### 13:45:19 INFO - ##### Running read-buildbot-config step. 13:45:19 INFO - ##### 13:45:19 INFO - Running pre-action listener: _resource_record_pre_action 13:45:19 INFO - Running main action method: read_buildbot_config 13:45:19 INFO - Using buildbot properties: 13:45:19 INFO - { 13:45:19 INFO - "project": "", 13:45:19 INFO - "product": "firefox", 13:45:19 INFO - "script_repo_revision": "production", 13:45:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 13:45:19 INFO - "repository": "", 13:45:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 13:45:19 INFO - "buildid": "20160707112723", 13:45:19 INFO - "pgo_build": "False", 13:45:19 INFO - "basedir": "/builds/slave/test", 13:45:19 INFO - "buildnumber": 284, 13:45:19 INFO - "slavename": "t-yosemite-r7-0095", 13:45:19 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:45:19 INFO - "platform": "yosemite_r7", 13:45:19 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 13:45:19 INFO - "branch": "mozilla-central", 13:45:19 INFO - "repo_path": "mozilla-central", 13:45:19 INFO - "revision": "94cce4e79310565ff5b169f890842499ea713f8a", 13:45:19 INFO - "stage_platform": "macosx64", 13:45:19 INFO - "builduid": "7c832758263d46b49cbba756a95a61ae", 13:45:19 INFO - "slavebuilddir": "test" 13:45:19 INFO - } 13:45:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 13:45:19 INFO - Running post-action listener: _resource_record_post_action 13:45:19 INFO - ##### 13:45:19 INFO - ##### Finished read-buildbot-config step (success) 13:45:19 INFO - ##### 13:45:19 INFO - ##### 13:45:19 INFO - ##### Running download-and-extract step. 13:45:19 INFO - ##### 13:45:19 INFO - Running pre-action listener: _resource_record_pre_action 13:45:19 INFO - Running main action method: download_and_extract 13:45:19 INFO - mkdir: /builds/slave/test/build/tests 13:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:45:19 INFO - https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:45:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 13:45:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 13:45:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/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 13:45:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/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 13:45:22 INFO - Downloaded 1199 bytes. 13:45:22 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 13:45:22 INFO - Using the following test package requirements: 13:45:22 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 13:45:22 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:45:22 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 13:45:22 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:45:22 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 13:45:22 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:45:22 INFO - u'jsshell-mac.zip'], 13:45:22 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:45:22 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 13:45:22 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 13:45:22 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:45:22 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 13:45:22 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:45:22 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 13:45:22 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:45:22 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 13:45:22 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:45:22 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 13:45:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:45:22 INFO - https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:45:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:45:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:45:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/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 13:45:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/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 13:45:24 INFO - Downloaded 18806368 bytes. 13:45:24 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'] 13:45:24 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 13:45:27 INFO - Return code: 0 13:45:27 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 13:45:27 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')]} 13:45:27 INFO - https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:45:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:45:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:45:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/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 13:45:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/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 13:45:28 INFO - Downloaded 18806368 bytes. 13:45:28 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'] 13:45:28 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 13:45:32 INFO - Return code: 0 13:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:45:32 INFO - https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:45:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 13:45:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 13:45:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/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 13:45:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/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 13:45:33 INFO - Downloaded 14403333 bytes. 13:45:33 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'] 13:45:33 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 13:45:33 INFO - Return code: 0 13:45:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:45:33 INFO - https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:45:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:45:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:45:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 13:45:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/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 13:45:36 INFO - Downloaded 112432830 bytes. 13:45:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:45:36 INFO - mkdir: /builds/slave/test/properties 13:45:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:45:36 INFO - Writing to file /builds/slave/test/properties/build_url 13:45:36 INFO - Contents: 13:45:36 INFO - build_url:https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:45:37 INFO - Running post-action listener: _resource_record_post_action 13:45:37 INFO - Running post-action listener: set_extra_try_arguments 13:45:37 INFO - ##### 13:45:37 INFO - ##### Finished download-and-extract step (success) 13:45:37 INFO - ##### 13:45:37 INFO - ##### 13:45:37 INFO - ##### Running populate-webroot step. 13:45:37 INFO - ##### 13:45:37 INFO - Running pre-action listener: _resource_record_pre_action 13:45:37 INFO - Running main action method: populate_webroot 13:45:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:45:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:45:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:45:37 INFO - '--tppagecycles', 13:45:37 INFO - '7'], 13:45:37 INFO - 'tests': ['tsvgm']}}, 13:45:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:45:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:45:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:45:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:45:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:45:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:45:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:37 INFO - 'talos_options': ['--disable-e10s'], 13:45:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:45:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:45:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:37 INFO - 'talos_options': ['--disable-e10s'], 13:45:37 INFO - 'tests': ['damp', 'tps']}, 13:45:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:37 INFO - 'tests': ['damp', 'tps']}, 13:45:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:45:37 INFO - 'tests': ['dromaeo_dom']}, 13:45:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:45:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:45:37 INFO - 'tests': ['basic_compositor_video']}, 13:45:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:45:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:45:37 INFO - 'tests': ['a11yr', 13:45:37 INFO - 'ts_paint', 13:45:37 INFO - 'tpaint', 13:45:37 INFO - 'sessionrestore', 13:45:37 INFO - 'sessionrestore_no_auto_restore', 13:45:37 INFO - 'tabpaint']}, 13:45:37 INFO - 'other-e10s': {'tests': ['a11yr', 13:45:37 INFO - 'ts_paint', 13:45:37 INFO - 'tpaint', 13:45:37 INFO - 'sessionrestore', 13:45:37 INFO - 'sessionrestore_no_auto_restore', 13:45:37 INFO - 'tabpaint']}, 13:45:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:45:37 INFO - 'ts_paint', 13:45:37 INFO - 'tpaint', 13:45:37 INFO - 'sessionrestore', 13:45:37 INFO - 'sessionrestore_no_auto_restore', 13:45:37 INFO - 'tabpaint']}, 13:45:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:45:37 INFO - 'ts_paint', 13:45:37 INFO - 'tpaint', 13:45:37 INFO - 'sessionrestore', 13:45:37 INFO - 'sessionrestore_no_auto_restore', 13:45:37 INFO - 'tabpaint']}, 13:45:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:45:37 INFO - 'tests': ['a11yr', 13:45:37 INFO - 'ts_paint', 13:45:37 INFO - 'tpaint', 13:45:37 INFO - 'sessionrestore', 13:45:37 INFO - 'sessionrestore_no_auto_restore', 13:45:37 INFO - 'tabpaint']}, 13:45:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:45:37 INFO - 'tests': ['a11yr', 13:45:37 INFO - 'ts_paint', 13:45:37 INFO - 'tpaint', 13:45:37 INFO - 'sessionrestore', 13:45:37 INFO - 'sessionrestore_no_auto_restore', 13:45:37 INFO - 'tabpaint']}, 13:45:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:45:37 INFO - 'tests': ['tsvgx', 13:45:37 INFO - 'tsvgr_opacity', 13:45:37 INFO - 'tart', 13:45:37 INFO - 'tscrollx', 13:45:37 INFO - 'cart']}, 13:45:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:45:37 INFO - 'tsvgr_opacity', 13:45:37 INFO - 'tart', 13:45:37 INFO - 'tscrollx', 13:45:37 INFO - 'cart']}, 13:45:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:37 INFO - 'talos_options': ['--disable-e10s'], 13:45:37 INFO - 'tests': ['tp5o']}, 13:45:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:37 INFO - 'tests': ['tp5o']}, 13:45:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:37 INFO - 'talos_options': ['--disable-e10s', 13:45:37 INFO - '--xperf_path', 13:45:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:45:37 INFO - 'tests': ['tp5n']}, 13:45:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:45:37 INFO - 'talos_options': ['--xperf_path', 13:45:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:45:37 INFO - 'tests': ['tp5n']}}, 13:45:37 INFO - 'talos.zip': {'path': '', 13:45:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:45:37 INFO - Running post-action listener: _resource_record_post_action 13:45:37 INFO - ##### 13:45:37 INFO - ##### Finished populate-webroot step (success) 13:45:37 INFO - ##### 13:45:37 INFO - ##### 13:45:37 INFO - ##### Running create-virtualenv step. 13:45:37 INFO - ##### 13:45:37 INFO - Running pre-action listener: _resource_record_pre_action 13:45:37 INFO - Running main action method: create_virtualenv 13:45:37 INFO - Creating virtualenv /builds/slave/test/build/venv 13:45:37 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 13:45:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:45:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:45:37 INFO - Using real prefix '/tools/python27' 13:45:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:45:38 INFO - Installing distribute.............................................................................................................................................................................................done. 13:45:41 INFO - Installing pip.................done. 13:45:41 INFO - Return code: 0 13:45:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:45:41 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')]} 13:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:41 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')]} 13:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:41 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 0x101098a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10112d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8203489120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ffcd30>, '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 0x100d61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4b20>, '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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 13:45:41 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 13:45:41 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 13:45:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDoPXS6tfB/Render', 13:45:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:45:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:45:41 INFO - 'HOME': '/Users/cltbld', 13:45:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:45:41 INFO - 'LOGNAME': 'cltbld', 13:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:41 INFO - 'MOZ_NO_REMOTE': '1', 13:45:41 INFO - 'NO_EM_RESTART': '1', 13:45:41 INFO - 'PAGER': '/bin/cat', 13:45:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:41 INFO - 'PWD': '/builds/slave/test', 13:45:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:45:41 INFO - 'SHELL': '/bin/bash', 13:45:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oUm1HL0bdc/Listeners', 13:45:41 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 13:45:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:45:41 INFO - 'USER': 'cltbld', 13:45:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:45:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:41 INFO - 'XPC_FLAGS': '0x0', 13:45:41 INFO - 'XPC_SERVICE_NAME': '0', 13:45:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:41 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 13:45:41 INFO - Cleaning up... 13:45:41 INFO - Return code: 0 13:45:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:45:41 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')]} 13:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:41 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')]} 13:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:41 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 0x101098a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10112d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8203489120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ffcd30>, '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 0x100d61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4b20>, '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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 13:45:41 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 13:45:41 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 13:45:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDoPXS6tfB/Render', 13:45:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:45:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:45:41 INFO - 'HOME': '/Users/cltbld', 13:45:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:45:41 INFO - 'LOGNAME': 'cltbld', 13:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:41 INFO - 'MOZ_NO_REMOTE': '1', 13:45:41 INFO - 'NO_EM_RESTART': '1', 13:45:41 INFO - 'PAGER': '/bin/cat', 13:45:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:41 INFO - 'PWD': '/builds/slave/test', 13:45:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:45:41 INFO - 'SHELL': '/bin/bash', 13:45:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oUm1HL0bdc/Listeners', 13:45:41 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 13:45:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:45:41 INFO - 'USER': 'cltbld', 13:45:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:45:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:41 INFO - 'XPC_FLAGS': '0x0', 13:45:41 INFO - 'XPC_SERVICE_NAME': '0', 13:45:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:41 INFO - Downloading/unpacking psutil>=3.1.1 13:45:41 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 13:45:41 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 13:45:41 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 13:45:41 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 13:45:41 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 13:45:41 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 13:45:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:45:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:45:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:45:44 INFO - Installing collected packages: psutil 13:45:44 INFO - Running setup.py install for psutil 13:45:44 INFO - building 'psutil._psutil_osx' extension 13:45:44 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 13:45:44 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 13:45:45 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 13:45:45 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 13:45:45 INFO - building 'psutil._psutil_posix' extension 13:45:45 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 13:45:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:45:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:45:45 INFO - ^ 13:45:45 INFO - 1 warning generated. 13:45:45 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 13:45:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:45:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:45:45 INFO - Successfully installed psutil 13:45:45 INFO - Cleaning up... 13:45:45 INFO - Return code: 0 13:45:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101098a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10112d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8203489120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ffcd30>, '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 0x100d61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4b20>, '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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 13:45:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:45:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:45:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDoPXS6tfB/Render', 13:45:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:45:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:45:45 INFO - 'HOME': '/Users/cltbld', 13:45:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:45:45 INFO - 'LOGNAME': 'cltbld', 13:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:45 INFO - 'MOZ_NO_REMOTE': '1', 13:45:45 INFO - 'NO_EM_RESTART': '1', 13:45:45 INFO - 'PAGER': '/bin/cat', 13:45:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:45 INFO - 'PWD': '/builds/slave/test', 13:45:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:45:45 INFO - 'SHELL': '/bin/bash', 13:45:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oUm1HL0bdc/Listeners', 13:45:45 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 13:45:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:45:45 INFO - 'USER': 'cltbld', 13:45:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:45:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:45 INFO - 'XPC_FLAGS': '0x0', 13:45:45 INFO - 'XPC_SERVICE_NAME': '0', 13:45:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:45:45 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 13:45:45 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 13:45:45 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 13:45:45 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 13:45:45 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 13:45:45 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 13:45:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:45:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:45:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:45:48 INFO - Installing collected packages: mozsystemmonitor 13:45:48 INFO - Running setup.py install for mozsystemmonitor 13:45:48 INFO - Successfully installed mozsystemmonitor 13:45:48 INFO - Cleaning up... 13:45:48 INFO - Return code: 0 13:45:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:45: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')]} 13:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45: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')]} 13:45:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:48 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 0x101098a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10112d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8203489120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ffcd30>, '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 0x100d61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4b20>, '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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 13:45:48 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 13:45:48 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 13:45:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDoPXS6tfB/Render', 13:45:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:45:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:45:48 INFO - 'HOME': '/Users/cltbld', 13:45:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:45:48 INFO - 'LOGNAME': 'cltbld', 13:45:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:48 INFO - 'MOZ_NO_REMOTE': '1', 13:45:48 INFO - 'NO_EM_RESTART': '1', 13:45:48 INFO - 'PAGER': '/bin/cat', 13:45:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:45:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:48 INFO - 'PWD': '/builds/slave/test', 13:45:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:45:48 INFO - 'SHELL': '/bin/bash', 13:45:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oUm1HL0bdc/Listeners', 13:45:48 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 13:45:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:45:48 INFO - 'USER': 'cltbld', 13:45:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:45:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:45:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:48 INFO - 'XPC_FLAGS': '0x0', 13:45:48 INFO - 'XPC_SERVICE_NAME': '0', 13:45:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:45:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:48 INFO - Downloading/unpacking jsonschema==2.5.1 13:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:45:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:45:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:45:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:45:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:45:51 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 13:45:51 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 13:45:51 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 13:45:51 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 13:45:51 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 13:45:51 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 13:45:51 INFO - Downloading functools32-3.2.3-2.tar.gz 13:45:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:45:51 INFO - warning: no files found matching '*.txt' 13:45:51 INFO - no previously-included directories found matching 'build' 13:45:51 INFO - no previously-included directories found matching 'dist' 13:45:51 INFO - no previously-included directories found matching '.git*' 13:45:51 INFO - Installing collected packages: jsonschema, functools32 13:45:52 INFO - Running setup.py install for functools32 13:45:52 INFO - warning: no files found matching '*.txt' 13:45:52 INFO - no previously-included directories found matching 'build' 13:45:52 INFO - no previously-included directories found matching 'dist' 13:45:52 INFO - no previously-included directories found matching '.git*' 13:45:52 INFO - Successfully installed jsonschema functools32 13:45:52 INFO - Cleaning up... 13:45:52 INFO - Return code: 0 13:45:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:45:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:52 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 0x101098a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10112d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8203489120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ffcd30>, '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 0x100d61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4b20>, '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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 13:45:52 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 13:45:52 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 13:45:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDoPXS6tfB/Render', 13:45:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:45:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:45:52 INFO - 'HOME': '/Users/cltbld', 13:45:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:45:52 INFO - 'LOGNAME': 'cltbld', 13:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:52 INFO - 'MOZ_NO_REMOTE': '1', 13:45:52 INFO - 'NO_EM_RESTART': '1', 13:45:52 INFO - 'PAGER': '/bin/cat', 13:45:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:52 INFO - 'PWD': '/builds/slave/test', 13:45:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:45:52 INFO - 'SHELL': '/bin/bash', 13:45:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oUm1HL0bdc/Listeners', 13:45:52 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 13:45:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:45:52 INFO - 'USER': 'cltbld', 13:45:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:45:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:52 INFO - 'XPC_FLAGS': '0x0', 13:45:52 INFO - 'XPC_SERVICE_NAME': '0', 13:45:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:45:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:52 INFO - Downloading/unpacking blobuploader==1.2.4 13:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:45:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:45:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:45:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:45:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:45:54 INFO - Downloading blobuploader-1.2.4.tar.gz 13:45:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:45:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:45:55 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 13:45:55 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 13:45:55 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 13:45:55 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 13:45:55 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 13:45:55 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 13:45:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:45:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:45:55 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 13:45:55 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 13:45:55 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 13:45:55 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 13:45:55 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 13:45:55 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 13:45:56 INFO - Downloading docopt-0.6.1.tar.gz 13:45:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:45:56 INFO - Installing collected packages: blobuploader, requests, docopt 13:45:56 INFO - Running setup.py install for blobuploader 13:45:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:45:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:45:56 INFO - Running setup.py install for requests 13:45:56 INFO - Running setup.py install for docopt 13:45:57 INFO - Successfully installed blobuploader requests docopt 13:45:57 INFO - Cleaning up... 13:45:57 INFO - Return code: 0 13:45:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:45:57 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')]} 13:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:57 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')]} 13:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:57 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 0x101098a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10112d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8203489120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ffcd30>, '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 0x100d61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4b20>, '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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 13:45:57 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 13:45:57 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 13:45:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDoPXS6tfB/Render', 13:45:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:45:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:45:57 INFO - 'HOME': '/Users/cltbld', 13:45:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:45:57 INFO - 'LOGNAME': 'cltbld', 13:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:57 INFO - 'MOZ_NO_REMOTE': '1', 13:45:57 INFO - 'NO_EM_RESTART': '1', 13:45:57 INFO - 'PAGER': '/bin/cat', 13:45:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:57 INFO - 'PWD': '/builds/slave/test', 13:45:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:45:57 INFO - 'SHELL': '/bin/bash', 13:45:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oUm1HL0bdc/Listeners', 13:45:57 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 13:45:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:45:57 INFO - 'USER': 'cltbld', 13:45:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:45:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:57 INFO - 'XPC_FLAGS': '0x0', 13:45:57 INFO - 'XPC_SERVICE_NAME': '0', 13:45:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:45:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:45:57 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-rh01jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:45:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:45:57 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-kqPlvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:45:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:45:57 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-_dzynt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:45:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:45:57 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-BMesNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:45:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:45:57 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-oH4Mp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:45:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:45:57 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-rRJ3nb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:45:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:45:57 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ogYgvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:45:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-cfAoAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:45:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-5_HBy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:45:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-LKuwFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:45:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-c2iucv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:45:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-QVo5HX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:45:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-c7Vi4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:45:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-YTz6Hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:45:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-YBUsCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:45:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:45:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-wggyZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:45:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-tLFhLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:45:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:45:59 INFO - Running setup.py install for manifestparser 13:45:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:45:59 INFO - Running setup.py install for mozcrash 13:45:59 INFO - Running setup.py install for mozdebug 13:45:59 INFO - Running setup.py install for mozdevice 13:45:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:45:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:45:59 INFO - Running setup.py install for mozfile 13:45:59 INFO - Running setup.py install for mozhttpd 13:45:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:46:00 INFO - Running setup.py install for mozinfo 13:46:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:46:00 INFO - Running setup.py install for mozInstall 13:46:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:46:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:46:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:46:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:46:00 INFO - Running setup.py install for mozleak 13:46:00 INFO - Running setup.py install for mozlog 13:46:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:46:00 INFO - Running setup.py install for moznetwork 13:46:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:46:00 INFO - Running setup.py install for mozprocess 13:46:00 INFO - Running setup.py install for mozprofile 13:46:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:46:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:46:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:46:01 INFO - Running setup.py install for mozrunner 13:46:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:46:01 INFO - Running setup.py install for mozscreenshot 13:46:01 INFO - Running setup.py install for moztest 13:46:01 INFO - Running setup.py install for mozversion 13:46:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:46:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:46:01 INFO - Cleaning up... 13:46:01 INFO - Return code: 0 13:46:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:46: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')]} 13:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46: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')]} 13:46:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:01 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 0x101098a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10112d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8203489120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ffcd30>, '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 0x100d61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4b20>, '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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 13:46:01 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 13:46:01 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 13:46:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDoPXS6tfB/Render', 13:46:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:01 INFO - 'HOME': '/Users/cltbld', 13:46:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:01 INFO - 'LOGNAME': 'cltbld', 13:46:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:01 INFO - 'MOZ_NO_REMOTE': '1', 13:46:01 INFO - 'NO_EM_RESTART': '1', 13:46:01 INFO - 'PAGER': '/bin/cat', 13:46:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:01 INFO - 'PWD': '/builds/slave/test', 13:46:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:01 INFO - 'SHELL': '/bin/bash', 13:46:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oUm1HL0bdc/Listeners', 13:46:01 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 13:46:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:01 INFO - 'USER': 'cltbld', 13:46:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:01 INFO - 'XPC_FLAGS': '0x0', 13:46:01 INFO - 'XPC_SERVICE_NAME': '0', 13:46:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:46:01 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-8uXQJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:46:02 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)) 13:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:46:02 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-uGs0em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:46:02 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)) 13:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:46:02 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-dnZgER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:46:02 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)) 13:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:46:02 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-_Jzc4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:46:02 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)) 13:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:46:02 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-hajiHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:46:02 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)) 13:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:46:02 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-NhKC7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:46:02 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)) 13:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:46:02 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-3zbNBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:46:02 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)) 13:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:46:02 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-gbTtju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:46:02 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)) 13:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:46:02 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-97e6Jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:46:02 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)) 13:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:46:02 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-CSDRcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:46:03 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)) 13:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:46:03 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-YoOe5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:46:03 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)) 13:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:46:03 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-_80YCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:46:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:46:03 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-yj_0qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:46:03 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)) 13:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:46:03 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-AWCSaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:46:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:46:03 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-8eTDrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:46:03 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)) 13:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:46:03 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-PWF2zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:46:03 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)) 13:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:46:03 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-x5RJIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:46:03 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)) 13:46:03 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)) 13:46:03 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)) 13:46:03 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)) 13:46:03 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)) 13:46:03 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)) 13:46:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:46:03 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 13:46:03 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 13:46:03 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 13:46:03 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 13:46:03 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 13:46:03 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 13:46:06 INFO - Downloading blessings-1.6.tar.gz 13:46:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:46:06 INFO - Installing collected packages: blessings 13:46:06 INFO - Running setup.py install for blessings 13:46:06 INFO - Successfully installed blessings 13:46:06 INFO - Cleaning up... 13:46:06 INFO - Return code: 0 13:46:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:46:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:46:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:46:06 INFO - Reading from file tmpfile_stdout 13:46:06 INFO - Current package versions: 13:46:06 INFO - blessings == 1.6 13:46:06 INFO - blobuploader == 1.2.4 13:46:06 INFO - docopt == 0.6.1 13:46:06 INFO - functools32 == 3.2.3-2 13:46:06 INFO - jsonschema == 2.5.1 13:46:06 INFO - manifestparser == 1.1 13:46:06 INFO - mozInstall == 1.12 13:46:06 INFO - mozcrash == 0.17 13:46:06 INFO - mozdebug == 0.1 13:46:06 INFO - mozdevice == 0.48 13:46:06 INFO - mozfile == 1.2 13:46:06 INFO - mozhttpd == 0.7 13:46:06 INFO - mozinfo == 0.9 13:46:06 INFO - mozleak == 0.1 13:46:06 INFO - mozlog == 3.2 13:46:06 INFO - moznetwork == 0.27 13:46:06 INFO - mozprocess == 0.23 13:46:06 INFO - mozprofile == 0.28 13:46:06 INFO - mozrunner == 6.12 13:46:06 INFO - mozscreenshot == 0.1 13:46:06 INFO - mozsystemmonitor == 0.3 13:46:06 INFO - moztest == 0.7 13:46:06 INFO - mozversion == 1.4 13:46:06 INFO - psutil == 3.1.1 13:46:06 INFO - requests == 1.2.3 13:46:06 INFO - wsgiref == 0.1.2 13:46:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:46:06 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')]} 13:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:06 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')]} 13:46:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46:06 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 0x101098a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10112d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8203489120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ffcd30>, '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 0x100d61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4b20>, '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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 13:46:06 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 13:46:06 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 13:46:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDoPXS6tfB/Render', 13:46:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:06 INFO - 'HOME': '/Users/cltbld', 13:46:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:06 INFO - 'LOGNAME': 'cltbld', 13:46:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:06 INFO - 'MOZ_NO_REMOTE': '1', 13:46:06 INFO - 'NO_EM_RESTART': '1', 13:46:06 INFO - 'PAGER': '/bin/cat', 13:46:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:06 INFO - 'PWD': '/builds/slave/test', 13:46:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:06 INFO - 'SHELL': '/bin/bash', 13:46:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oUm1HL0bdc/Listeners', 13:46:06 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 13:46:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:06 INFO - 'USER': 'cltbld', 13:46:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:06 INFO - 'XPC_FLAGS': '0x0', 13:46:06 INFO - 'XPC_SERVICE_NAME': '0', 13:46:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:07 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)) 13:46:07 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)) 13:46:07 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)) 13:46:07 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)) 13:46:07 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)) 13:46:07 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)) 13:46:07 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)) 13:46:07 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)) 13:46:07 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)) 13:46:07 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)) 13:46:07 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)) 13:46:07 INFO - Cleaning up... 13:46:07 INFO - Return code: 0 13:46:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:46: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')]} 13:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:46:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46: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')]} 13:46:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:46:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:46: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101098a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10112d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8203489120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ffcd30>, '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 0x100d61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4b20>, '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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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 13:46: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'] in /builds/slave/test/build 13:46: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 13:46:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDoPXS6tfB/Render', 13:46:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:07 INFO - 'HOME': '/Users/cltbld', 13:46:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:07 INFO - 'LOGNAME': 'cltbld', 13:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:07 INFO - 'MOZ_NO_REMOTE': '1', 13:46:07 INFO - 'NO_EM_RESTART': '1', 13:46:07 INFO - 'PAGER': '/bin/cat', 13:46:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:07 INFO - 'PWD': '/builds/slave/test', 13:46:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:07 INFO - 'SHELL': '/bin/bash', 13:46:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oUm1HL0bdc/Listeners', 13:46:07 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 13:46:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:07 INFO - 'USER': 'cltbld', 13:46:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:07 INFO - 'XPC_FLAGS': '0x0', 13:46:07 INFO - 'XPC_SERVICE_NAME': '0', 13:46:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:46:07 INFO - Cleaning up... 13:46:07 INFO - Return code: 0 13:46:07 INFO - Running post-action listener: _resource_record_post_action 13:46:07 INFO - Running post-action listener: _start_resource_monitoring 13:46:07 INFO - Starting resource monitoring. 13:46:07 INFO - ##### 13:46:07 INFO - ##### Finished create-virtualenv step (success) 13:46:07 INFO - ##### 13:46:07 INFO - ##### 13:46:07 INFO - ##### Running install step. 13:46:07 INFO - ##### 13:46:07 INFO - Running pre-action listener: _resource_record_pre_action 13:46:07 INFO - Running main action method: install 13:46:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:46:07 INFO - Reading from file tmpfile_stdout 13:46:07 INFO - Detecting whether we're running mozinstall >=1.0... 13:46:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:46:07 INFO - Reading from file tmpfile_stdout 13:46:07 INFO - Output received: 13:46:07 INFO - Usage: mozinstall [options] installer 13:46:07 INFO - Options: 13:46:07 INFO - -h, --help show this help message and exit 13:46:07 INFO - -d DEST, --destination=DEST 13:46:07 INFO - Directory to install application into. [default: 13:46:07 INFO - "/builds/slave/test"] 13:46:07 INFO - --app=APP Application being installed. [default: firefox] 13:46:07 INFO - mkdir: /builds/slave/test/build/application 13:46:07 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'] 13:46:07 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 13:46:42 INFO - Reading from file tmpfile_stdout 13:46:42 INFO - Output received: 13:46:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:46:42 INFO - Running post-action listener: _resource_record_post_action 13:46:42 INFO - ##### 13:46:42 INFO - ##### Finished install step (success) 13:46:42 INFO - ##### 13:46:42 INFO - ##### 13:46:42 INFO - ##### Running run-tests step. 13:46:42 INFO - ##### 13:46:42 INFO - Running pre-action listener: _resource_record_pre_action 13:46:42 INFO - Running main action method: run_tests 13:46:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:46:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:46:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:46:42 INFO - 2016-07-07 13:46:42.426 screenresolution[1636:7342] starting screenresolution argv=screenresolution get 13:46:42 INFO - 2016-07-07 13:46:42.428 screenresolution[1636:7342] Display 0: 1600x1200x32@60 13:46:42 INFO - 2016-07-07 13:46:42.434 screenresolution[1637:7344] starting screenresolution argv=screenresolution list 13:46:42 INFO - Available Modes on Display 0 13:46:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:46:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:46:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:46:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:46:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:46:42 INFO - Intel Iris: 13:46:42 INFO - Chipset Model: Intel Iris 13:46:42 INFO - Type: GPU 13:46:42 INFO - Bus: Built-In 13:46:42 INFO - VRAM (Dynamic, Max): 1536 MB 13:46:42 INFO - Vendor: Intel (0x8086) 13:46:42 INFO - Device ID: 0x0a2e 13:46:42 INFO - Revision ID: 0x0009 13:46:42 INFO - Displays: 13:46:42 INFO - Display: 13:46:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:46:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:46:42 INFO - Display Serial Number: 1600x1200 60 13:46:42 INFO - Main Display: Yes 13:46:42 INFO - Mirror: Off 13:46:42 INFO - Online: Yes 13:46:42 INFO - Rotation: Supported 13:46:42 INFO - Return code: 0 13:46:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:46:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:46:42 INFO - Python 2.7.3 13:46:42 INFO - Return code: 0 13:46:42 INFO - grabbing minidump binary from tooltool 13:46: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')]} 13:46:42 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 0x100ffcd30>, '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 0x100d61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ff4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:46:42 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 13:46:42 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 13:46:42 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 13:46:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:46:42 INFO - Return code: 0 13:46:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:46:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:46:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:46:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:46:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:46:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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 13:46:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0095 --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 13:46:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iDoPXS6tfB/Render', 13:46:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:46:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:46:42 INFO - 'HOME': '/Users/cltbld', 13:46:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:46:42 INFO - 'LOGNAME': 'cltbld', 13:46:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:46:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:46:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:46:42 INFO - 'MOZ_NO_REMOTE': '1', 13:46:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:46:42 INFO - 'NO_EM_RESTART': '1', 13:46:42 INFO - 'PAGER': '/bin/cat', 13:46:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:46:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:46:42 INFO - 'PWD': '/builds/slave/test', 13:46:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:46:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:46:42 INFO - 'SHELL': '/bin/bash', 13:46:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oUm1HL0bdc/Listeners', 13:46:42 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 13:46:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:46:42 INFO - 'USER': 'cltbld', 13:46:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:46:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:46:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:46:42 INFO - 'XPC_FLAGS': '0x0', 13:46:42 INFO - 'XPC_SERVICE_NAME': '0', 13:46:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:46:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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 13:46:43 INFO - mozversion application_buildid: 20160707112723 13:46:43 INFO - mozversion application_changeset: 94cce4e79310565ff5b169f890842499ea713f8a 13:46:43 INFO - mozversion application_display_name: Nightly 13:46:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:46:43 INFO - mozversion application_name: Firefox 13:46:43 INFO - mozversion application_remotingname: firefox 13:46:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:46:43 INFO - mozversion application_vendor: Mozilla 13:46:43 INFO - mozversion application_version: 50.0a1 13:46:43 INFO - mozversion platform_buildid: 20160707112723 13:46:43 INFO - mozversion platform_changeset: 94cce4e79310565ff5b169f890842499ea713f8a 13:46:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:46:43 INFO - mozversion platform_version: 50.0a1 13:46:43 INFO - using testdate: 1467924403 13:46:43 INFO - actual date: 1467924403 13:46:43 INFO - starting webserver on 'localhost:49266' 13:46:43 INFO - SUITE-START | Running 2 tests 13:46:43 INFO - TEST-START | dromaeo_css 13:46:43 INFO - operating with platform_type : mac_ 13:46:43 INFO - Initialising browser for dromaeo_css test... 13:46:43 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpAKcyEW/profile http://localhost:49266/getInfo.html) 13:46:45 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 13:46:45 INFO - PROCESS | 1647 | colorDepth:24 13:46:45 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 13:46:45 INFO - PROCESS | 1647 | __metrics 13:46:45 INFO - PROCESS | 1647 | 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" 13:46:45 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:46:46 INFO - TEST-INFO | 1647: exit 0 13:46:46 INFO - Browser initialized. 13:46:46 INFO - Running cycle 1/1 for dromaeo_css test... 13:46:46 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpAKcyEW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:46:58 INFO - PROCESS | 1649 | RSS: Main: 243593216 13:46:58 INFO - PROCESS | 1649 | 13:47:49 INFO - PROCESS | 1649 | 1467924469241 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] 13:49:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 13:50:00 INFO - PROCESS | 1649 | RSS: Main: 362143744 13:50:00 INFO - PROCESS | 1649 | 13:53:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 13:53:04 INFO - PROCESS | 1649 | RSS: Main: 380973056 13:53:04 INFO - PROCESS | 1649 | 13:56:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 13:56:07 INFO - PROCESS | 1649 | RSS: Main: 370991104 13:56:07 INFO - PROCESS | 1649 | 13:59:09 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 13:59:10 INFO - PROCESS | 1649 | RSS: Main: 368238592 13:59:10 INFO - PROCESS | 1649 | 14:02:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 14:02:14 INFO - PROCESS | 1649 | RSS: Main: 339750912 14:02:14 INFO - PROCESS | 1649 | 14:05:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 14:05:17 INFO - PROCESS | 1649 | RSS: Main: 382840832 14:05:17 INFO - PROCESS | 1649 | 14:05:17 INFO - PROCESS | 1649 | __start_tp_report 14:05:17 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 14:05:17 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 14:05:17 INFO - PROCESS | 1649 | |i|pagename|runs| 14:05:17 INFO - PROCESS | 1649 | |0;dojo.html;2583.5866261398173;2694.9595756063654;3191.473406887864;3338.4324664806995;3401.0307062487195;5250.816221432246;5509.338879334476;5628.127640215763;5638.013347664158;5642.7178641067985;4713.717176969376;5623.550115990721;5632.971835140818;5710.057840456319;5740.7129643517865;5101.566366858821;5133.640645154841;5141.305923700309;5159.38087429509;5332.893342133155;5256.9474305256845;5282.339707536558;5286.233496143076;5289.391719952197;5320.600954928376;5095.0574143783515;5141.100307446182;5164.6384753067305;5171.0692075426405;5198.480151984809;5120.92318615221;5123.846284611431;5124.92312615311;5131.589472842164;5181.53366197044;5102.158143906251;5118.104331741967;5129.9230511542355;5141.691498510064;5192.844214673566;5544.390117087117;5622.353429355595;5674.858128546819;5724.427557244284;5729.427057294279;6122.977604208165;6162.506999440079;6185.195924529782;6185.474234690057;6217.626942383471;6172.7530898764;6179.134921111048;6184.752609895644;6187.659678717669;6253.280872699606;5643.040683083804;5681.147827825776;5825.766969321265;5829.941700583025;5839.941600583982;6186.350433204539;6240.7503699852405;6256.655883926428;6260.530460215411;6300.9684951574945;6105.8168254952425;6110.236220472441;6114.960456722368;6126.908096378558;6131.172291740558;6153.569250152446;6200.441960223601;6229.0967809667345;6248.375162483806;6249.875002499924;6061.212042434543;6108.144859719643;6114.449699526975;6134.325224225332;6157.538184636171;6189.319174890758;6190.3190649029475;6192;6194.318624951252;6207.875842483216;4878.243872199815;4911.705297682151;4913.410390753061;4914.483979182206;4957.934044180519;4072.3687828386646;4235.983363992619;4347.7173983690955;4357.281048627005;4429.601335879722;5608.046632072557;5612.186232996274;5615.6630602164005;5630.071038278638;5644.74598643062;6097.817065487954;6188.412100850502;6217.160683307786;6223.751049958041;6230.906536401958;5562.9443705563235;5571.247881536022;5606.21512988182;5641.012822756002;5653.067243904792;5325.041492531382;5326.201069839478;5334.653247538898;5337.919931201035;5344.946550534522;6235.594686345373;6319.9052014218905;6320.778772742932;6338.714757836001;6351.491880649537;6165.5375846810775;6229.688515574294;6230.065490176328;6238.906416403667;6259.998400255937;6308.148399965946;6309.652969086745;6320.810375688737;6331.113644089739;6337.556371053982;5954.553408494467;6036.3058248302;6065.090236464565;6144.754209831557;6153.753849845956;5067.23991401278;6133.723982420891;6186.319504854372;6195.318514963441;6208.379162083756;5885.234919460443;5925.792597259076;5928.229330187048;5940.16837642739;5956.910646340221;5823.70881455934;5866.7066646668345;5897.882042359214;5908.497777688773;5939.762409503572;4810.615150787999;5006.824761133343;5754.971225144015;5789.507891829242;5818;5896.17453556511;5897.3807750187325;5908.350081490947;5929.407059294036;5959.195508606283;5704.543636509153;5764.855878603068;5775.451332123442;5815.82552523425;5889.705514724161 14:05:17 INFO - PROCESS | 1649 | |1;ext.html;532.2362409941734;538.7225578826902;539.409346765292;541.9539339156169;552.8645481856946;30308.848455757718;40908.59091409085;41966.58033419669;42140.57859421405;44390;43315.566844331544;43633.90915227121;43637.56362436375;43659;43909.78045109778;8440.28257598105;8693.391462597621;9638.180754635881;9823.852642210373;9825.361351512129;8714.825703485927;8884.466931984069;8889.555522223864;8899.68851090212;11777.705557361049;11583.18917675763;11890.7621847563;11902.880971190263;11904.464299106518;11906.880931190708;11807.291562506278;11936.16446848721;11937.761244775056;11941.402929853559;11947.641570752892;7377.55734655917;8882.555872206429;8898.644054237822;8981.595828187747;11780.234284771463;39765.80117099425;40362.798186009175;40393.192136157406;42452.787736061124;42480.57519424797;3446.758726889119;3453.8963831085107;3493.7030352420074;3503.299340131958;3592.263585964871;31243.84378078118;31569;31605.20986975321;31965.52051719226;32554.511682324784;2920.8101473404163;3207.3905957867933;3251.154699778051;3271.7382609391193;3273.3289675616443;524267;527744;528441;528691;532641;50566;51525.48474515282;51632.22551661728;58990.70504647535;59291;53088.203676944875;60787;61441;61681;64414.677926610064;16015.279312430968;16262.75605865913;16287.51137465878;16298.592535186715;16339.59151021234;12590.874091258971;12835.165714228546;12852.742945141044;12858.678533036562;12860.549880754128;13151.53969611059;13632.863671363359;13930.164190148624;15254.542363729106;15341.769873451907;6695.430888374445;6707.731690732414;6739;6762.120924279812;6800.183977922681;953.7663272498144;1023.6673081248624;1058.1323314881722;1081.3185496552935;1107.645553422897;30375.088747337617;30491.8475407626;30581.847090764404;30586.69413305883;31104.844475777478;992.6972273456665;1065.350136416785;1078.5038882114159;1084.7017070305571;1103.0293341859274;15788.763168552481;15797.921010395105;15981.520554383387;16125.274362653709;16174.514764557081;693694;698394;698554;698625.5068724623;700057;59535.702321488985;59824;59875;59913.801723965276;59919.700401497714;1283.9293838838953;1402.1797248609473;1418.2058047493438;1422.025912250112;1437.23826372023;31260.843695782285;31497.527537086502;31600.73597056092;32132.19669508281;32234.83882580666;48962;49167.754161228964;49544.256836148204;49726.502734973634;49838.750806245735;568788;572507;572718;573557;573877;26311;26723.33191670224;26752.33119172036;27544.760485777788;38599.807000964815;39045.02387440337;48217.276740849615;48740;48822;49167.50832491629;39899.40150897797;39947.40078898761;40555.79722101371;41374;41403.58596414118;38947.6105238944;39400.211995760495;39535.60464395319;40878;40939.59060409477;33578.3284334307;38979.610203897595;39324;39339.80330098331;39396;33468.832655836566;34719.4792078114;35441.82279088588;35596.82201588975;35608.82195589005;24455.51088978246;25851.870740646176;25895.61156582615;26113.608295875198;26693.599596005686 14:05:17 INFO - PROCESS | 1649 | |2;jquery.html;351.4552443712247;356.8233724306469;378.77462909568806;387.58145188609683;389.35172937059815;13520.864791352096;15930.442434514795;20600;21196.470088247803;21260.681089783626;20354.592908141865;21270;21593.352199434004;21991.79045152516;22001.889990550066;5061.924071138936;5468.070428027225;5643.4074422185695;5656.490915817576;5669.0646043402785;5537.224788529609;5549.72251387429;5577.637453565526;5578.581606379517;5593.244911936898;5432.6468779529405;5575.275214222145;5575.275214222166;5578.9163162552395;5582.246396736449;5558.555315574744;5571.247881535981;5588.469095435927;5588.469095435927;5598.944010559883;4682.742449165295;5090.567301779353;5425;5439.347278326587;5443.863903402407;13204.735905281937;13218.471261149543;13280.601581952556;13364.131331463424;13443.395047222899;2297.3222899244597;2317.3395582258977;2338.368640467081;2407.1574948767966;2413.4207790130245;10049.497525123714;10058.396496210178;10515.684529464128;13874.375653095634;14066.226357550331;1524.6645737937636;1858.5632376391536;1888.9149988250424;1910.7133929910485;1919;81991.12456568905;112532.43733781361;120533.39733301365;122489;134260.32869835687;10904.672859814218;11652.242604230698;11698.824517632242;11710.765784684429;11867.58463453775;12379.814302785466;14535.345909434101;14553.636159095895;14698.926505367404;14734.631634209016;8550.358723095793;8624.611892464854;8694.304455643532;8700.434471759316;8706.564671766386;8627.094155113748;8648.610812513452;8648.610812513452;8739.956300218586;8759.474431534129;4481.865544033684;4541.364209010741;4555.088982203572;4599.310103484504;4625.791839367169;1771.1852547828144;1821.6174603333184;1911.5794525204433;1915.1190452392057;1949.502876766444;881.8809460722848;887.1394747095308;926.8563372677246;972.9562169702381;1050.6112738286884;21263;22149.44626384321;22190.226221722285;22264.332070037926;22340;943.5706753427312;952.1383148250844;994.3487016004528;1008.4403156248206;1030.4899074957943;8313.293370063613;8752.518611476302;10409.895901041044;10425.592545006328;11573.942130289295;555646;597362;600494;618557;625975;101146;129348.35325823311;133413;141574;146629;962.31675510387;970.5292932927524;983.1201075037801;992.7071513903271;993.3642468819961;14445.855541444871;19798.50503737418;20078.598428031648;20102.09540570707;20206.79793202108;54910.45089549053;55005.449945501634;56100.71949640226;57314.71342643427;57863.13205302008;69044.30955690378;80101.1989880117;86225.56887215524;86492.54029838988;90847;7038.648067596702;7349.853002940018;7381.188069312264;7662.961685191538;8317.667293308199;9237.307201959746;12252.693682658004;12998.415071322002;13275.336233188495;13311.80032299497;11082.722931926766;11247.662570122582;11328.433578320886;11418.257813242113;11423.543058277575;9740.659076932558;10830.566777328819;10889.455527223448;10963.671089867317;11327.943360283145;10318.587256509656;11347.943260283646;11360.659180224606;11576.363300018244;11689;7344.669489872862;7345.412367010519;7422.628868556659;7425.257474252531;7632;8172.305354044973;10849.186311026546;11152.776944461228;11162.665120046411;11229.101671866069 14:05:17 INFO - PROCESS | 1649 | |3;mootools.html;1493.3951749541434;1510.7884896114547;1723.164265331315;1885.2647467487673;1907.5707965707718;7871.134175240726;8253.257206851382;8708.43395179313;8917.554122293875;8925.330600204978;7789.104253010905;8022.398320125984;8435.536045517494;8675.26260267878;8707.695230666928;2356.104680221518;2381.4760752634393;2382.737898831127;2382.940426489343;2384.2489615771065;2344.6717459555675;2351.459164392182;2383.9642405363934;2384.59461891478;2384.618461046233;1909.9904500477478;2080.1367432515563;2180.923667671632;2368.9052437902465;2369.052379048377;1960.696092105726;2353.7410884802657;2380.773826486481;2385.296337580418;2394.401399650096;2063.123172651617;2291.186628746804;2332.988335058331;2349.7767712067325;2359.7168339799164;8823.647054117828;8888.866667;8891.888513935757;8897.243734282594;8898.154675305836;5141.974290128563;5350.197470379474;5361.597880159004;5369.006733754268;5371.919421208685;3566.6651680678647;3570.535830342065;3641.089727568108;3641.6176301488226;3650.5801832789302;929.9116583924584;1055.6463584699136;1085.1752667972398;1089.0634054712941;1110.3559935237543;11441.942790285995;11452.885471145266;11454.255473394203;11456.828147577793;11513.769724605461;3908.7459315144993;4585.2663573828695;4704.506026867198;4719.834805781781;4767.666263361601;9847.212223022141;9860.605575776888;9866.851997220047;9876.80246395068;9881.209503239723;5351.464853514695;5358.571314294847;5361.303030605996;5389.137737961985;5392.272043274108;4748.786304616231;4930.852074437701;4935.580475659609;4947.48051454592;4953.380827396575;3812.6759225466144;3824.8007395747554;3859.536855577349;3882.9223415532097;3916.373380259145;1102.2835157147758;1174.682835634374;1178.0987544528443;1189.108168873345;1190.1252579354168;727.7780277015455;908.7637214324324;919.9126083022102;959.69289827256;969.9233850426021;5895.587308888336;5935.881282374328;5970.194023806817;6050.394960503914;6186.690665466709;920.9363185520734;954.5847556312888;979.1970584121095;980.3921568627496;983.537737263485;3546.9822650887095;4011.157656892027;4037.1320166163828;4059.0258337998666;4104.240715467648;10337;10647.201459890539;10652.094571961316;10653.360798352123;10667.83998240027;10442.738931526621;10528.315659482108;10620.840687389695;10633.627823026158;10640.733981650521;7357.3378395943555;7881.448298619041;8117.756467305991;8184.549849758201;8303.584820759059;5986.491148252448;6059.135017857352;6106.32830388648;6222.719977601109;6300.117983482408;9525.61897524119;10077.596896124367;10084.84872726895;10093.545790439595;10106.343087699275;10064.345817521838;10180.338278011906;10211.387316761018;10226.6420675276;10238.590456381959;7412;7431.442641801995;7515.286047825448;7595.810104747426;7607.35337496319;8196.016478022437;8242;8319.292860106958;8325.583720814057;8333.000039995239;7430.591317477591;7533.54798712079;7541.283578060075;7681.731139410093;7682.347000505019;7371.48399612022;7391.482596218429;7431.554106753612;7453.925460745323;7499.812504687427;6894.551854129466;6902.620355880475;6941.409980151744;6950.096487456598;6955.339242771928;4849.612031037438;4890.535399137077;4963.1636294571235;5030.0191462664425;5054.140796064751;5000.549950504472;5070.949290507047;5082.059818933509;5098.362704661918;5199.116150254541 14:05:17 INFO - PROCESS | 1649 | |4;prototype.html;292.25620795076526;300.631726135484;306.16264516547255;307.5171979991413;308.56184218409857;4126.112885729567;4571.834182283517;5141.460146684601;5332.200169974501;5366.382865970415;5139.614528910328;5255.632105752599;5278.392984806748;5293.497117773815;5294.920576191351;3432.399330117232;3540.982295088521;3621.438677005062;3914.412838074283;4029.697772667047;3070.4319700855413;3221.194701324669;4091.3249313863175;4104.445899803518;4143.502779666429;3357.4628059510483;3518.6833185013343;3529.3823580873373;4035.244668569167;4050.7164498485117;4033.334499807528;4045.59544045596;4055.1078762672164;4056.594340565949;4065.3698676705308;3534.575850897909;3541.3625547401457;3547.219611685425;3554.306910152521;3562.4300111982216;4214.936775948363;4243.490781106287;4775.546323099335;4908.877278068041;5147.356580427447;3636.8545258189906;3861.8841434757;4588.770561471946;4642.489326174118;4652.720836749806;4323.589259020388;5507.256520369779;5531.889362212733;5539.556835453154;5569.693666848321;3412.675795799395;3466.6600044969546;3752.3245815753153;3896.383001054559;3904.726669133162;5854.18041474194;5860.531157507389;5878.294604647427;5890.617109887844;5913.113033045035;3317.701406873393;3333.449980753149;3511.7717348372275;3966.5438474575217;4028.395740638869;3229.370272796782;3826.9808650956566;4013.478247827822;4035.6569691575955;4078.6336577246952;3872.1094148345474;4597.95402045982;4609.7695115244105;4667.556582124761;4669.836555720533;4619.5611416915335;5115.7442127893455;5613.410591887793;5678.68767217807;5684.431556844283;1284.5825106840314;1329.6409969308427;1329.734053189366;1333.8599447058114;1343.6362092476145;824.8721448175543;852.4373913217247;856.8218699288478;871.020102384817;874.9282129391445;3804.6766024887643;4427.579379958899;4429.911401771947;4450.46594408673;4458.331250312479;3968.7817170055923;4364.236258654722;4528.705634133771;4774.641901857374;4876.609871210295;3574.890814419505;3881.4760007399204;4211.126048908206;4391.7584532851;4398.912021759547;2054.7123402723632;2161.870287782738;2372.4579023634774;2391.5934291170543;2535.733747956475;4196.852360729453;4358.6730995175485;4481.619062379669;4886.682365646293;4906.852794416173;4069.7558146511305;4299.4744309665775;4687.29690546414;4765.9523404765505;4810.39870016248;3851.669739807432;4117.629413352813;4703.764811759398;4954.432808377962;5005.499450054966;3923.843046278117;4161.646260067928;4726.794667359802;4830.154722923545;4864.854054378375;4422.646188304863;4605.30920361935;4622.098690755271;4746.359241502353;4860.594005967069;4608;4890.731009794496;4919.852404427873;4944.9368385797225;4978.27814966835;3043.8388743992014;3270.6565810590496;3451.637578054268;3466.826658667006;3492.9301413972084;3772.358699021118;4920.45874953762;4933.605311574994;4946.233333833262;5533.474319939599;4849.58778503817;4899.485554016919;5238.319018527567;5416.7020813855615;5611;4615.769211539477;5485.54410721243;5549.667019978814;5550.916736248879;5582;4892.724882929455;4961.08219979305;5307.336582927134;5450.5367043801725;5468.726563671721;4603.3785438965315;4633.4208223972;4861.295112208639;5287.629865909349;5593.664380137205;5466.863328416822;5478.835634930959;5486.341639003185;5488.69812160335;5520.834374968757 14:05:17 INFO - PROCESS | 1649 | |5;yui.html;744.3858816476406;756.8675481790685;786.5870418030534;850.6044689219514;866.69665617034;14891.925540372296;16015.91992040041;16033;16042.59893502663;16050.27773750181;14575.489857854951;14724.411023559072;14734.557963261093;14744.705105897876;14749.77875331871;693.5180049865346;721.7473884140561;853.2576658307281;879.3229213505597;881.5592203898051;859.4757198109148;873.6112429968666;875.5709702245917;876.5441970175505;877.3419935048713;924.8427767279545;925.0749250749251;925.9351845370827;925.995295104812;928.0209379105041;685.2120061928822;710.3074502360183;897.4077109108023;920.2867777472445;924.1820988425225;679.6126208061407;680.7651360280745;681.1247546902256;681.6727970574102;682.3381320119477;14944.476943307041;14977.850221497754;14978.400863965428;14980.700385992219;14995;14092.647683807885;14199.929000354932;14206.573802785933;14225.715485690333;14345.42618295267;12662.556810511587;12703.682407939783;12714;12983.221006739626;13214.933925330311;4510.639148868083;4539.886502837422;4572.634189264851;4659;4762.952370476286;86339;116269;116929.41535292269;118471;119908;10802.297850639683;11003.064739497073;11058.281211721212;11307.265027773334;11331.490082946288;21359.89320053421;21947.122115115537;21958.01188946501;21965.560688786456;21977.890110549346;6515.804525864231;7892.921070789219;8002.159773223844;8069.919300806917;8148.714794982147;4363.170997510463;4406.559344065632;4485.506594274627;4600.769961501978;4669.112868554964;3397.3205358928312;3471.323091997087;3509.771864828778;3514.2444374459615;3517.4547945068557;541.3044238153954;639.7249182851325;650.2489624483729;662.6454846657111;709.0994437064899;568.3322096536554;631.6841579210395;674.430106559956;686.629220221085;688.4870771275432;8424.452410593414;9033.96109447422;9310.906890931004;9564.18704410119;9810.80378392428;618.8890940761344;666.4435196610788;671.701093013614;695.6660802814678;696.8292768271806;4668.626509879202;4680.4149481314835;4692;4726.669133160645;4732.1718699227495;113202.43398782953;124894;168820.1558992197;176363;180961;108342;110684.44657776659;149582.25208874105;150207;152326;69031.65484172547;81056.35246413808;102823.97176028293;105402.94597054238;112955;9656.37233579815;10048.045435683598;10195.949020255148;10216.335938163995;10230.590776368861;60645.69677151586;61372.07941880965;61978.38021619907;62626;63148.684256578425;86669.56665216634;91202.08797911936;92340.53829730809;92770;94343;13250.87367573767;13494.93252533731;13564.864351356755;13630.1140425872;14081.472582678593;15133.697326053638;15307.704884731313;15588.37646494173;15984.600384990468;16057.277422516248;14071.648208794863;14227.07524010936;14281.42874285017;14285.857141428452;14857.777133342792;13910;14370.209638469985;14396.352164152848;14854.374714606138;14954.252287385805;11749.353785541878;13704.794428083387;13955.790663140264;14419.711605768036;14586.635334116307;11409.885901140882;11586.536538538365;11693.941530292293;11909.464074116859;11927.284362938251;9147.908520914973;10823.194266675124;10938.617148399768;11766.411679415824;11781.823272650745 14:05:17 INFO - PROCESS | 1649 | __end_tp_report 14:05:17 INFO - PROCESS | 1649 | __start_cc_report 14:05:17 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1189 14:05:17 INFO - PROCESS | 1649 | __end_cc_report 14:05:17 INFO - PROCESS | 1649 | __startTimestamp1467925517783__endTimestamp 14:05:17 INFO - PROCESS | 1649 | 14:05:17 INFO - PROCESS | 1649 | ------- Summary: start ------- 14:05:17 INFO - PROCESS | 1649 | Number of tests: 6 14:05:17 INFO - PROCESS | 1649 | 14:05:17 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5649.71 Median:5823.71 stddev:682.15 (11.7%) stddev-sans-first:640.44 14:05:17 INFO - PROCESS | 1649 | Values: 2583.6 2695.0 3191.5 3338.4 3401.0 5250.8 5509.3 5628.1 5638.0 5642.7 4713.7 5623.6 5633.0 5710.1 5740.7 5101.6 5133.6 5141.3 5159.4 5332.9 5256.9 5282.3 5286.2 5289.4 5320.6 5095.1 5141.1 5164.6 5171.1 5198.5 5120.9 5123.8 5124.9 5131.6 5181.5 5102.2 5118.1 5129.9 5141.7 5192.8 5544.4 5622.4 5674.9 5724.4 5729.4 6123.0 6162.5 6185.2 6185.5 6217.6 6172.8 6179.1 6184.8 6187.7 6253.3 5643.0 5681.1 5825.8 5829.9 5839.9 6186.4 6240.8 6256.7 6260.5 6301.0 6105.8 6110.2 6115.0 6126.9 6131.2 6153.6 6200.4 6229.1 6248.4 6249.9 6061.2 6108.1 6114.4 6134.3 6157.5 6189.3 6190.3 6192.0 6194.3 6207.9 4878.2 4911.7 4913.4 4914.5 4957.9 4072.4 4236.0 4347.7 4357.3 4429.6 5608.0 5612.2 5615.7 5630.1 5644.7 6097.8 6188.4 6217.2 6223.8 6230.9 5562.9 5571.2 5606.2 5641.0 5653.1 5325.0 5326.2 5334.7 5337.9 5344.9 6235.6 6319.9 6320.8 6338.7 6351.5 6165.5 6229.7 6230.1 6238.9 6260.0 6308.1 6309.7 6320.8 6331.1 6337.6 5954.6 6036.3 6065.1 6144.8 6153.8 5067.2 6133.7 6186.3 6195.3 6208.4 5885.2 5925.8 5928.2 5940.2 5956.9 5823.7 5866.7 5897.9 5908.5 5939.8 4810.6 5006.8 5755.0 5789.5 5818.0 5896.2 5897.4 5908.4 5929.4 5959.2 5704.5 5764.9 5775.5 5815.8 5889.7 14:05:17 INFO - PROCESS | 1649 | 14:05:17 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:72920.34 Median:39331.90 stddev:161546.04 (410.7%) stddev-sans-first:161907.80 14:05:17 INFO - PROCESS | 1649 | Values: 532.2 538.7 539.4 542.0 552.9 30308.8 40908.6 41966.6 42140.6 44390.0 43315.6 43633.9 43637.6 43659.0 43909.8 8440.3 8693.4 9638.2 9823.9 9825.4 8714.8 8884.5 8889.6 8899.7 11777.7 11583.2 11890.8 11902.9 11904.5 11906.9 11807.3 11936.2 11937.8 11941.4 11947.6 7377.6 8882.6 8898.6 8981.6 11780.2 39765.8 40362.8 40393.2 42452.8 42480.6 3446.8 3453.9 3493.7 3503.3 3592.3 31243.8 31569.0 31605.2 31965.5 32554.5 2920.8 3207.4 3251.2 3271.7 3273.3 524267.0 527744.0 528441.0 528691.0 532641.0 50566.0 51525.5 51632.2 58990.7 59291.0 53088.2 60787.0 61441.0 61681.0 64414.7 16015.3 16262.8 16287.5 16298.6 16339.6 12590.9 12835.2 12852.7 12858.7 12860.5 13151.5 13632.9 13930.2 15254.5 15341.8 6695.4 6707.7 6739.0 6762.1 6800.2 953.8 1023.7 1058.1 1081.3 1107.6 30375.1 30491.8 30581.8 30586.7 31104.8 992.7 1065.4 1078.5 1084.7 1103.0 15788.8 15797.9 15981.5 16125.3 16174.5 693694.0 698394.0 698554.0 698625.5 700057.0 59535.7 59824.0 59875.0 59913.8 59919.7 1283.9 1402.2 1418.2 1422.0 1437.2 31260.8 31497.5 31600.7 32132.2 32234.8 48962.0 49167.8 49544.3 49726.5 49838.8 568788.0 572507.0 572718.0 573557.0 573877.0 26311.0 26723.3 26752.3 27544.8 38599.8 39045.0 48217.3 48740.0 48822.0 49167.5 39899.4 39947.4 40555.8 41374.0 41403.6 38947.6 39400.2 39535.6 40878.0 40939.6 33578.3 38979.6 39324.0 39339.8 39396.0 33468.8 34719.5 35441.8 35596.8 35608.8 24455.5 25851.9 25895.6 26113.6 26693.6 14:05:17 INFO - PROCESS | 1649 | 14:05:17 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:34844.66 Median:2410.29 stddev:100362.44 (4163.9%) stddev-sans-first:100610.57 14:05:17 INFO - PROCESS | 1649 | Values: 351.5 356.8 378.8 387.6 389.4 13520.9 15930.4 20600.0 21196.5 21260.7 20354.6 21270.0 21593.4 21991.8 22001.9 5061.9 5468.1 5643.4 5656.5 5669.1 5537.2 5549.7 5577.6 5578.6 5593.2 5432.6 5575.3 5575.3 5578.9 5582.2 5558.6 5571.2 5588.5 5588.5 5598.9 4682.7 5090.6 5425.0 5439.3 5443.9 13204.7 13218.5 13280.6 13364.1 13443.4 2297.3 2317.3 2338.4 2407.2 2413.4 10049.5 10058.4 10515.7 13874.4 14066.2 1524.7 1858.6 1888.9 1910.7 1919.0 81991.1 112532.4 120533.4 122489.0 134260.3 10904.7 11652.2 11698.8 11710.8 11867.6 12379.8 14535.3 14553.6 14698.9 14734.6 8550.4 8624.6 8694.3 8700.4 8706.6 8627.1 8648.6 8648.6 8740.0 8759.5 4481.9 4541.4 4555.1 4599.3 4625.8 1771.2 1821.6 1911.6 1915.1 1949.5 881.9 887.1 926.9 973.0 1050.6 21263.0 22149.4 22190.2 22264.3 22340.0 943.6 952.1 994.3 1008.4 1030.5 8313.3 8752.5 10409.9 10425.6 11573.9 555646.0 597362.0 600494.0 618557.0 625975.0 101146.0 129348.4 133413.0 141574.0 146629.0 962.3 970.5 983.1 992.7 993.4 14445.9 19798.5 20078.6 20102.1 20206.8 54910.5 55005.4 56100.7 57314.7 57863.1 69044.3 80101.2 86225.6 86492.5 90847.0 7038.6 7349.9 7381.2 7663.0 8317.7 9237.3 12252.7 12998.4 13275.3 13311.8 11082.7 11247.7 11328.4 11418.3 11423.5 9740.7 10830.6 10889.5 10963.7 11327.9 10318.6 11347.9 11360.7 11576.4 11689.0 7344.7 7345.4 7422.6 7425.3 7632.0 8172.3 10849.2 11152.8 11162.7 11229.1 14:05:17 INFO - PROCESS | 1649 | 14:05:17 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5698.05 Median:4981.86 stddev:3176.83 (63.8%) stddev-sans-first:3170.02 14:05:17 INFO - PROCESS | 1649 | Values: 1493.4 1510.8 1723.2 1885.3 1907.6 7871.1 8253.3 8708.4 8917.6 8925.3 7789.1 8022.4 8435.5 8675.3 8707.7 2356.1 2381.5 2382.7 2382.9 2384.2 2344.7 2351.5 2384.0 2384.6 2384.6 1910.0 2080.1 2180.9 2368.9 2369.1 1960.7 2353.7 2380.8 2385.3 2394.4 2063.1 2291.2 2333.0 2349.8 2359.7 8823.6 8888.9 8891.9 8897.2 8898.2 5142.0 5350.2 5361.6 5369.0 5371.9 3566.7 3570.5 3641.1 3641.6 3650.6 929.9 1055.6 1085.2 1089.1 1110.4 11441.9 11452.9 11454.3 11456.8 11513.8 3908.7 4585.3 4704.5 4719.8 4767.7 9847.2 9860.6 9866.9 9876.8 9881.2 5351.5 5358.6 5361.3 5389.1 5392.3 4748.8 4930.9 4935.6 4947.5 4953.4 3812.7 3824.8 3859.5 3882.9 3916.4 1102.3 1174.7 1178.1 1189.1 1190.1 727.8 908.8 919.9 959.7 969.9 5895.6 5935.9 5970.2 6050.4 6186.7 920.9 954.6 979.2 980.4 983.5 3547.0 4011.2 4037.1 4059.0 4104.2 10337.0 10647.2 10652.1 10653.4 10667.8 10442.7 10528.3 10620.8 10633.6 10640.7 7357.3 7881.4 8117.8 8184.5 8303.6 5986.5 6059.1 6106.3 6222.7 6300.1 9525.6 10077.6 10084.8 10093.5 10106.3 10064.3 10180.3 10211.4 10226.6 10238.6 7412.0 7431.4 7515.3 7595.8 7607.4 8196.0 8242.0 8319.3 8325.6 8333.0 7430.6 7533.5 7541.3 7681.7 7682.3 7371.5 7391.5 7431.6 7453.9 7499.8 6894.6 6902.6 6941.4 6950.1 6955.3 4849.6 4890.5 4963.2 5030.0 5054.1 5000.5 5070.9 5082.1 5098.4 5199.1 14:05:17 INFO - PROCESS | 1649 | 14:05:17 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4159.52 Median:4550.27 stddev:1279.51 (28.1%) stddev-sans-first:1249.74 14:05:17 INFO - PROCESS | 1649 | Values: 292.3 300.6 306.2 307.5 308.6 4126.1 4571.8 5141.5 5332.2 5366.4 5139.6 5255.6 5278.4 5293.5 5294.9 3432.4 3541.0 3621.4 3914.4 4029.7 3070.4 3221.2 4091.3 4104.4 4143.5 3357.5 3518.7 3529.4 4035.2 4050.7 4033.3 4045.6 4055.1 4056.6 4065.4 3534.6 3541.4 3547.2 3554.3 3562.4 4214.9 4243.5 4775.5 4908.9 5147.4 3636.9 3861.9 4588.8 4642.5 4652.7 4323.6 5507.3 5531.9 5539.6 5569.7 3412.7 3466.7 3752.3 3896.4 3904.7 5854.2 5860.5 5878.3 5890.6 5913.1 3317.7 3333.4 3511.8 3966.5 4028.4 3229.4 3827.0 4013.5 4035.7 4078.6 3872.1 4598.0 4609.8 4667.6 4669.8 4619.6 5115.7 5613.4 5678.7 5684.4 1284.6 1329.6 1329.7 1333.9 1343.6 824.9 852.4 856.8 871.0 874.9 3804.7 4427.6 4429.9 4450.5 4458.3 3968.8 4364.2 4528.7 4774.6 4876.6 3574.9 3881.5 4211.1 4391.8 4398.9 2054.7 2161.9 2372.5 2391.6 2535.7 4196.9 4358.7 4481.6 4886.7 4906.9 4069.8 4299.5 4687.3 4766.0 4810.4 3851.7 4117.6 4703.8 4954.4 5005.5 3923.8 4161.6 4726.8 4830.2 4864.9 4422.6 4605.3 4622.1 4746.4 4860.6 4608.0 4890.7 4919.9 4944.9 4978.3 3043.8 3270.7 3451.6 3466.8 3492.9 3772.4 4920.5 4933.6 4946.2 5533.5 4849.6 4899.5 5238.3 5416.7 5611.0 4615.8 5485.5 5549.7 5550.9 5582.0 4892.7 4961.1 5307.3 5450.5 5468.7 4603.4 4633.4 4861.3 5287.6 5593.7 5466.9 5478.8 5486.3 5488.7 5520.8 14:05:17 INFO - PROCESS | 1649 | 14:05:17 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:24926.89 Median:3434.32 stddev:40064.69 (1166.6%) stddev-sans-first:40135.94 14:05:17 INFO - PROCESS | 1649 | Values: 744.4 756.9 786.6 850.6 866.7 14891.9 16015.9 16033.0 16042.6 16050.3 14575.5 14724.4 14734.6 14744.7 14749.8 693.5 721.7 853.3 879.3 881.6 859.5 873.6 875.6 876.5 877.3 924.8 925.1 925.9 926.0 928.0 685.2 710.3 897.4 920.3 924.2 679.6 680.8 681.1 681.7 682.3 14944.5 14977.9 14978.4 14980.7 14995.0 14092.6 14199.9 14206.6 14225.7 14345.4 12662.6 12703.7 12714.0 12983.2 13214.9 4510.6 4539.9 4572.6 4659.0 4763.0 86339.0 116269.0 116929.4 118471.0 119908.0 10802.3 11003.1 11058.3 11307.3 11331.5 21359.9 21947.1 21958.0 21965.6 21977.9 6515.8 7892.9 8002.2 8069.9 8148.7 4363.2 4406.6 4485.5 4600.8 4669.1 3397.3 3471.3 3509.8 3514.2 3517.5 541.3 639.7 650.2 662.6 709.1 568.3 631.7 674.4 686.6 688.5 8424.5 9034.0 9310.9 9564.2 9810.8 618.9 666.4 671.7 695.7 696.8 4668.6 4680.4 4692.0 4726.7 4732.2 113202.4 124894.0 168820.2 176363.0 180961.0 108342.0 110684.4 149582.3 150207.0 152326.0 69031.7 81056.4 102824.0 105402.9 112955.0 9656.4 10048.0 10195.9 10216.3 10230.6 60645.7 61372.1 61978.4 62626.0 63148.7 86669.6 91202.1 92340.5 92770.0 94343.0 13250.9 13494.9 13564.9 13630.1 14081.5 15133.7 15307.7 15588.4 15984.6 16057.3 14071.6 14227.1 14281.4 14285.9 14857.8 13910.0 14370.2 14396.4 14854.4 14954.3 11749.4 13704.8 13955.8 14419.7 14586.6 11409.9 11586.5 11693.9 11909.5 11927.3 9147.9 10823.2 10938.6 11766.4 11781.8 14:05:17 INFO - PROCESS | 1649 | -------- Summary: end -------- 14:05:17 INFO - PROCESS | 1649 | 14:05:18 INFO - TEST-INFO | 1649: exit 0 14:05:18 INFO - TEST-OK | dromaeo_css | took 1115376ms 14:05:18 INFO - TEST-START | kraken 14:05:18 INFO - operating with platform_type : mac_ 14:05:18 INFO - Initialising browser for kraken test... 14:05:18 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpM2_4eV/profile http://localhost:49266/getInfo.html) 14:05:20 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 14:05:20 INFO - PROCESS | 1659 | colorDepth:24 14:05:20 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 14:05:20 INFO - PROCESS | 1659 | __metrics 14:05:20 INFO - PROCESS | 1659 | 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" 14:05:20 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:05:21 INFO - TEST-INFO | 1659: exit 0 14:05:21 INFO - Browser initialized. 14:05:21 INFO - Running cycle 1/1 for kraken test... 14:05:21 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpM2_4eV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:05:33 INFO - PROCESS | 1660 | RSS: Main: 243703808 14:05:33 INFO - PROCESS | 1660 | 14:05:36 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 14:05:37 INFO - PROCESS | 1660 | RSS: Main: 294252544 14:05:37 INFO - PROCESS | 1660 | 14:05:39 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 14:05:40 INFO - PROCESS | 1660 | RSS: Main: 304435200 14:05:40 INFO - PROCESS | 1660 | 14:05:43 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 14:05:44 INFO - PROCESS | 1660 | RSS: Main: 390623232 14:05:44 INFO - PROCESS | 1660 | 14:05:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 14:05:47 INFO - PROCESS | 1660 | RSS: Main: 290095104 14:05:47 INFO - PROCESS | 1660 | 14:05:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:05:49 INFO - PROCESS | 1660 | RSS: Main: 294268928 14:05:49 INFO - PROCESS | 1660 | 14:05:52 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 14:05:53 INFO - PROCESS | 1660 | RSS: Main: 330903552 14:05:53 INFO - PROCESS | 1660 | 14:05:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 14:05:56 INFO - PROCESS | 1660 | RSS: Main: 333295616 14:05:56 INFO - PROCESS | 1660 | 14:05:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 14:06:00 INFO - PROCESS | 1660 | RSS: Main: 330964992 14:06:00 INFO - PROCESS | 1660 | 14:06:01 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:06:02 INFO - PROCESS | 1660 | RSS: Main: 312500224 14:06:02 INFO - PROCESS | 1660 | 14:06:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:06:05 INFO - PROCESS | 1660 | RSS: Main: 318812160 14:06:05 INFO - PROCESS | 1660 | 14:06:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:06:08 INFO - PROCESS | 1660 | RSS: Main: 316129280 14:06:08 INFO - PROCESS | 1660 | 14:06:10 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:06:11 INFO - PROCESS | 1660 | RSS: Main: 327135232 14:06:11 INFO - PROCESS | 1660 | 14:06:14 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:06:14 INFO - PROCESS | 1660 | RSS: Main: 328204288 14:06:14 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 14:06:16 INFO - PROCESS | 1660 | RSS: Main: 371949568 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | __start_tp_report 14:06:16 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 14:06:16 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 14:06:16 INFO - PROCESS | 1660 | |i|pagename|runs| 14:06:16 INFO - PROCESS | 1660 | |0;ai-astar;101;113;112;115;112;111;113;112;111;112 14:06:16 INFO - PROCESS | 1660 | |1;audio-beat-detection;149;108;107;156;112;136;143;130;148;136 14:06:16 INFO - PROCESS | 1660 | |2;audio-dft;182;186;184;183;183;183;183;183;182;182 14:06:16 INFO - PROCESS | 1660 | |3;audio-fft;79;74;58;87;59;58;64;66;83;56 14:06:16 INFO - PROCESS | 1660 | |4;audio-oscillator;85;89;91;72;74;68;97;70;67;102 14:06:16 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;90;87;89;87;90;85;89;87;90;86 14:06:16 INFO - PROCESS | 1660 | |6;imaging-darkroom;95;94;95;95;95;95;95;97;95;93 14:06:16 INFO - PROCESS | 1660 | |7;imaging-desaturate;103;145;107;86;86;86;84;84;106;143 14:06:16 INFO - PROCESS | 1660 | |8;json-parse-financial;56;53;52;55;54;55;54;54;55;53 14:06:16 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;64;75;72;78;70;70;68;70;69;71 14:06:16 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;82;85;83;83;82;82;82;84;83;81 14:06:16 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;145;142;124;144;152;145;140;136;189;147 14:06:16 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;164;167;163;162;160;161;163;168;162;161 14:06:16 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;65;66;66;65;65;64;67;66;66;65 14:06:16 INFO - PROCESS | 1660 | __end_tp_report 14:06:16 INFO - PROCESS | 1660 | __start_cc_report 14:06:16 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2057 14:06:16 INFO - PROCESS | 1660 | __end_cc_report 14:06:16 INFO - PROCESS | 1660 | __startTimestamp1467925576876__endTimestamp 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | ------- Summary: start ------- 14:06:16 INFO - PROCESS | 1660 | Number of tests: 14 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:3.77 (3.4%) stddev-sans-first:1.22 14:06:16 INFO - PROCESS | 1660 | Values: 101.0 113.0 112.0 115.0 112.0 111.0 113.0 112.0 111.0 112.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:139.50 stddev:17.88 (12.8%) stddev-sans-first:17.94 14:06:16 INFO - PROCESS | 1660 | Values: 149.0 108.0 107.0 156.0 112.0 136.0 143.0 130.0 148.0 136.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.00 stddev:1.20 (0.7%) stddev-sans-first:1.20 14:06:16 INFO - PROCESS | 1660 | Values: 182.0 186.0 184.0 183.0 183.0 183.0 183.0 183.0 182.0 182.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:68.40 Median:70.00 stddev:11.48 (16.4%) stddev-sans-first:11.52 14:06:16 INFO - PROCESS | 1660 | Values: 79.0 74.0 58.0 87.0 59.0 58.0 64.0 66.0 83.0 56.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:81.50 Median:79.50 stddev:12.87 (16.2%) stddev-sans-first:13.59 14:06:16 INFO - PROCESS | 1660 | Values: 85.0 89.0 91.0 72.0 74.0 68.0 97.0 70.0 67.0 102.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:89.00 stddev:1.83 (2.1%) stddev-sans-first:1.79 14:06:16 INFO - PROCESS | 1660 | Values: 90.0 87.0 89.0 87.0 90.0 85.0 89.0 87.0 90.0 86.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:95.00 stddev:0.99 (1.0%) stddev-sans-first:1.05 14:06:16 INFO - PROCESS | 1660 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 97.0 95.0 93.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:103.00 Median:84.00 stddev:23.52 (28.0%) stddev-sans-first:24.94 14:06:16 INFO - PROCESS | 1660 | Values: 103.0 145.0 107.0 86.0 86.0 86.0 84.0 84.0 106.0 143.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:1.20 (2.2%) stddev-sans-first:1.05 14:06:16 INFO - PROCESS | 1660 | Values: 56.0 53.0 52.0 55.0 54.0 55.0 54.0 54.0 55.0 53.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.50 stddev:3.80 (5.4%) stddev-sans-first:3.17 14:06:16 INFO - PROCESS | 1660 | Values: 64.0 75.0 72.0 78.0 70.0 70.0 68.0 70.0 69.0 71.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:1.16 (1.4%) stddev-sans-first:1.20 14:06:16 INFO - PROCESS | 1660 | Values: 82.0 85.0 83.0 83.0 82.0 82.0 82.0 84.0 83.0 81.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:145.00 stddev:16.75 (11.6%) stddev-sans-first:17.76 14:06:16 INFO - PROCESS | 1660 | Values: 145.0 142.0 124.0 144.0 152.0 145.0 140.0 136.0 189.0 147.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.10 Median:163.00 stddev:2.60 (1.6%) stddev-sans-first:2.74 14:06:16 INFO - PROCESS | 1660 | Values: 164.0 167.0 163.0 162.0 160.0 161.0 163.0 168.0 162.0 161.0 14:06:16 INFO - PROCESS | 1660 | 14:06:16 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 14:06:16 INFO - PROCESS | 1660 | Values: 65.0 66.0 66.0 65.0 65.0 64.0 67.0 66.0 66.0 65.0 14:06:16 INFO - PROCESS | 1660 | -------- Summary: end -------- 14:06:16 INFO - PROCESS | 1660 | 14:06:17 INFO - TEST-INFO | 1660: exit 0 14:06:17 INFO - TEST-OK | kraken | took 59377ms 14:06:17 INFO - SUITE-END | took 1174s 14:06:18 INFO - Completed test suite (00:19:35) 14:06:18 INFO - javascript benchmark 14:06:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2583.5866261398173, 2694.9595756063654, 3191.473406887864, 3338.4324664806995, 3401.0307062487195, 5250.816221432246, 5509.338879334476, 5628.127640215763, 5638.013347664158, 5642.7178641067985, 4713.717176969376, 5623.550115990721, 5632.971835140818, 5710.057840456319, 5740.7129643517865, 5101.566366858821, 5133.640645154841, 5141.305923700309, 5159.38087429509, 5332.893342133155, 5256.9474305256845, 5282.339707536558, 5286.233496143076, 5289.391719952197, 5320.600954928376, 5095.0574143783515, 5141.100307446182, 5164.6384753067305, 5171.0692075426405, 5198.480151984809, 5120.92318615221, 5123.846284611431, 5124.92312615311, 5131.589472842164, 5181.53366197044, 5102.158143906251, 5118.104331741967, 5129.9230511542355, 5141.691498510064, 5192.844214673566, 5544.390117087117, 5622.353429355595, 5674.858128546819, 5724.427557244284, 5729.427057294279, 6122.977604208165, 6162.506999440079, 6185.195924529782, 6185.474234690057, 6217.626942383471, 6172.7530898764, 6179.134921111048, 6184.752609895644, 6187.659678717669, 6253.280872699606, 5643.040683083804, 5681.147827825776, 5825.766969321265, 5829.941700583025, 5839.941600583982, 6186.350433204539, 6240.7503699852405, 6256.655883926428, 6260.530460215411, 6300.9684951574945, 6105.8168254952425, 6110.236220472441, 6114.960456722368, 6126.908096378558, 6131.172291740558, 6153.569250152446, 6200.441960223601, 6229.0967809667345, 6248.375162483806, 6249.875002499924, 6061.212042434543, 6108.144859719643, 6114.449699526975, 6134.325224225332, 6157.538184636171, 6189.319174890758, 6190.3190649029475, 6192.0, 6194.318624951252, 6207.875842483216, 4878.243872199815, 4911.705297682151, 4913.410390753061, 4914.483979182206, 4957.934044180519, 4072.3687828386646, 4235.983363992619, 4347.7173983690955, 4357.281048627005, 4429.601335879722, 5608.046632072557, 5612.186232996274, 5615.6630602164005, 5630.071038278638, 5644.74598643062, 6097.817065487954, 6188.412100850502, 6217.160683307786, 6223.751049958041, 6230.906536401958, 5562.9443705563235, 5571.247881536022, 5606.21512988182, 5641.012822756002, 5653.067243904792, 5325.041492531382, 5326.201069839478, 5334.653247538898, 5337.919931201035, 5344.946550534522, 6235.594686345373, 6319.9052014218905, 6320.778772742932, 6338.714757836001, 6351.491880649537, 6165.5375846810775, 6229.688515574294, 6230.065490176328, 6238.906416403667, 6259.998400255937, 6308.148399965946, 6309.652969086745, 6320.810375688737, 6331.113644089739, 6337.556371053982, 5954.553408494467, 6036.3058248302, 6065.090236464565, 6144.754209831557, 6153.753849845956, 5067.23991401278, 6133.723982420891, 6186.319504854372, 6195.318514963441, 6208.379162083756, 5885.234919460443, 5925.792597259076, 5928.229330187048, 5940.16837642739, 5956.910646340221, 5823.70881455934, 5866.7066646668345, 5897.882042359214, 5908.497777688773, 5939.762409503572, 4810.615150787999, 5006.824761133343, 5754.971225144015, 5789.507891829242, 5818.0, 5896.17453556511, 5897.3807750187325, 5908.350081490947, 5929.407059294036, 5959.195508606283, 5704.543636509153, 5764.855878603068, 5775.451332123442, 5815.82552523425, 5889.705514724161], "value": 5601.559858417114, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.2362409941734, 538.7225578826902, 539.409346765292, 541.9539339156169, 552.8645481856946, 30308.848455757718, 40908.59091409085, 41966.58033419669, 42140.57859421405, 44390.0, 43315.566844331544, 43633.90915227121, 43637.56362436375, 43659.0, 43909.78045109778, 8440.28257598105, 8693.391462597621, 9638.180754635881, 9823.852642210373, 9825.361351512129, 8714.825703485927, 8884.466931984069, 8889.555522223864, 8899.68851090212, 11777.705557361049, 11583.18917675763, 11890.7621847563, 11902.880971190263, 11904.464299106518, 11906.880931190708, 11807.291562506278, 11936.16446848721, 11937.761244775056, 11941.402929853559, 11947.641570752892, 7377.55734655917, 8882.555872206429, 8898.644054237822, 8981.595828187747, 11780.234284771463, 39765.80117099425, 40362.798186009175, 40393.192136157406, 42452.787736061124, 42480.57519424797, 3446.758726889119, 3453.8963831085107, 3493.7030352420074, 3503.299340131958, 3592.263585964871, 31243.84378078118, 31569.0, 31605.20986975321, 31965.52051719226, 32554.511682324784, 2920.8101473404163, 3207.3905957867933, 3251.154699778051, 3271.7382609391193, 3273.3289675616443, 524267.0, 527744.0, 528441.0, 528691.0, 532641.0, 50566.0, 51525.48474515282, 51632.22551661728, 58990.70504647535, 59291.0, 53088.203676944875, 60787.0, 61441.0, 61681.0, 64414.677926610064, 16015.279312430968, 16262.75605865913, 16287.51137465878, 16298.592535186715, 16339.59151021234, 12590.874091258971, 12835.165714228546, 12852.742945141044, 12858.678533036562, 12860.549880754128, 13151.53969611059, 13632.863671363359, 13930.164190148624, 15254.542363729106, 15341.769873451907, 6695.430888374445, 6707.731690732414, 6739.0, 6762.120924279812, 6800.183977922681, 953.7663272498144, 1023.6673081248624, 1058.1323314881722, 1081.3185496552935, 1107.645553422897, 30375.088747337617, 30491.8475407626, 30581.847090764404, 30586.69413305883, 31104.844475777478, 992.6972273456665, 1065.350136416785, 1078.5038882114159, 1084.7017070305571, 1103.0293341859274, 15788.763168552481, 15797.921010395105, 15981.520554383387, 16125.274362653709, 16174.514764557081, 693694.0, 698394.0, 698554.0, 698625.5068724623, 700057.0, 59535.702321488985, 59824.0, 59875.0, 59913.801723965276, 59919.700401497714, 1283.9293838838953, 1402.1797248609473, 1418.2058047493438, 1422.025912250112, 1437.23826372023, 31260.843695782285, 31497.527537086502, 31600.73597056092, 32132.19669508281, 32234.83882580666, 48962.0, 49167.754161228964, 49544.256836148204, 49726.502734973634, 49838.750806245735, 568788.0, 572507.0, 572718.0, 573557.0, 573877.0, 26311.0, 26723.33191670224, 26752.33119172036, 27544.760485777788, 38599.807000964815, 39045.02387440337, 48217.276740849615, 48740.0, 48822.0, 49167.50832491629, 39899.40150897797, 39947.40078898761, 40555.79722101371, 41374.0, 41403.58596414118, 38947.6105238944, 39400.211995760495, 39535.60464395319, 40878.0, 40939.59060409477, 33578.3284334307, 38979.610203897595, 39324.0, 39339.80330098331, 39396.0, 33468.832655836566, 34719.4792078114, 35441.82279088588, 35596.82201588975, 35608.82195589005, 24455.51088978246, 25851.870740646176, 25895.61156582615, 26113.608295875198, 26693.599596005686], "value": 20299.66566330424, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.4552443712247, 356.8233724306469, 378.77462909568806, 387.58145188609683, 389.35172937059815, 13520.864791352096, 15930.442434514795, 20600.0, 21196.470088247803, 21260.681089783626, 20354.592908141865, 21270.0, 21593.352199434004, 21991.79045152516, 22001.889990550066, 5061.924071138936, 5468.070428027225, 5643.4074422185695, 5656.490915817576, 5669.0646043402785, 5537.224788529609, 5549.72251387429, 5577.637453565526, 5578.581606379517, 5593.244911936898, 5432.6468779529405, 5575.275214222145, 5575.275214222166, 5578.9163162552395, 5582.246396736449, 5558.555315574744, 5571.247881535981, 5588.469095435927, 5588.469095435927, 5598.944010559883, 4682.742449165295, 5090.567301779353, 5425.0, 5439.347278326587, 5443.863903402407, 13204.735905281937, 13218.471261149543, 13280.601581952556, 13364.131331463424, 13443.395047222899, 2297.3222899244597, 2317.3395582258977, 2338.368640467081, 2407.1574948767966, 2413.4207790130245, 10049.497525123714, 10058.396496210178, 10515.684529464128, 13874.375653095634, 14066.226357550331, 1524.6645737937636, 1858.5632376391536, 1888.9149988250424, 1910.7133929910485, 1919.0, 81991.12456568905, 112532.43733781361, 120533.39733301365, 122489.0, 134260.32869835687, 10904.672859814218, 11652.242604230698, 11698.824517632242, 11710.765784684429, 11867.58463453775, 12379.814302785466, 14535.345909434101, 14553.636159095895, 14698.926505367404, 14734.631634209016, 8550.358723095793, 8624.611892464854, 8694.304455643532, 8700.434471759316, 8706.564671766386, 8627.094155113748, 8648.610812513452, 8648.610812513452, 8739.956300218586, 8759.474431534129, 4481.865544033684, 4541.364209010741, 4555.088982203572, 4599.310103484504, 4625.791839367169, 1771.1852547828144, 1821.6174603333184, 1911.5794525204433, 1915.1190452392057, 1949.502876766444, 881.8809460722848, 887.1394747095308, 926.8563372677246, 972.9562169702381, 1050.6112738286884, 21263.0, 22149.44626384321, 22190.226221722285, 22264.332070037926, 22340.0, 943.5706753427312, 952.1383148250844, 994.3487016004528, 1008.4403156248206, 1030.4899074957943, 8313.293370063613, 8752.518611476302, 10409.895901041044, 10425.592545006328, 11573.942130289295, 555646.0, 597362.0, 600494.0, 618557.0, 625975.0, 101146.0, 129348.35325823311, 133413.0, 141574.0, 146629.0, 962.31675510387, 970.5292932927524, 983.1201075037801, 992.7071513903271, 993.3642468819961, 14445.855541444871, 19798.50503737418, 20078.598428031648, 20102.09540570707, 20206.79793202108, 54910.45089549053, 55005.449945501634, 56100.71949640226, 57314.71342643427, 57863.13205302008, 69044.30955690378, 80101.1989880117, 86225.56887215524, 86492.54029838988, 90847.0, 7038.648067596702, 7349.853002940018, 7381.188069312264, 7662.961685191538, 8317.667293308199, 9237.307201959746, 12252.693682658004, 12998.415071322002, 13275.336233188495, 13311.80032299497, 11082.722931926766, 11247.662570122582, 11328.433578320886, 11418.257813242113, 11423.543058277575, 9740.659076932558, 10830.566777328819, 10889.455527223448, 10963.671089867317, 11327.943360283145, 10318.587256509656, 11347.943260283646, 11360.659180224606, 11576.363300018244, 11689.0, 7344.669489872862, 7345.412367010519, 7422.628868556659, 7425.257474252531, 7632.0, 8172.305354044973, 10849.186311026546, 11152.776944461228, 11162.665120046411, 11229.101671866069], "value": 9321.887098976673, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1493.3951749541434, 1510.7884896114547, 1723.164265331315, 1885.2647467487673, 1907.5707965707718, 7871.134175240726, 8253.257206851382, 8708.43395179313, 8917.554122293875, 8925.330600204978, 7789.104253010905, 8022.398320125984, 8435.536045517494, 8675.26260267878, 8707.695230666928, 2356.104680221518, 2381.4760752634393, 2382.737898831127, 2382.940426489343, 2384.2489615771065, 2344.6717459555675, 2351.459164392182, 2383.9642405363934, 2384.59461891478, 2384.618461046233, 1909.9904500477478, 2080.1367432515563, 2180.923667671632, 2368.9052437902465, 2369.052379048377, 1960.696092105726, 2353.7410884802657, 2380.773826486481, 2385.296337580418, 2394.401399650096, 2063.123172651617, 2291.186628746804, 2332.988335058331, 2349.7767712067325, 2359.7168339799164, 8823.647054117828, 8888.866667, 8891.888513935757, 8897.243734282594, 8898.154675305836, 5141.974290128563, 5350.197470379474, 5361.597880159004, 5369.006733754268, 5371.919421208685, 3566.6651680678647, 3570.535830342065, 3641.089727568108, 3641.6176301488226, 3650.5801832789302, 929.9116583924584, 1055.6463584699136, 1085.1752667972398, 1089.0634054712941, 1110.3559935237543, 11441.942790285995, 11452.885471145266, 11454.255473394203, 11456.828147577793, 11513.769724605461, 3908.7459315144993, 4585.2663573828695, 4704.506026867198, 4719.834805781781, 4767.666263361601, 9847.212223022141, 9860.605575776888, 9866.851997220047, 9876.80246395068, 9881.209503239723, 5351.464853514695, 5358.571314294847, 5361.303030605996, 5389.137737961985, 5392.272043274108, 4748.786304616231, 4930.852074437701, 4935.580475659609, 4947.48051454592, 4953.380827396575, 3812.6759225466144, 3824.8007395747554, 3859.536855577349, 3882.9223415532097, 3916.373380259145, 1102.2835157147758, 1174.682835634374, 1178.0987544528443, 1189.108168873345, 1190.1252579354168, 727.7780277015455, 908.7637214324324, 919.9126083022102, 959.69289827256, 969.9233850426021, 5895.587308888336, 5935.881282374328, 5970.194023806817, 6050.394960503914, 6186.690665466709, 920.9363185520734, 954.5847556312888, 979.1970584121095, 980.3921568627496, 983.537737263485, 3546.9822650887095, 4011.157656892027, 4037.1320166163828, 4059.0258337998666, 4104.240715467648, 10337.0, 10647.201459890539, 10652.094571961316, 10653.360798352123, 10667.83998240027, 10442.738931526621, 10528.315659482108, 10620.840687389695, 10633.627823026158, 10640.733981650521, 7357.3378395943555, 7881.448298619041, 8117.756467305991, 8184.549849758201, 8303.584820759059, 5986.491148252448, 6059.135017857352, 6106.32830388648, 6222.719977601109, 6300.117983482408, 9525.61897524119, 10077.596896124367, 10084.84872726895, 10093.545790439595, 10106.343087699275, 10064.345817521838, 10180.338278011906, 10211.387316761018, 10226.6420675276, 10238.590456381959, 7412.0, 7431.442641801995, 7515.286047825448, 7595.810104747426, 7607.35337496319, 8196.016478022437, 8242.0, 8319.292860106958, 8325.583720814057, 8333.000039995239, 7430.591317477591, 7533.54798712079, 7541.283578060075, 7681.731139410093, 7682.347000505019, 7371.48399612022, 7391.482596218429, 7431.554106753612, 7453.925460745323, 7499.812504687427, 6894.551854129466, 6902.620355880475, 6941.409980151744, 6950.096487456598, 6955.339242771928, 4849.612031037438, 4890.535399137077, 4963.1636294571235, 5030.0191462664425, 5054.140796064751, 5000.549950504472, 5070.949290507047, 5082.059818933509, 5098.362704661918, 5199.116150254541], "value": 4575.745383797395, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.25620795076526, 300.631726135484, 306.16264516547255, 307.5171979991413, 308.56184218409857, 4126.112885729567, 4571.834182283517, 5141.460146684601, 5332.200169974501, 5366.382865970415, 5139.614528910328, 5255.632105752599, 5278.392984806748, 5293.497117773815, 5294.920576191351, 3432.399330117232, 3540.982295088521, 3621.438677005062, 3914.412838074283, 4029.697772667047, 3070.4319700855413, 3221.194701324669, 4091.3249313863175, 4104.445899803518, 4143.502779666429, 3357.4628059510483, 3518.6833185013343, 3529.3823580873373, 4035.244668569167, 4050.7164498485117, 4033.334499807528, 4045.59544045596, 4055.1078762672164, 4056.594340565949, 4065.3698676705308, 3534.575850897909, 3541.3625547401457, 3547.219611685425, 3554.306910152521, 3562.4300111982216, 4214.936775948363, 4243.490781106287, 4775.546323099335, 4908.877278068041, 5147.356580427447, 3636.8545258189906, 3861.8841434757, 4588.770561471946, 4642.489326174118, 4652.720836749806, 4323.589259020388, 5507.256520369779, 5531.889362212733, 5539.556835453154, 5569.693666848321, 3412.675795799395, 3466.6600044969546, 3752.3245815753153, 3896.383001054559, 3904.726669133162, 5854.18041474194, 5860.531157507389, 5878.294604647427, 5890.617109887844, 5913.113033045035, 3317.701406873393, 3333.449980753149, 3511.7717348372275, 3966.5438474575217, 4028.395740638869, 3229.370272796782, 3826.9808650956566, 4013.478247827822, 4035.6569691575955, 4078.6336577246952, 3872.1094148345474, 4597.95402045982, 4609.7695115244105, 4667.556582124761, 4669.836555720533, 4619.5611416915335, 5115.7442127893455, 5613.410591887793, 5678.68767217807, 5684.431556844283, 1284.5825106840314, 1329.6409969308427, 1329.734053189366, 1333.8599447058114, 1343.6362092476145, 824.8721448175543, 852.4373913217247, 856.8218699288478, 871.020102384817, 874.9282129391445, 3804.6766024887643, 4427.579379958899, 4429.911401771947, 4450.46594408673, 4458.331250312479, 3968.7817170055923, 4364.236258654722, 4528.705634133771, 4774.641901857374, 4876.609871210295, 3574.890814419505, 3881.4760007399204, 4211.126048908206, 4391.7584532851, 4398.912021759547, 2054.7123402723632, 2161.870287782738, 2372.4579023634774, 2391.5934291170543, 2535.733747956475, 4196.852360729453, 4358.6730995175485, 4481.619062379669, 4886.682365646293, 4906.852794416173, 4069.7558146511305, 4299.4744309665775, 4687.29690546414, 4765.9523404765505, 4810.39870016248, 3851.669739807432, 4117.629413352813, 4703.764811759398, 4954.432808377962, 5005.499450054966, 3923.843046278117, 4161.646260067928, 4726.794667359802, 4830.154722923545, 4864.854054378375, 4422.646188304863, 4605.30920361935, 4622.098690755271, 4746.359241502353, 4860.594005967069, 4608.0, 4890.731009794496, 4919.852404427873, 4944.9368385797225, 4978.27814966835, 3043.8388743992014, 3270.6565810590496, 3451.637578054268, 3466.826658667006, 3492.9301413972084, 3772.358699021118, 4920.45874953762, 4933.605311574994, 4946.233333833262, 5533.474319939599, 4849.58778503817, 4899.485554016919, 5238.319018527567, 5416.7020813855615, 5611.0, 4615.769211539477, 5485.54410721243, 5549.667019978814, 5550.916736248879, 5582.0, 4892.724882929455, 4961.08219979305, 5307.336582927134, 5450.5367043801725, 5468.726563671721, 4603.3785438965315, 4633.4208223972, 4861.295112208639, 5287.629865909349, 5593.664380137205, 5466.863328416822, 5478.835634930959, 5486.341639003185, 5488.69812160335, 5520.834374968757], "value": 3769.7327075456164, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.3858816476406, 756.8675481790685, 786.5870418030534, 850.6044689219514, 866.69665617034, 14891.925540372296, 16015.91992040041, 16033.0, 16042.59893502663, 16050.27773750181, 14575.489857854951, 14724.411023559072, 14734.557963261093, 14744.705105897876, 14749.77875331871, 693.5180049865346, 721.7473884140561, 853.2576658307281, 879.3229213505597, 881.5592203898051, 859.4757198109148, 873.6112429968666, 875.5709702245917, 876.5441970175505, 877.3419935048713, 924.8427767279545, 925.0749250749251, 925.9351845370827, 925.995295104812, 928.0209379105041, 685.2120061928822, 710.3074502360183, 897.4077109108023, 920.2867777472445, 924.1820988425225, 679.6126208061407, 680.7651360280745, 681.1247546902256, 681.6727970574102, 682.3381320119477, 14944.476943307041, 14977.850221497754, 14978.400863965428, 14980.700385992219, 14995.0, 14092.647683807885, 14199.929000354932, 14206.573802785933, 14225.715485690333, 14345.42618295267, 12662.556810511587, 12703.682407939783, 12714.0, 12983.221006739626, 13214.933925330311, 4510.639148868083, 4539.886502837422, 4572.634189264851, 4659.0, 4762.952370476286, 86339.0, 116269.0, 116929.41535292269, 118471.0, 119908.0, 10802.297850639683, 11003.064739497073, 11058.281211721212, 11307.265027773334, 11331.490082946288, 21359.89320053421, 21947.122115115537, 21958.01188946501, 21965.560688786456, 21977.890110549346, 6515.804525864231, 7892.921070789219, 8002.159773223844, 8069.919300806917, 8148.714794982147, 4363.170997510463, 4406.559344065632, 4485.506594274627, 4600.769961501978, 4669.112868554964, 3397.3205358928312, 3471.323091997087, 3509.771864828778, 3514.2444374459615, 3517.4547945068557, 541.3044238153954, 639.7249182851325, 650.2489624483729, 662.6454846657111, 709.0994437064899, 568.3322096536554, 631.6841579210395, 674.430106559956, 686.629220221085, 688.4870771275432, 8424.452410593414, 9033.96109447422, 9310.906890931004, 9564.18704410119, 9810.80378392428, 618.8890940761344, 666.4435196610788, 671.701093013614, 695.6660802814678, 696.8292768271806, 4668.626509879202, 4680.4149481314835, 4692.0, 4726.669133160645, 4732.1718699227495, 113202.43398782953, 124894.0, 168820.1558992197, 176363.0, 180961.0, 108342.0, 110684.44657776659, 149582.25208874105, 150207.0, 152326.0, 69031.65484172547, 81056.35246413808, 102823.97176028293, 105402.94597054238, 112955.0, 9656.37233579815, 10048.045435683598, 10195.949020255148, 10216.335938163995, 10230.590776368861, 60645.69677151586, 61372.07941880965, 61978.38021619907, 62626.0, 63148.684256578425, 86669.56665216634, 91202.08797911936, 92340.53829730809, 92770.0, 94343.0, 13250.87367573767, 13494.93252533731, 13564.864351356755, 13630.1140425872, 14081.472582678593, 15133.697326053638, 15307.704884731313, 15588.37646494173, 15984.600384990468, 16057.277422516248, 14071.648208794863, 14227.07524010936, 14281.42874285017, 14285.857141428452, 14857.777133342792, 13910.0, 14370.209638469985, 14396.352164152848, 14854.374714606138, 14954.252287385805, 11749.353785541878, 13704.794428083387, 13955.790663140264, 14419.711605768036, 14586.635334116307, 11409.885901140882, 11586.536538538365, 11693.941530292293, 11909.464074116859, 11927.284362938251, 9147.908520914973, 10823.194266675124, 10938.617148399768, 11766.411679415824, 11781.823272650745], "value": 7977.718453337746, "unit": "score"}], "name": "dromaeo_css", "value": 7255.499030633831, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 112.0, 115.0, 112.0, 111.0, 113.0, 112.0, 111.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 108.0, 107.0, 156.0, 112.0, 136.0, 143.0, 130.0, 148.0, 136.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 184.0, 183.0, 183.0, 183.0, 183.0, 183.0, 182.0, 182.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 58.0, 87.0, 59.0, 58.0, 64.0, 66.0, 83.0, 56.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 91.0, 72.0, 74.0, 68.0, 97.0, 70.0, 67.0, 102.0], "value": 81.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 90.0, 85.0, 89.0, 87.0, 90.0, 86.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 97.0, 95.0, 93.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 145.0, 107.0, 86.0, 86.0, 86.0, 84.0, 84.0, 106.0, 143.0], "value": 103.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 52.0, 55.0, 54.0, 55.0, 54.0, 54.0, 55.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 72.0, 78.0, 70.0, 70.0, 68.0, 70.0, 69.0, 71.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 83.0, 83.0, 82.0, 82.0, 82.0, 84.0, 83.0, 81.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 142.0, 124.0, 144.0, 152.0, 145.0, 140.0, 136.0, 189.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 163.0, 162.0, 160.0, 161.0, 163.0, 168.0, 162.0, 161.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 65.0, 64.0, 67.0, 66.0, 66.0, 65.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1445.1, "alertThreshold": 2.0}]} 14:06:18 INFO - Return code: 0 14:06:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:06:18 INFO - # TBPL SUCCESS # 14:06:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:06:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:06:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:06:18 INFO - 2016-07-07 14:06:18.175 screenresolution[1664:9581] starting screenresolution argv=screenresolution get 14:06:18 INFO - 2016-07-07 14:06:18.178 screenresolution[1664:9581] Display 0: 1600x1200x32@60 14:06:18 INFO - 2016-07-07 14:06:18.184 screenresolution[1665:9584] starting screenresolution argv=screenresolution list 14:06:18 INFO - Available Modes on Display 0 14:06:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:06:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:06:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:06:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:06:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:06:18 INFO - Intel Iris: 14:06:18 INFO - Chipset Model: Intel Iris 14:06:18 INFO - Type: GPU 14:06:18 INFO - Bus: Built-In 14:06:18 INFO - VRAM (Dynamic, Max): 1536 MB 14:06:18 INFO - Vendor: Intel (0x8086) 14:06:18 INFO - Device ID: 0x0a2e 14:06:18 INFO - Revision ID: 0x0009 14:06:18 INFO - Displays: 14:06:18 INFO - Display: 14:06:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:06:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:06:18 INFO - Display Serial Number: 1600x1200 60 14:06:18 INFO - Main Display: Yes 14:06:18 INFO - Mirror: Off 14:06:18 INFO - Online: Yes 14:06:18 INFO - Rotation: Supported 14:06:18 INFO - Return code: 0 14:06:18 INFO - Running post-action listener: _resource_record_post_action 14:06:18 INFO - ##### 14:06:18 INFO - ##### Finished run-tests step (success) 14:06:18 INFO - ##### 14:06:18 INFO - Running post-run listener: _resource_record_post_run 14:06:18 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 38131200; Write bytes: 705655296; Read time: 391; Write time: 1952 14:06:18 INFO - TinderboxPrint: CPU usage
53.4% 14:06:18 INFO - TinderboxPrint: I/O read bytes / time
38,131,200 / 391 14:06:18 INFO - TinderboxPrint: I/O write bytes / time
705,655,296 / 1,952 14:06:18 INFO - TinderboxPrint: CPU idle
2,256.4 (46.6%) 14:06:18 INFO - TinderboxPrint: CPU system
962.6 (19.9%) 14:06:18 INFO - TinderboxPrint: CPU user
1,622.7 (33.5%) 14:06:18 INFO - TinderboxPrint: Swap in / out
445,263,872 / 0 14:06:18 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 386214400; Write bytes: 380544512; Read time: 29264; Write time: 706 14:06:18 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 37176832; Write bytes: 295783424; Read time: 376; Write time: 1194 14:06:18 INFO - Running post-run listener: _upload_blobber_files 14:06:18 INFO - Blob upload gear active. 14:06:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:06:18 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 14:06:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:06:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:06:19 INFO - (blobuploader) - INFO - Open directory for files ... 14:06:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:06:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:06:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:06:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:06:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:06:19 INFO - (blobuploader) - INFO - Done attempting. 14:06:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:06:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:06:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:06:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:06:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:06:20 INFO - (blobuploader) - INFO - Done attempting. 14:06:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:06:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:06:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:06:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:06:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:06:23 INFO - (blobuploader) - INFO - Done attempting. 14:06:23 INFO - (blobuploader) - INFO - Iteration through files over. 14:06:23 INFO - Return code: 0 14:06:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:06:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:06:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a615417bef3e550595af19c371636256cdeea3dc0c8f3932aba8a80952623c0af71737da4a02f5027acc2133702e8770bb66eec628145feea9fc4462be1b7e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81561ac676a4879ed400a12e7ae7b8d2bd79bff901d0ad744a212924de6d18db62843bbd50e06a0971bc0760654b7c6041bfc0c416e407cdba3ba1578b6bf26e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b3b4b0632d9c3b59d710cd81c8408bd17461c38553940717b6c044ab10db6a645898b9a11997e10b2c99b565fb24959770916cf7b84a45e3cb09eee7e608128"} 14:06:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:06:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:06:23 INFO - Contents: 14:06:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a615417bef3e550595af19c371636256cdeea3dc0c8f3932aba8a80952623c0af71737da4a02f5027acc2133702e8770bb66eec628145feea9fc4462be1b7e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81561ac676a4879ed400a12e7ae7b8d2bd79bff901d0ad744a212924de6d18db62843bbd50e06a0971bc0760654b7c6041bfc0c416e407cdba3ba1578b6bf26e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b3b4b0632d9c3b59d710cd81c8408bd17461c38553940717b6c044ab10db6a645898b9a11997e10b2c99b565fb24959770916cf7b84a45e3cb09eee7e608128"} 14:06:23 INFO - Running post-run listener: copy_logs_to_upload_dir 14:06:23 INFO - Copying logs to upload dir... 14:06:23 INFO - mkdir: /builds/slave/test/build/upload/logs 14:06:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.825980 ========= master_lag: 0.55 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-07 14:06:23.704802) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 14:06:23.708650) ========= 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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a615417bef3e550595af19c371636256cdeea3dc0c8f3932aba8a80952623c0af71737da4a02f5027acc2133702e8770bb66eec628145feea9fc4462be1b7e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81561ac676a4879ed400a12e7ae7b8d2bd79bff901d0ad744a212924de6d18db62843bbd50e06a0971bc0760654b7c6041bfc0c416e407cdba3ba1578b6bf26e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b3b4b0632d9c3b59d710cd81c8408bd17461c38553940717b6c044ab10db6a645898b9a11997e10b2c99b565fb24959770916cf7b84a45e3cb09eee7e608128"} build_url:https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011124 build_url: 'https://queue.taskcluster.net/v1/task/Ix-RKyh_RHuBkOLBxR0GEQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a615417bef3e550595af19c371636256cdeea3dc0c8f3932aba8a80952623c0af71737da4a02f5027acc2133702e8770bb66eec628145feea9fc4462be1b7e43", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81561ac676a4879ed400a12e7ae7b8d2bd79bff901d0ad744a212924de6d18db62843bbd50e06a0971bc0760654b7c6041bfc0c416e407cdba3ba1578b6bf26e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b3b4b0632d9c3b59d710cd81c8408bd17461c38553940717b6c044ab10db6a645898b9a11997e10b2c99b565fb24959770916cf7b84a45e3cb09eee7e608128"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 14:06:23.741589) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 14:06:23.741931) ========= 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.iDoPXS6tfB/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.oUm1HL0bdc/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.005171 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 14:06:23.790597) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 14:06:23.790874) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 14:06:23.791220) ========= ========= Total master_lag: 0.93 =========