builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0126 starttime: 1468341368.44 results: success (0) buildid: 20160712072641 builduid: f1671b1e731d494fbcad432be7cc2726 revision: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:08.444172) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:08.444612) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:08.444891) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:08.469335) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:08.469706) ========= 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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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-12 09:36:08-- 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% 211M=0s 2016-07-12 09:36:08 (211 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137535 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:08.629898) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:08.630227) ========= 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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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.027643 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:08.679128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-12 09:36:08.679476) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb --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 fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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-12 09:36:08,754 truncating revision to first 12 chars 2016-07-12 09:36:08,755 Setting DEBUG logging. 2016-07-12 09:36:08,755 attempt 1/10 2016-07-12 09:36:08,755 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fcfa26398425?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 09:36:09,875 unpacking tar archive at: mozilla-central-fcfa26398425/testing/mozharness/ program finished with exit code 0 elapsedTime=1.394570 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-12 09:36:10.102646) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:10.104588) ========= script_repo_revision: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:10.105536) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:10.105965) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 09:36:10.125802) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-12 09:36:10.126202) ========= /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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:36:10 INFO - MultiFileLogger online at 20160712 09:36:10 in /builds/slave/test 09:36:10 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 09:36:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:10 INFO - {'append_to_log': False, 09:36:10 INFO - 'base_work_dir': '/builds/slave/test', 09:36:10 INFO - 'blob_upload_branch': 'Firefox', 09:36:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:10 INFO - 'branch': 'Firefox', 09:36:10 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:10 INFO - 'config_files': ('talos/mac_config.py',), 09:36:10 INFO - 'default_actions': ('clobber', 09:36:10 INFO - 'read-buildbot-config', 09:36:10 INFO - 'download-and-extract', 09:36:10 INFO - 'populate-webroot', 09:36:10 INFO - 'create-virtualenv', 09:36:10 INFO - 'install', 09:36:10 INFO - 'run-tests'), 09:36:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:10 INFO - 'download_minidump_stackwalk': True, 09:36:10 INFO - 'download_symbols': 'ondemand', 09:36:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:10 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:10 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:10 INFO - 'installer_path': 'installer.exe', 09:36:10 INFO - 'log_level': 'info', 09:36:10 INFO - 'log_name': 'talos', 09:36:10 INFO - 'log_to_console': True, 09:36:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:36:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:36:10 INFO - 'opt_config_files': (), 09:36:10 INFO - 'pip_index': False, 09:36:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:10 INFO - 'cmd': ('bash', 09:36:10 INFO - '-c', 09:36:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:10 INFO - 'enabled': True, 09:36:10 INFO - 'halt_on_failure': False, 09:36:10 INFO - 'name': 'check_screen_resolution'},), 09:36:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:10 INFO - 'cmd': ('bash', 09:36:10 INFO - '-c', 09:36:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:10 INFO - 'enabled': True, 09:36:10 INFO - 'halt_on_failure': False, 09:36:10 INFO - 'name': 'check_screen_resolution'},), 09:36:10 INFO - 'run_cmd_checks_enabled': True, 09:36:10 INFO - 'sps_profile': False, 09:36:10 INFO - 'sps_profile_interval': 0, 09:36:10 INFO - 'suite': 'dromaeojs', 09:36:10 INFO - 'system_bits': '32', 09:36:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:10 INFO - 'title': 't-yosemite-r7-0126', 09:36:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:10 INFO - 'use_talos_json': True, 09:36:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:10 INFO - 'work_dir': 'build'} 09:36:10 INFO - ##### 09:36:10 INFO - ##### Running clobber step. 09:36:10 INFO - ##### 09:36:10 INFO - Running pre-action listener: _resource_record_pre_action 09:36:10 INFO - Running main action method: clobber 09:36:10 INFO - rmtree: /builds/slave/test/build 09:36:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:11 INFO - Running post-action listener: _resource_record_post_action 09:36:11 INFO - ##### 09:36:11 INFO - ##### Finished clobber step (success) 09:36:11 INFO - ##### 09:36:11 INFO - ##### 09:36:11 INFO - ##### Running read-buildbot-config step. 09:36:11 INFO - ##### 09:36:11 INFO - Running pre-action listener: _resource_record_pre_action 09:36:11 INFO - Running main action method: read_buildbot_config 09:36:11 INFO - Using buildbot properties: 09:36:11 INFO - { 09:36:11 INFO - "project": "", 09:36:11 INFO - "product": "firefox", 09:36:11 INFO - "script_repo_revision": "production", 09:36:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:36:11 INFO - "repository": "", 09:36:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:36:11 INFO - "buildid": "20160712072641", 09:36:11 INFO - "pgo_build": "False", 09:36:11 INFO - "basedir": "/builds/slave/test", 09:36:11 INFO - "buildnumber": 291, 09:36:11 INFO - "slavename": "t-yosemite-r7-0126", 09:36:11 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:36:11 INFO - "platform": "yosemite_r7", 09:36:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:36:11 INFO - "branch": "mozilla-central", 09:36:11 INFO - "repo_path": "mozilla-central", 09:36:11 INFO - "revision": "fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb", 09:36:11 INFO - "stage_platform": "macosx64", 09:36:11 INFO - "builduid": "f1671b1e731d494fbcad432be7cc2726", 09:36:11 INFO - "slavebuilddir": "test" 09:36:11 INFO - } 09:36:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:36:11 INFO - Running post-action listener: _resource_record_post_action 09:36:11 INFO - ##### 09:36:11 INFO - ##### Finished read-buildbot-config step (success) 09:36:11 INFO - ##### 09:36:11 INFO - ##### 09:36:11 INFO - ##### Running download-and-extract step. 09:36:11 INFO - ##### 09:36:11 INFO - Running pre-action listener: _resource_record_pre_action 09:36:11 INFO - Running main action method: download_and_extract 09:36:11 INFO - mkdir: /builds/slave/test/build/tests 09:36:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:11 INFO - https://queue.taskcluster.net/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:36:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:36:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:36:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/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 09:36:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/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 09:36:13 INFO - Downloaded 1199 bytes. 09:36:13 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:36:13 INFO - Using the following test package requirements: 09:36:13 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:36:13 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:13 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:36:13 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:13 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:36:13 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:13 INFO - u'jsshell-mac.zip'], 09:36:13 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:13 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:36:13 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:36:13 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:13 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:36:13 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:13 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:36:13 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:13 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:36:13 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:36:13 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:36:13 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:36:13 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')]} 09:36:13 INFO - https://queue.taskcluster.net/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:36:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:36:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:36:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/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 09:36:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/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 09:36:14 INFO - Downloaded 18772483 bytes. 09:36:14 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'] 09:36:14 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 09:36:17 INFO - Return code: 0 09:36:17 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 09:36:17 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')]} 09:36:17 INFO - https://queue.taskcluster.net/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:36:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:36:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:36:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/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 09:36:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/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 09:36:19 INFO - Downloaded 18772483 bytes. 09:36:19 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'] 09:36:19 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 09:36:22 INFO - Return code: 0 09:36: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')]} 09:36:22 INFO - https://queue.taskcluster.net/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:36:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:36:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:36:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/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 09:36:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/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 09:36:23 INFO - Downloaded 14403317 bytes. 09:36:23 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'] 09:36:23 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 09:36:24 INFO - Return code: 0 09:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:36:24 INFO - https://queue.taskcluster.net/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:36:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:36:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:36:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:36:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/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 09:36:27 INFO - Downloaded 112444357 bytes. 09:36:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:36:27 INFO - mkdir: /builds/slave/test/properties 09:36:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:36:27 INFO - Writing to file /builds/slave/test/properties/build_url 09:36:27 INFO - Contents: 09:36:27 INFO - build_url:https://queue.taskcluster.net/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:36:28 INFO - Running post-action listener: _resource_record_post_action 09:36:28 INFO - Running post-action listener: set_extra_try_arguments 09:36:28 INFO - ##### 09:36:28 INFO - ##### Finished download-and-extract step (success) 09:36:28 INFO - ##### 09:36:28 INFO - ##### 09:36:28 INFO - ##### Running populate-webroot step. 09:36:28 INFO - ##### 09:36:28 INFO - Running pre-action listener: _resource_record_pre_action 09:36:28 INFO - Running main action method: populate_webroot 09:36:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:36:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:36:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:36:28 INFO - '--tppagecycles', 09:36:28 INFO - '7'], 09:36:28 INFO - 'tests': ['tsvgm']}}, 09:36:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:36:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:36:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:36:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:36:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:36:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:36:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:28 INFO - 'talos_options': ['--disable-e10s'], 09:36:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:28 INFO - 'talos_options': ['--disable-e10s'], 09:36:28 INFO - 'tests': ['damp', 'tps']}, 09:36:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:28 INFO - 'tests': ['damp', 'tps']}, 09:36:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:36:28 INFO - 'tests': ['dromaeo_dom']}, 09:36:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:36:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:36:28 INFO - 'tests': ['basic_compositor_video']}, 09:36:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:36:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:36:28 INFO - 'tests': ['a11yr', 09:36:28 INFO - 'ts_paint', 09:36:28 INFO - 'tpaint', 09:36:28 INFO - 'sessionrestore', 09:36:28 INFO - 'sessionrestore_no_auto_restore', 09:36:28 INFO - 'tabpaint']}, 09:36:28 INFO - 'other-e10s': {'tests': ['a11yr', 09:36:28 INFO - 'ts_paint', 09:36:28 INFO - 'tpaint', 09:36:28 INFO - 'sessionrestore', 09:36:28 INFO - 'sessionrestore_no_auto_restore', 09:36:28 INFO - 'tabpaint']}, 09:36:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:36:28 INFO - 'ts_paint', 09:36:28 INFO - 'tpaint', 09:36:28 INFO - 'sessionrestore', 09:36:28 INFO - 'sessionrestore_no_auto_restore', 09:36:28 INFO - 'tabpaint']}, 09:36:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:36:28 INFO - 'ts_paint', 09:36:28 INFO - 'tpaint', 09:36:28 INFO - 'sessionrestore', 09:36:28 INFO - 'sessionrestore_no_auto_restore', 09:36:28 INFO - 'tabpaint']}, 09:36:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:36:28 INFO - 'tests': ['a11yr', 09:36:28 INFO - 'ts_paint', 09:36:28 INFO - 'tpaint', 09:36:28 INFO - 'sessionrestore', 09:36:28 INFO - 'sessionrestore_no_auto_restore', 09:36:28 INFO - 'tabpaint']}, 09:36:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:36:28 INFO - 'tests': ['a11yr', 09:36:28 INFO - 'ts_paint', 09:36:28 INFO - 'tpaint', 09:36:28 INFO - 'sessionrestore', 09:36:28 INFO - 'sessionrestore_no_auto_restore', 09:36:28 INFO - 'tabpaint']}, 09:36:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:36:28 INFO - 'tests': ['tsvgx', 09:36:28 INFO - 'tsvgr_opacity', 09:36:28 INFO - 'tart', 09:36:28 INFO - 'tscrollx', 09:36:28 INFO - 'cart']}, 09:36:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:36:28 INFO - 'tsvgr_opacity', 09:36:28 INFO - 'tart', 09:36:28 INFO - 'tscrollx', 09:36:28 INFO - 'cart']}, 09:36:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:28 INFO - 'talos_options': ['--disable-e10s'], 09:36:28 INFO - 'tests': ['tp5o']}, 09:36:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:28 INFO - 'tests': ['tp5o']}, 09:36:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:28 INFO - 'talos_options': ['--disable-e10s', 09:36:28 INFO - '--xperf_path', 09:36:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:28 INFO - 'tests': ['tp5n']}, 09:36:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:28 INFO - 'talos_options': ['--xperf_path', 09:36:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:28 INFO - 'tests': ['tp5n']}}, 09:36:28 INFO - 'talos.zip': {'path': '', 09:36:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:36:28 INFO - Running post-action listener: _resource_record_post_action 09:36:28 INFO - ##### 09:36:28 INFO - ##### Finished populate-webroot step (success) 09:36:28 INFO - ##### 09:36:28 INFO - ##### 09:36:28 INFO - ##### Running create-virtualenv step. 09:36:28 INFO - ##### 09:36:28 INFO - Running pre-action listener: _resource_record_pre_action 09:36:28 INFO - Running main action method: create_virtualenv 09:36:28 INFO - Creating virtualenv /builds/slave/test/build/venv 09:36:28 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 09:36:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:36:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:36:28 INFO - Using real prefix '/tools/python27' 09:36:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:36:29 INFO - Installing distribute.............................................................................................................................................................................................done. 09:36:32 INFO - Installing pip.................done. 09:36:32 INFO - Return code: 0 09:36:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:32 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 0x100937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceaacd8d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10089bd30>, '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 0x100600db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100893b20>, '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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:36:32 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 09:36:32 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 09:36:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUQY7QdhTT/Render', 09:36:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:32 INFO - 'HOME': '/Users/cltbld', 09:36:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:32 INFO - 'LOGNAME': 'cltbld', 09:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:32 INFO - 'MOZ_NO_REMOTE': '1', 09:36:32 INFO - 'NO_EM_RESTART': '1', 09:36:32 INFO - 'PAGER': '/bin/cat', 09:36:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:32 INFO - 'PWD': '/builds/slave/test', 09:36:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:32 INFO - 'SHELL': '/bin/bash', 09:36:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLJRKd0Gmm/Listeners', 09:36:32 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:36:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:32 INFO - 'USER': 'cltbld', 09:36:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:32 INFO - 'XPC_FLAGS': '0x0', 09:36:32 INFO - 'XPC_SERVICE_NAME': '0', 09:36:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:32 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 09:36:32 INFO - Cleaning up... 09:36:32 INFO - Return code: 0 09:36:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:32 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 0x100937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceaacd8d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10089bd30>, '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 0x100600db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100893b20>, '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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:36:32 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 09:36:32 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 09:36:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUQY7QdhTT/Render', 09:36:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:32 INFO - 'HOME': '/Users/cltbld', 09:36:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:32 INFO - 'LOGNAME': 'cltbld', 09:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:32 INFO - 'MOZ_NO_REMOTE': '1', 09:36:32 INFO - 'NO_EM_RESTART': '1', 09:36:32 INFO - 'PAGER': '/bin/cat', 09:36:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:32 INFO - 'PWD': '/builds/slave/test', 09:36:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:32 INFO - 'SHELL': '/bin/bash', 09:36:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLJRKd0Gmm/Listeners', 09:36:32 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:36:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:32 INFO - 'USER': 'cltbld', 09:36:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:32 INFO - 'XPC_FLAGS': '0x0', 09:36:32 INFO - 'XPC_SERVICE_NAME': '0', 09:36:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:32 INFO - Downloading/unpacking psutil>=3.1.1 09:36:32 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 09:36:32 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 09:36:32 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 09:36:32 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 09:36:32 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 09:36:32 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 09:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:36:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:35 INFO - Installing collected packages: psutil 09:36:35 INFO - Running setup.py install for psutil 09:36:35 INFO - building 'psutil._psutil_osx' extension 09:36:35 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 09:36:36 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 09:36:36 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 09:36:36 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 09:36:36 INFO - building 'psutil._psutil_posix' extension 09:36:36 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 09:36:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:36:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:36:36 INFO - ^ 09:36:36 INFO - 1 warning generated. 09:36:36 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 09:36:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:36 INFO - Successfully installed psutil 09:36:36 INFO - Cleaning up... 09:36:36 INFO - Return code: 0 09:36:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:36:36 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')]} 09:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:36 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')]} 09:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:36 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 0x100937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceaacd8d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10089bd30>, '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 0x100600db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100893b20>, '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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:36:36 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 09:36:36 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 09:36:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUQY7QdhTT/Render', 09:36:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:36 INFO - 'HOME': '/Users/cltbld', 09:36:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:36 INFO - 'LOGNAME': 'cltbld', 09:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:36 INFO - 'MOZ_NO_REMOTE': '1', 09:36:36 INFO - 'NO_EM_RESTART': '1', 09:36:36 INFO - 'PAGER': '/bin/cat', 09:36:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:36 INFO - 'PWD': '/builds/slave/test', 09:36:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:36 INFO - 'SHELL': '/bin/bash', 09:36:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLJRKd0Gmm/Listeners', 09:36:36 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:36:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:36 INFO - 'USER': 'cltbld', 09:36:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:36 INFO - 'XPC_FLAGS': '0x0', 09:36:36 INFO - 'XPC_SERVICE_NAME': '0', 09:36:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:36:37 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 09:36:37 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 09:36:37 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 09:36:37 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 09:36:37 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 09:36:37 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 09:36:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:36:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:36:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:36:39 INFO - Installing collected packages: mozsystemmonitor 09:36:39 INFO - Running setup.py install for mozsystemmonitor 09:36:39 INFO - Successfully installed mozsystemmonitor 09:36:39 INFO - Cleaning up... 09:36:39 INFO - Return code: 0 09:36:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceaacd8d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10089bd30>, '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 0x100600db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100893b20>, '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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:36:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:36:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:36:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUQY7QdhTT/Render', 09:36:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:39 INFO - 'HOME': '/Users/cltbld', 09:36:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:39 INFO - 'LOGNAME': 'cltbld', 09:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:39 INFO - 'MOZ_NO_REMOTE': '1', 09:36:39 INFO - 'NO_EM_RESTART': '1', 09:36:39 INFO - 'PAGER': '/bin/cat', 09:36:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:39 INFO - 'PWD': '/builds/slave/test', 09:36:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:39 INFO - 'SHELL': '/bin/bash', 09:36:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLJRKd0Gmm/Listeners', 09:36:39 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:36:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:39 INFO - 'USER': 'cltbld', 09:36:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:39 INFO - 'XPC_FLAGS': '0x0', 09:36:39 INFO - 'XPC_SERVICE_NAME': '0', 09:36:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:40 INFO - Downloading/unpacking jsonschema==2.5.1 09:36:40 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 09:36:40 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 09:36:40 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 09:36:40 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 09:36:40 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 09:36:40 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 09:36:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:36:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:36:42 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 09:36:42 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 09:36:42 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 09:36:42 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 09:36:42 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 09:36:42 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 09:36:42 INFO - Downloading functools32-3.2.3-2.tar.gz 09:36:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:36:43 INFO - warning: no files found matching '*.txt' 09:36:43 INFO - no previously-included directories found matching 'build' 09:36:43 INFO - no previously-included directories found matching 'dist' 09:36:43 INFO - no previously-included directories found matching '.git*' 09:36:43 INFO - Installing collected packages: jsonschema, functools32 09:36:43 INFO - Running setup.py install for functools32 09:36:43 INFO - warning: no files found matching '*.txt' 09:36:43 INFO - no previously-included directories found matching 'build' 09:36:43 INFO - no previously-included directories found matching 'dist' 09:36:43 INFO - no previously-included directories found matching '.git*' 09:36:43 INFO - Successfully installed jsonschema functools32 09:36:43 INFO - Cleaning up... 09:36:43 INFO - Return code: 0 09:36:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceaacd8d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10089bd30>, '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 0x100600db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100893b20>, '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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:36:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:36:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUQY7QdhTT/Render', 09:36:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:43 INFO - 'HOME': '/Users/cltbld', 09:36:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:43 INFO - 'LOGNAME': 'cltbld', 09:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:43 INFO - 'MOZ_NO_REMOTE': '1', 09:36:43 INFO - 'NO_EM_RESTART': '1', 09:36:43 INFO - 'PAGER': '/bin/cat', 09:36:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:43 INFO - 'PWD': '/builds/slave/test', 09:36:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:43 INFO - 'SHELL': '/bin/bash', 09:36:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLJRKd0Gmm/Listeners', 09:36:43 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:36:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:43 INFO - 'USER': 'cltbld', 09:36:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:43 INFO - 'XPC_FLAGS': '0x0', 09:36:43 INFO - 'XPC_SERVICE_NAME': '0', 09:36:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:43 INFO - Downloading/unpacking blobuploader==1.2.4 09:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:45 INFO - Downloading blobuploader-1.2.4.tar.gz 09:36:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:36:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:36:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:47 INFO - Downloading docopt-0.6.1.tar.gz 09:36:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:36:47 INFO - Installing collected packages: blobuploader, requests, docopt 09:36:47 INFO - Running setup.py install for blobuploader 09:36:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:36:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:36:47 INFO - Running setup.py install for requests 09:36:47 INFO - Running setup.py install for docopt 09:36:48 INFO - Successfully installed blobuploader requests docopt 09:36:48 INFO - Cleaning up... 09:36:48 INFO - Return code: 0 09:36:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:48 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 0x100937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceaacd8d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10089bd30>, '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 0x100600db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100893b20>, '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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:36:48 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 09:36:48 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 09:36:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUQY7QdhTT/Render', 09:36:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:48 INFO - 'HOME': '/Users/cltbld', 09:36:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:48 INFO - 'LOGNAME': 'cltbld', 09:36:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:48 INFO - 'MOZ_NO_REMOTE': '1', 09:36:48 INFO - 'NO_EM_RESTART': '1', 09:36:48 INFO - 'PAGER': '/bin/cat', 09:36:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:48 INFO - 'PWD': '/builds/slave/test', 09:36:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:48 INFO - 'SHELL': '/bin/bash', 09:36:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLJRKd0Gmm/Listeners', 09:36:48 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:36:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:48 INFO - 'USER': 'cltbld', 09:36:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:48 INFO - 'XPC_FLAGS': '0x0', 09:36:48 INFO - 'XPC_SERVICE_NAME': '0', 09:36:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:48 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-MYhcsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:48 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-nEUmeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:48 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-7lBE2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:48 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-rlsvzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:48 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-o8INNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:48 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-PnERF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:49 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-KYNEgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:49 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-CbTnmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:49 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-2lxkdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:49 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-ooG1pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:49 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-n5I3ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:49 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-hdVMny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:49 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-FrYvVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:49 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-YRKa4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:49 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-Y7vV3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:49 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-_JjHx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:50 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-6T1bYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:36:50 INFO - Running setup.py install for manifestparser 09:36:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:36:50 INFO - Running setup.py install for mozcrash 09:36:50 INFO - Running setup.py install for mozdebug 09:36:50 INFO - Running setup.py install for mozdevice 09:36:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:36:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:36:50 INFO - Running setup.py install for mozfile 09:36:50 INFO - Running setup.py install for mozhttpd 09:36:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:36:51 INFO - Running setup.py install for mozinfo 09:36:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:36:51 INFO - Running setup.py install for mozInstall 09:36:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:36:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:36:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:36:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:36:51 INFO - Running setup.py install for mozleak 09:36:51 INFO - Running setup.py install for mozlog 09:36:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:36:51 INFO - Running setup.py install for moznetwork 09:36:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:36:51 INFO - Running setup.py install for mozprocess 09:36:51 INFO - Running setup.py install for mozprofile 09:36:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Running setup.py install for mozrunner 09:36:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Running setup.py install for mozscreenshot 09:36:52 INFO - Running setup.py install for moztest 09:36:52 INFO - Running setup.py install for mozversion 09:36:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:36:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:36:52 INFO - Cleaning up... 09:36:52 INFO - Return code: 0 09:36:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:52 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 0x100937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceaacd8d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10089bd30>, '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 0x100600db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100893b20>, '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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:36:52 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 09:36:52 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 09:36:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUQY7QdhTT/Render', 09:36:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:52 INFO - 'HOME': '/Users/cltbld', 09:36:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:52 INFO - 'LOGNAME': 'cltbld', 09:36:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:52 INFO - 'MOZ_NO_REMOTE': '1', 09:36:52 INFO - 'NO_EM_RESTART': '1', 09:36:52 INFO - 'PAGER': '/bin/cat', 09:36:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:52 INFO - 'PWD': '/builds/slave/test', 09:36:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:52 INFO - 'SHELL': '/bin/bash', 09:36:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLJRKd0Gmm/Listeners', 09:36:52 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:36:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:52 INFO - 'USER': 'cltbld', 09:36:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:52 INFO - 'XPC_FLAGS': '0x0', 09:36:52 INFO - 'XPC_SERVICE_NAME': '0', 09:36:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:53 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-mnhI6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:53 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)) 09:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:53 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-xwwBM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:53 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)) 09:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:53 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-orMrYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:53 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)) 09:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:53 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-7VpSRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:53 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)) 09:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:53 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-6UkVnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:53 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)) 09:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:53 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-qZsIsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:53 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)) 09:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:53 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-zvUu1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:53 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)) 09:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:53 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-tMyQq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:53 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)) 09:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:53 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-mIQ8Yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:53 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)) 09:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:53 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-7GXJ7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:54 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)) 09:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:54 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-zFBlkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:54 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)) 09:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:54 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-6l07Qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:54 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)) 09:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:54 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-wb5vCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:54 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)) 09:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:54 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-1fTp94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:54 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)) 09:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:54 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-0oF6Nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:54 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)) 09:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:54 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-EwuZxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:54 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)) 09:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:54 INFO - Running setup.py (path:/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/pip-DH3Ql8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:54 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)) 09:36:54 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)) 09:36:54 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)) 09:36:54 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)) 09:36:54 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)) 09:36:54 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)) 09:36:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:36:54 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 09:36:54 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 09:36:54 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 09:36:54 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 09:36:54 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 09:36:54 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 09:36:57 INFO - Downloading blessings-1.6.tar.gz 09:36:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:36:57 INFO - Installing collected packages: blessings 09:36:57 INFO - Running setup.py install for blessings 09:36:57 INFO - Successfully installed blessings 09:36:57 INFO - Cleaning up... 09:36:57 INFO - Return code: 0 09:36:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:36:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:57 INFO - Reading from file tmpfile_stdout 09:36:57 INFO - Current package versions: 09:36:57 INFO - blessings == 1.6 09:36:57 INFO - blobuploader == 1.2.4 09:36:57 INFO - docopt == 0.6.1 09:36:57 INFO - functools32 == 3.2.3-2 09:36:57 INFO - jsonschema == 2.5.1 09:36:57 INFO - manifestparser == 1.1 09:36:57 INFO - mozInstall == 1.12 09:36:57 INFO - mozcrash == 0.17 09:36:57 INFO - mozdebug == 0.1 09:36:57 INFO - mozdevice == 0.48 09:36:57 INFO - mozfile == 1.2 09:36:57 INFO - mozhttpd == 0.7 09:36:57 INFO - mozinfo == 0.9 09:36:57 INFO - mozleak == 0.1 09:36:57 INFO - mozlog == 3.2 09:36:57 INFO - moznetwork == 0.27 09:36:57 INFO - mozprocess == 0.23 09:36:57 INFO - mozprofile == 0.28 09:36:57 INFO - mozrunner == 6.12 09:36:57 INFO - mozscreenshot == 0.1 09:36:57 INFO - mozsystemmonitor == 0.3 09:36:57 INFO - moztest == 0.7 09:36:57 INFO - mozversion == 1.4 09:36:57 INFO - psutil == 3.1.1 09:36:57 INFO - requests == 1.2.3 09:36:57 INFO - wsgiref == 0.1.2 09:36:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:57 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 0x100937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceaacd8d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10089bd30>, '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 0x100600db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100893b20>, '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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:36:57 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 09:36:57 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 09:36:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUQY7QdhTT/Render', 09:36:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:57 INFO - 'HOME': '/Users/cltbld', 09:36:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:57 INFO - 'LOGNAME': 'cltbld', 09:36:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:57 INFO - 'MOZ_NO_REMOTE': '1', 09:36:57 INFO - 'NO_EM_RESTART': '1', 09:36:57 INFO - 'PAGER': '/bin/cat', 09:36:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:57 INFO - 'PWD': '/builds/slave/test', 09:36:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:57 INFO - 'SHELL': '/bin/bash', 09:36:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLJRKd0Gmm/Listeners', 09:36:57 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:36:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:57 INFO - 'USER': 'cltbld', 09:36:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:57 INFO - 'XPC_FLAGS': '0x0', 09:36:57 INFO - 'XPC_SERVICE_NAME': '0', 09:36:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:58 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)) 09:36:58 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)) 09:36:58 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)) 09:36:58 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)) 09:36:58 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)) 09:36:58 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)) 09:36:58 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)) 09:36:58 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)) 09:36:58 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)) 09:36:58 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)) 09:36:58 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)) 09:36:58 INFO - Cleaning up... 09:36:58 INFO - Return code: 0 09:36:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:36:58 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')]} 09:36:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:58 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')]} 09:36:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:58 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 0x100937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceaacd8d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10089bd30>, '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 0x100600db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100893b20>, '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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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 09:36:58 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 09:36:58 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 09:36:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUQY7QdhTT/Render', 09:36:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:58 INFO - 'HOME': '/Users/cltbld', 09:36:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:58 INFO - 'LOGNAME': 'cltbld', 09:36:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:58 INFO - 'MOZ_NO_REMOTE': '1', 09:36:58 INFO - 'NO_EM_RESTART': '1', 09:36:58 INFO - 'PAGER': '/bin/cat', 09:36:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:58 INFO - 'PWD': '/builds/slave/test', 09:36:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:58 INFO - 'SHELL': '/bin/bash', 09:36:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLJRKd0Gmm/Listeners', 09:36:58 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:36:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:58 INFO - 'USER': 'cltbld', 09:36:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:58 INFO - 'XPC_FLAGS': '0x0', 09:36:58 INFO - 'XPC_SERVICE_NAME': '0', 09:36:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:36:58 INFO - Cleaning up... 09:36:58 INFO - Return code: 0 09:36:58 INFO - Running post-action listener: _resource_record_post_action 09:36:58 INFO - Running post-action listener: _start_resource_monitoring 09:36:58 INFO - Starting resource monitoring. 09:36:58 INFO - ##### 09:36:58 INFO - ##### Finished create-virtualenv step (success) 09:36:58 INFO - ##### 09:36:58 INFO - ##### 09:36:58 INFO - ##### Running install step. 09:36:58 INFO - ##### 09:36:58 INFO - Running pre-action listener: _resource_record_pre_action 09:36:58 INFO - Running main action method: install 09:36:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:58 INFO - Reading from file tmpfile_stdout 09:36:58 INFO - Detecting whether we're running mozinstall >=1.0... 09:36:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:36:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:36:58 INFO - Reading from file tmpfile_stdout 09:36:58 INFO - Output received: 09:36:58 INFO - Usage: mozinstall [options] installer 09:36:58 INFO - Options: 09:36:58 INFO - -h, --help show this help message and exit 09:36:58 INFO - -d DEST, --destination=DEST 09:36:58 INFO - Directory to install application into. [default: 09:36:58 INFO - "/builds/slave/test"] 09:36:58 INFO - --app=APP Application being installed. [default: firefox] 09:36:58 INFO - mkdir: /builds/slave/test/build/application 09:36:58 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'] 09:36:58 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 09:37:33 INFO - Reading from file tmpfile_stdout 09:37:33 INFO - Output received: 09:37:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:37:33 INFO - Running post-action listener: _resource_record_post_action 09:37:33 INFO - ##### 09:37:33 INFO - ##### Finished install step (success) 09:37:33 INFO - ##### 09:37:33 INFO - ##### 09:37:33 INFO - ##### Running run-tests step. 09:37:33 INFO - ##### 09:37:33 INFO - Running pre-action listener: _resource_record_pre_action 09:37:33 INFO - Running main action method: run_tests 09:37:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:33 INFO - 2016-07-12 09:37:33.175 screenresolution[1635:7349] starting screenresolution argv=screenresolution get 09:37:33 INFO - 2016-07-12 09:37:33.178 screenresolution[1635:7349] Display 0: 1600x1200x32@60 09:37:33 INFO - 2016-07-12 09:37:33.184 screenresolution[1636:7351] starting screenresolution argv=screenresolution list 09:37:33 INFO - Available Modes on Display 0 09:37:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:33 INFO - Intel Iris: 09:37:33 INFO - Chipset Model: Intel Iris 09:37:33 INFO - Type: GPU 09:37:33 INFO - Bus: Built-In 09:37:33 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:33 INFO - Vendor: Intel (0x8086) 09:37:33 INFO - Device ID: 0x0a2e 09:37:33 INFO - Revision ID: 0x0009 09:37:33 INFO - Displays: 09:37:33 INFO - Display: 09:37:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:33 INFO - Display Serial Number: 1600x1200 60 09:37:33 INFO - Main Display: Yes 09:37:33 INFO - Mirror: Off 09:37:33 INFO - Online: Yes 09:37:33 INFO - Rotation: Supported 09:37:33 INFO - Return code: 0 09:37:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:37:33 INFO - Python 2.7.3 09:37:33 INFO - Return code: 0 09:37:33 INFO - grabbing minidump binary from tooltool 09:37: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')]} 09:37:33 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 0x10089bd30>, '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 0x100600db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100893b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:37:33 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 09:37:33 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 09:37:33 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 09:37:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:37:33 INFO - Return code: 0 09:37:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:37:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:37:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:37:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:37:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:37:33 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/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0126', '--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 09:37:33 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/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0126 --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 09:37:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUQY7QdhTT/Render', 09:37:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:33 INFO - 'HOME': '/Users/cltbld', 09:37:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:33 INFO - 'LOGNAME': 'cltbld', 09:37:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:37:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:33 INFO - 'MOZ_NO_REMOTE': '1', 09:37:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:37:33 INFO - 'NO_EM_RESTART': '1', 09:37:33 INFO - 'PAGER': '/bin/cat', 09:37:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:33 INFO - 'PWD': '/builds/slave/test', 09:37:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:37:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:33 INFO - 'SHELL': '/bin/bash', 09:37:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mLJRKd0Gmm/Listeners', 09:37:33 INFO - 'TMPDIR': '/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/', 09:37:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:33 INFO - 'USER': 'cltbld', 09:37:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:33 INFO - 'XPC_FLAGS': '0x0', 09:37:33 INFO - 'XPC_SERVICE_NAME': '0', 09:37:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:33 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/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0126', '--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 09:37:33 INFO - mozversion application_buildid: 20160712072641 09:37:33 INFO - mozversion application_changeset: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb 09:37:33 INFO - mozversion application_display_name: Nightly 09:37:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:37:33 INFO - mozversion application_name: Firefox 09:37:33 INFO - mozversion application_remotingname: firefox 09:37:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:37:33 INFO - mozversion application_vendor: Mozilla 09:37:33 INFO - mozversion application_version: 50.0a1 09:37:33 INFO - mozversion platform_buildid: 20160712072641 09:37:33 INFO - mozversion platform_changeset: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb 09:37:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:37:33 INFO - mozversion platform_version: 50.0a1 09:37:33 INFO - using testdate: 1468341453 09:37:33 INFO - actual date: 1468341453 09:37:33 INFO - starting webserver on 'localhost:49266' 09:37:33 INFO - SUITE-START | Running 2 tests 09:37:33 INFO - TEST-START | dromaeo_css 09:37:33 INFO - operating with platform_type : mac_ 09:37:33 INFO - Initialising browser for dromaeo_css test... 09:37:33 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmp3Sy3k9/profile http://localhost:49266/getInfo.html) 09:37:36 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 09:37:36 INFO - PROCESS | 1646 | colorDepth:24 09:37:36 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 09:37:36 INFO - PROCESS | 1646 | __metrics 09:37:36 INFO - PROCESS | 1646 | 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" 09:37:36 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:37:36 INFO - TEST-INFO | 1646: exit 0 09:37:36 INFO - Browser initialized. 09:37:36 INFO - Running cycle 1/1 for dromaeo_css test... 09:37:36 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmp3Sy3k9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:48 INFO - PROCESS | 1648 | RSS: Main: 243699712 09:37:48 INFO - PROCESS | 1648 | 09:38:39 INFO - PROCESS | 1648 | 1468341519729 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] 09:40:51 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 09:40:52 INFO - PROCESS | 1648 | RSS: Main: 366166016 09:40:52 INFO - PROCESS | 1648 | 09:43:55 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 09:43:56 INFO - PROCESS | 1648 | RSS: Main: 384061440 09:43:56 INFO - PROCESS | 1648 | 09:46:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 09:46:59 INFO - PROCESS | 1648 | RSS: Main: 370962432 09:46:59 INFO - PROCESS | 1648 | 09:50:01 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 09:50:02 INFO - PROCESS | 1648 | RSS: Main: 369885184 09:50:02 INFO - PROCESS | 1648 | 09:53:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 09:53:06 INFO - PROCESS | 1648 | RSS: Main: 336412672 09:53:06 INFO - PROCESS | 1648 | 09:56:10 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 09:56:10 INFO - PROCESS | 1648 | RSS: Main: 382730240 09:56:10 INFO - PROCESS | 1648 | 09:56:10 INFO - PROCESS | 1648 | __start_tp_report 09:56:10 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 09:56:10 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 09:56:10 INFO - PROCESS | 1648 | |i|pagename|runs| 09:56:10 INFO - PROCESS | 1648 | |0;dojo.html;3335.0328404762618;3350.162459385154;3365.3101114271576;3374.865005399785;3418.3163367326542;5622.662640241589;5655.20827084208;5709.029464990951;5774.335951365589;5775.133729940511;5727.997600419931;5753.65478071315;5763.452472015162;5767.10609855472;5769.3076830780265;5173.818916337929;5207.401148867872;5240.1353776627;5252.264682944391;5303.363596368441;5273.604479664022;5277.023750606131;5292.126799078167;5295.629305948585;5297.814576489824;5198.038362902875;5198.09033419152;5226.529612334889;5245.950809838028;5276.577873770089;5287.841364759044;5307.044731948247;5312.256284120226;5335.946640533583;5336.279602253685;5115.053715062688;5115.488451154854;5121.257417674454;5224.451432599561;5225.686458812484;5642.520385767174;5659.660420374749;5678.659280443146;5718.342390625048;5729.341125770548;6194.25668919728;6201.193844800192;6202.41077097675;6205.3484384139465;6234.283057448406;6098.711721644487;6119.898632333103;6156.7845125420845;6162.075688646694;6185.165002724664;5864.06175011996;5864.76540938362;5866.413358664142;5888.617239879394;5900.586958912921;6214.378562143749;6223.315435302113;6237.4698150657705;6238.875222495525;6244.843878903063;6015.4586087252355;6083.543734219951;6096.573239873255;6142.477889379364;6171.352008039181;6167.290761562388;6170.074488826712;6174.228221472316;6205.193324867739;6206.689665516707;6127.172831667757;6201.162843016135;6220.942439785247;6230.906536401958;6234.1583886174785;6081.391860813883;6109.358517355702;6112.969435152796;6122.9471041527795;6191.721372538246;4816.95183048165;4851.078295123915;4851.369321988119;4854.514548545188;4856.927146092811;4380.496242932104;4417.955820441819;4418.270985287456;4420.58004489573;4434.467863856358;5725.370209276976;5776.249087618667;5794.159846822187;5844.912326315109;5864.06175011996;6099.054646529795;6109.769069418303;6154.969225153845;6162.322144564095;6163.445289923839;5665.2635157430095;5700.714964251771;5789.623674461147;5815.360310365856;5817.272840894888;5400.567954563626;5406.053940560386;5408.891822163535;5412.729363531807;5416.187571864252;6097.796915383721;6184.690765461709;6230.563860529719;6259.061140828912;6272.435480806749;6128.655903792346;6139.508839292756;6164;6165.568410211242;6186.288576813544;6235.438810507076;6243.125962365181;6246.344133865879;6250.656213908279;6273.466755325666;6004.889095517343;6134.601250918676;6139.508839292936;6169.074638804215;6188.1027251049245;4319.5464476229545;6110.5587591967305;6119.197913140417;6135.539834512519;6225.875482490415;5978;5985.850353741192;6043.667598282137;6062.999605065115;6072.574919755575;5876.44173803488;5951.077582974646;6006.9699651503215;6026;6035.486983606443;5860.765569377348;5961.612495187799;5988.401159883977;6058.333583305921;6072.969635151796;6102.4120617988265;6122.225212563356;6123.19365787085;6137.867325793252;6149.508039356931;5645.887082258249;5718.285214348207;5883.823485295448;5953.404659534185;6050.0017497112485 09:56:10 INFO - PROCESS | 1648 | |1;ext.html;478.7558345243926;519.4390058736565;535.0636386323936;535.1758292229966;540.7134218864002;29216.41567168658;30828.845855770716;40863;42291.3656295155;42306.15387692244;43634.56365436345;43910.56089439105;44032;44038.55961440384;44069.33895991563;9626.278029147807;9828.508574571279;9831.950840245789;9844.753881152992;9853.06395892389;8769.166929141753;8957.059508751585;11775.646730598053;11973.760524789543;11995.520179192821;10338.121259692934;12065.698357541089;12133.635990920286;12133.696657583543;12147.817782733222;8697.304215662733;11978.940105299505;12042.39788010596;12065.638030859089;12070.155089143676;8689.869651955225;8917.420367676144;8928.598213080362;11518.308901465938;11807.23252988553;41546.37680434796;41703;41816.79091604553;41874;41933.58066419327;3443.4318337474288;3543.131932676485;3610.3681855675413;3782.92976469366;4244.596763307481;30715.539266911015;31939.84030079858;32019.839900800347;32320.838395808107;32779.6722032779;3323.4682450807995;3327.38443387974;3329.467285234375;3358.395488812013;3393.694567488938;524843;525834;526307;526723;526778;49632.75918102004;60474;60479.09281360783;60617.393826062056;60661;57817.710911445174;57891.42108578861;57909;57935.71032144897;57992.42007579954;15741.449049283448;16251.918740406223;16259.268332925047;16271.59321016984;16272;11391.259568128045;11622.535098595961;12449.004079673605;12646.87353126457;12727.363631818373;15434.842386820808;15452.304646290944;15492.922535387252;15500.767488487681;15679.294431750599;5795.710214489259;5860.970695146582;6205.758848230372;6475.611463312122;6475.7733479328;900.4822227219375;979.1725991537139;999.5202302894651;1010.6412223660642;1110.5002748763088;30600.08199754011;30615.38769224603;30873.382532349675;31006;31097;1116.815725405299;1119.7424592343643;1126.3636045634191;1168.894799468052;1184.6623712242142;14069.2965351732;15440.768388474182;15989.12059836697;16024.919875400548;16055.678886422205;156458;175619;177441;177644.11177944028;177663;59265.703671481366;59371.40628593659;59403.702981484814;59422;59553.106703399484;1271.5549557654747;1383.2461308586844;1410.2314238739707;1422.3386125451714;1424.8646378594017;30716.846415767777;31064.22339441532;31409.21476963094;31649.050528484182;31757.8412107938;47949.04101917872;48027.75986120187;48357.27464088112;48410.03179936311;48486;462356;462406;462809;463912;466197.3380266154;34294.326750982735;35277;39064;40749.592504075765;42308.57691423046;47751;48758.5124148754;48834.26748598703;49158.50841491539;49302.767430814514;38659.03352416212;40077.398839018024;40552;40665;41576.168476630904;39263.28840539039;39356.21287574175;39942.80028599838;40505.59494405018;40680.79659601683;38325.23349532938;38910;39082.60917390903;39200.01999950024;39228.62699805493;34296.14259643529;34775.608975641706;35175.47236791501;35737.463938041474;35763.46354804732;23682.289531314087;25559.105431309814;25561.488770224118;25665.615015774405;25937.481250375266 09:56:10 INFO - PROCESS | 1648 | |2;jquery.html;308.3693846084756;382.5562347676695;391.6494737210199;392.24492851261317;413.1282992885012;15687.980281281709;16461.012339259654;20933.58132837346;21378.679319810217;21396.679049814265;21124.8943755281;21355.252566160187;21686.89156554215;21788.237411690596;21886.781132188673;5329.946700533003;5910.881782364361;6145.078238264251;6154.569180157391;6179.876402471948;4246.4691913510815;4473.686841921067;6053.213082299295;6500.707468163944;6832.555883867532;6071.210742603455;6768.7969360919005;6806.897896531556;6822.215445223788;6846.7945961621235;6595.835104122411;6650.135482387307;6672.199336079701;6679.899801502957;6708.698108585077;3720.21875406165;5546.69493177875;5792.478676919055;5852.063669812809;5859.707014649251;25666.61500077482;26430.6035409469;26797.660116994073;26936.86531567349;27266.863665681743;2383;2419.068658566447;2518.937026574332;2926.3415731460464;3241.9351612967844;14989.400423983028;16009.519714408587;16297.429589964295;16436.75344869828;16715;1900.5058684741928;1971.1425529894398;1974.8420126389854;2000.0599718132455;2055.5169535159225;110758;112060.4396978018;112535.43732281368;112687;115928;10558.04977552024;10808.837867431996;10979.267049203308;11041.668749937515;11205.439728013567;13532.93233533826;13586.252756098509;13609.979251556173;13611.591652250449;13613.86386136126;8321.292690121287;8508.10664880191;8519;8582.970043594809;8602.741917742478;8570.4000719949;8579.356548258906;8589.44168629037;8698.565071746387;8707.521086340312;5639.605227634026;6556.311587283362;6686.665666716644;6687.899681504781;6770.83072923171;1652.8099268584267;1717.9398721044704;1722.6640805043146;1742.9912850435921;1748.720204767231;370.7108455404789;414.94813148356457;431.5835219013667;788.9250521200477;1077.7628921637227;9789.657361992297;11786.705332366759;11889.048876089892;11891.881081189078;12230.021598272117;434.5458995349867;437.12792978009077;452.92300308947557;455.11480171066955;456.4796132409056;5275.868103297448;6082.60463069899;6203.995269154201;6243.906341404882;6244.812655620339;623839;624076;626316;635294;638492;136052;137545;138082;138874;140624.296878517;441.6157942589903;445.44319600499375;449.5302408982621;451.42668810610996;451.56649616368;10265.384076955406;10911.908809119024;11229.27009744364;11497.885021150016;11501.53993840237;51704.482955169966;56017.15974260308;56435.717821412276;56640.43359566516;56798.58003549944;82818.75771863306;84390.57804710937;84954.57522712347;84976;85058.1494185075;6965.825854353681;7357.0404587447465;7375;7978.361731061617;8413.158684131782;9341.719748407559;12401.62795116112;13081.280529570584;13318.600441986757;13364.665883352605;7021.929780702332;9406.623735050794;11238.213325067167;11478;11559.190856639954;11313.943430282796;11373.772524549628;11418.771624567296;11443.48504317291;11600;10645.201609879445;10702.769181544221;11733.471993760131;11888.167828251939;12055.638330850088;5807.506361959281;7460.776176714708;7555.395568354628;8255.339572834278;8496.362772791941;11009.174311926477;11343.716407089889;11551.595694150665;11706.941465292619;11707.590234341757 09:56:10 INFO - PROCESS | 1648 | |3;mootools.html;1545.6213227759197;1581.659943112231;1770.238797317154;1864.6830038893386;1906.5710215201582;8366.958165209173;8676.349273804459;8820.470771753691;8832.20510154086;8832.823343533126;7740.535567865918;7964.561949092812;8359.749207523784;8587.871181932276;8651;2338.462153704649;2357.233898982829;2362.5393048355577;2367.585672507313;2369.455025344172;2327.7323107842562;2329.5923213437663;2377.988110059447;2378.940526486834;2379.5121999990047;2079.386580958613;2165.8159056480217;2281.0989659084735;2350.612148995414;2355.5053438777877;2025.2000459818362;2348.3072493614427;2366.171839856054;2372.3950392649763;2379.464620460383;2341.519988402373;2359.1624973134462;2359.976400235993;2364.6689463475127;2368.46709490365;8747.037825839152;8816.559172041436;8822.2060014599;8839.823203535958;8840.734777956672;5120.001599688062;5179.844604661828;5234.9476505235225;5242.7378631068295;5243.6067294952645;3543.645635436436;3569.8215089245696;3591.6587924147166;3624.58317293512;3635.054885729703;1080.443571560647;1087.9456027198607;1103.906167975723;1110.5502271580024;1110.572429614596;11481;11493.942530287379;11499.022583080448;11503.309801411942;11505.597304094317;4508.571685689854;4539.296409056601;4731.124741922756;4736.100140973204;4745;9752.658656947115;9755.560999755027;9758.902410975941;9760;9764.853527197098;5286.497782710637;5294.2852714883;5307.65500242483;5312.92030619541;5316.308879845596;4717.8112875485285;4857.1742803723455;4868.537488938545;4872.4883887192045;4921.827736029221;3721.1627383838963;3735.6637902588895;3767.1335592813807;3793.943090853639;3824.0822202671156;976.3995142987012;1102.7408558988632;1147.4090843215752;1165.5046605192758;1167.270455965022;725.3073314984177;762.4319881688177;813.6379311206456;872.3370238618695;889.782003409156;5689.459501347365;5861.501772349313;5955.910661340084;6019.217501724747;6043;475.3060531623799;482.7972251654243;496.3497817858617;498.2352089542535;632.9905051424232;3643.9453408198897;3801.1827457096306;3952.8418863245724;4099.426080348753;4133.586641335843;9523.28575356851;10479.21405894561;10537.420441875616;10664.520096595672;10675.786484270426;9652.662156824477;9937.950310248403;10519.737006574896;10601.363918164934;10611.681649550372;7760.611969401507;8016.6392512335915;8122.634481448349;8124.390670699721;8132.064812546633;5867.413258674099;5992.64044157352;6166.013437849922;6200.627962322275;6240.497649876182;9488.762780930532;9585.568649410654;9903.752406189615;9932.957039511037;9936.850947235942;9682.612695492102;10022.148117410103;10025.799484010426;10102.848457273294;10147.985201480089;7764.107127680389;8043.3565314773505;8145.185481451807;8161.632726527439;8286;8252.33981281508;8269.09040005587;8334;8348.916510834813;8353;7280.599567023864;7360.852782944418;7390.852182956418;7423.1834498206235;7646.388288936983;7273.854522909618;7322.96338518304;7327.63361831917;7395.889061663972;7413.703451861865;6950.895736563854;6963;7010.509264351446;7024;7029.472789540702;4636.582707556337;4758.16732071893;4787.952120478751;4860.805567777249;4889.511048895082;4873.269009648581;4911.7298548580175;4912.8771780705765;4914.70511769295;5028.59771218309 09:56:10 INFO - PROCESS | 1648 | |4;prototype.html;301.1642691531001;311.78798417076376;312.96244450665915;318.485645682223;319.88324261644505;5257.86855328617;5319.973400132994;5324.600654950875;5327;5354.973225133878;5236;5239.764210610522;5245.8950820983555;5313.707746073965;5317.776911310401;3805.923881522368;3877.205172939555;3958.7624742515395;4070.796460176994;4092.9181416371653;3960.3663563730083;4038.8788336349958;4049.3318602430563;4062.2890994076065;4080.714574908909;3970.2258059678375;3997.0806714455694;4003.319435695939;4011.1348223076266;4023.4568333274924;3760.699144068468;3808.9809550952205;3813.046738315421;3929.548101968253;3954.901127471822;2904.8983285584904;2917.970820291791;3443.6039855416698;3490.5462289902152;3493.807840568768;4228.4080228768;5104.979004199175;5126.128558145124;5171.715555644438;5211.7133557654315;4226.837619654589;4229.598188172119;4553.089382123589;4566.018306064212;4593.5406459354135;4884.829030983934;5086.753745332418;5556.222128901957;5562.443755624446;5583.832485025456;3329.3840639481773;3550.1302180965827;3822.4075268333454;3825.7321987460887;3827.770333779982;4814.975925120352;5606.24315717377;5804.303483581997;5804.622699524517;5842.24050873388;3821.808909554511;3832.2143960488593;3837.9424308635394;3857.9035524112123;3879.0690234343556;3909.198614284122;3926.6858651307825;3926.9214615708097;3931.6264954829244;3948.309045916953;4274;4391.692581519327;4590.472095709036;4593.517680643551;4625.95374046262;4657.67396282257;5193.870153246124;5605.579581531401;5609.439056094439;5617.556362982034;1226.558438961973;1313.1070871807071;1313.1923866822026;1324.4834514539336;1336.6858788184768;703.6903762344552;721.5490318550906;749.5090715581302;826.8837069955512;834.2866848844272;4668.183067963161;5019.723915184627;5027.114831938024;5078.390593128848;5229.6339256251695;4779.259214821708;5370.812021579226;5642.548596112301;5723.370429252779;5757.955925646335;5044.394672639307;5197.764961066778;5220.686758794409;5227.18978558324;5411.540019098342;2416.8066554675584;2811.917411796432;2846.4449432360493;2972.1677930179585;3027.9243018924703;5885.970570147208;5923.289205295306;5930.525557955353;5972.641641501524;5994.100884867217;5490.56075513958;5493.818828951715;5833.979053665592;5871.266091738533;5880.2943646761805;5023.974880125649;5070.797168113234;5432.483914028161;5597.313289620924;5854.502367298742;4718.669693121586;5102.948970510248;5253.711045892513;5700.372958974593;5772.7402266898935;4510.413646225969;4682.484926657997;5592.328920529562;5618.977168219356;5640.097584386478;4934.605231581537;5803.622764520293;5816.738246778989;5827.73646757817;5867.765289388547;3237.6114866216203;3322.352141332417;3764.774113553086;3770.943435848519;3788.223414200027;5261.947380526146;5528.28623126832;5644.10922341004;5688.975984322861;5720.313562372542;4865.7323847188745;5056.57019153376;5297.5232229099565;5599.440055994368;5607.046802043581;4186.518550366747;4467.709598876063;4775.355327030807;4960.156773348467;5597.44025597437;5262.9210561842365;5377.230726176101;5404.216388623706;5463.672179669233;5519.944800551943;4341.587549182823;5230.29391032221;5423.023855706011;5572.804951826666;5581.325602319369;5094.879126592144;5128.358955130609;5338.3060202173465;5414.350277966511;5441.945580544302 09:56:10 INFO - PROCESS | 1648 | |5;yui.html;745.783722720411;786.8150984518637;841.5539763925121;845.8688903220001;846.420202161519;15094.094354338733;16390.508284751446;16395.098269595168;16398.26207820648;16405.83594164058;14835.480758173466;15048;15059.247037648101;15079.170645614488;15104.01823881447;712.6508011074562;728.2462651156054;735.0811485642946;735.3418690272204;893.6246776353914;726.6584705188553;728.5810658871144;884.2439714044496;913.0367462327242;917.5091326140516;952.599908038622;965.4303960663242;967.6468089147481;968.2641192693533;968.6464440479209;958.2334132693817;959.3045042344314;959.3476436023501;960.0207788056214;960.5869476125264;555.5500044963593;681.9727210911558;704.9083619129481;705.0059416223152;709.3474003916409;14908.403663853433;14945.551633451014;14959.626009349853;14987.550373488813;14994.70010599782;13534.323283835769;13945.233012184326;14001.299935003311;14116.576502704935;14118.435262589484;12487.31319777412;12627.368631568384;12628.948420631728;12644.936775316157;13116.6720831979;4502.421621190651;4556.29377446493;4590;4672.766361681936;4738.739369334685;81278.5616431508;106023.93976060295;107826.46086769595;109981.90018099797;110905.44547277293;10843.891561084445;11141.827343668068;11163.720906977229;11249.268797528135;11474.426278686033;21699.78300217009;22027.889860550593;22073.558528829333;22084.558308834054;22098.668519972212;7561.281678240558;7842.215778422226;8004.5597492138495;8022.0373555173755;8167.060788009455;4131.938020929689;4263.87208383749;4493.438320209973;4519.480259770103;4706.317583950308;3033.9393212136074;3650.178709790329;3652.7443078984215;3654.926901462009;3666.32173047987;684.7706018483764;723.0672432562042;732.1104857597937;732.4909188114209;740.5075624709508;597.2713289786453;678.6945874356559;689.1557841643946;699.3251512290664;704.411816133524;9294.442333460014;9415.576299066559;9782.072675649339;9872.210223182128;10071.648920290489;509.40399732313284;616.6146158650844;656.0028756290412;662.9569078009914;690.413148823496;4489.753063581535;4709.481956984729;4724.00795832879;4732.52674732524;4786.425628924551;131680;140125.2993735025;140548;141670;146123.26938365452;122999;128770.05454536281;129342;130567.34716326547;132074.33962830124;91283.54358228167;110819;111896.44051780015;112722;114475.42762286302;8902.59938302765;8957.238634716123;8969.910300896907;8986;9084.454932704059;56542;61378.693106534185;61646.691766540884;63428.365716344095;64000;58322;66256.6687166561;72199;87846.56076719576;88203;12142.150049496451;12584;12625.494980200689;13435.529756458476;13816.378262977989;15356.385744570092;15356.539303820904;15451.304691289144;15644.296006679499;15972.20138993069;14235.074720143157;14257.42970281177;14335.56993290203;14706.65889538189;14718;13891.652708682364;14176.858231417966;14193.148411095368;14485.493007744679;14622.561323160322;11691.649250522498;13344.799828002393;13790.724185516434;14087.803517718097;14255.429782808571;11239.438028098726;11456.713582160513;11559.479823407799;11804.232724872856;12098.697532561757;11203.775924481628;11255.879492726403;11659.358735269641;11683.415829208676;11697.824532632187 09:56:10 INFO - PROCESS | 1648 | __end_tp_report 09:56:10 INFO - PROCESS | 1648 | __start_cc_report 09:56:10 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1160 09:56:10 INFO - PROCESS | 1648 | __end_cc_report 09:56:10 INFO - PROCESS | 1648 | __startTimestamp1468342570319__endTimestamp 09:56:10 INFO - PROCESS | 1648 | 09:56:10 INFO - PROCESS | 1648 | ------- Summary: start ------- 09:56:10 INFO - PROCESS | 1648 | Number of tests: 6 09:56:10 INFO - PROCESS | 1648 | 09:56:10 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5717.70 Median:5953.40 stddev:624.30 (10.5%) stddev-sans-first:597.58 09:56:10 INFO - PROCESS | 1648 | Values: 3335.0 3350.2 3365.3 3374.9 3418.3 5622.7 5655.2 5709.0 5774.3 5775.1 5728.0 5753.7 5763.5 5767.1 5769.3 5173.8 5207.4 5240.1 5252.3 5303.4 5273.6 5277.0 5292.1 5295.6 5297.8 5198.0 5198.1 5226.5 5246.0 5276.6 5287.8 5307.0 5312.3 5335.9 5336.3 5115.1 5115.5 5121.3 5224.5 5225.7 5642.5 5659.7 5678.7 5718.3 5729.3 6194.3 6201.2 6202.4 6205.3 6234.3 6098.7 6119.9 6156.8 6162.1 6185.2 5864.1 5864.8 5866.4 5888.6 5900.6 6214.4 6223.3 6237.5 6238.9 6244.8 6015.5 6083.5 6096.6 6142.5 6171.4 6167.3 6170.1 6174.2 6205.2 6206.7 6127.2 6201.2 6220.9 6230.9 6234.2 6081.4 6109.4 6113.0 6122.9 6191.7 4817.0 4851.1 4851.4 4854.5 4856.9 4380.5 4418.0 4418.3 4420.6 4434.5 5725.4 5776.2 5794.2 5844.9 5864.1 6099.1 6109.8 6155.0 6162.3 6163.4 5665.3 5700.7 5789.6 5815.4 5817.3 5400.6 5406.1 5408.9 5412.7 5416.2 6097.8 6184.7 6230.6 6259.1 6272.4 6128.7 6139.5 6164.0 6165.6 6186.3 6235.4 6243.1 6246.3 6250.7 6273.5 6004.9 6134.6 6139.5 6169.1 6188.1 4319.5 6110.6 6119.2 6135.5 6225.9 5978.0 5985.9 6043.7 6063.0 6072.6 5876.4 5951.1 6007.0 6026.0 6035.5 5860.8 5961.6 5988.4 6058.3 6073.0 6102.4 6122.2 6123.2 6137.9 6149.5 5645.9 5718.3 5883.8 5953.4 6050.0 09:56:10 INFO - PROCESS | 1648 | 09:56:10 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:55567.37 Median:38784.52 stddev:111260.53 (286.9%) stddev-sans-first:111495.77 09:56:10 INFO - PROCESS | 1648 | Values: 478.8 519.4 535.1 535.2 540.7 29216.4 30828.8 40863.0 42291.4 42306.2 43634.6 43910.6 44032.0 44038.6 44069.3 9626.3 9828.5 9832.0 9844.8 9853.1 8769.2 8957.1 11775.6 11973.8 11995.5 10338.1 12065.7 12133.6 12133.7 12147.8 8697.3 11978.9 12042.4 12065.6 12070.2 8689.9 8917.4 8928.6 11518.3 11807.2 41546.4 41703.0 41816.8 41874.0 41933.6 3443.4 3543.1 3610.4 3782.9 4244.6 30715.5 31939.8 32019.8 32320.8 32779.7 3323.5 3327.4 3329.5 3358.4 3393.7 524843.0 525834.0 526307.0 526723.0 526778.0 49632.8 60474.0 60479.1 60617.4 60661.0 57817.7 57891.4 57909.0 57935.7 57992.4 15741.4 16251.9 16259.3 16271.6 16272.0 11391.3 11622.5 12449.0 12646.9 12727.4 15434.8 15452.3 15492.9 15500.8 15679.3 5795.7 5861.0 6205.8 6475.6 6475.8 900.5 979.2 999.5 1010.6 1110.5 30600.1 30615.4 30873.4 31006.0 31097.0 1116.8 1119.7 1126.4 1168.9 1184.7 14069.3 15440.8 15989.1 16024.9 16055.7 156458.0 175619.0 177441.0 177644.1 177663.0 59265.7 59371.4 59403.7 59422.0 59553.1 1271.6 1383.2 1410.2 1422.3 1424.9 30716.8 31064.2 31409.2 31649.1 31757.8 47949.0 48027.8 48357.3 48410.0 48486.0 462356.0 462406.0 462809.0 463912.0 466197.3 34294.3 35277.0 39064.0 40749.6 42308.6 47751.0 48758.5 48834.3 49158.5 49302.8 38659.0 40077.4 40552.0 40665.0 41576.2 39263.3 39356.2 39942.8 40505.6 40680.8 38325.2 38910.0 39082.6 39200.0 39228.6 34296.1 34775.6 35175.5 35737.5 35763.5 23682.3 25559.1 25561.5 25665.6 25937.5 09:56:10 INFO - PROCESS | 1648 | 09:56:10 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:35865.74 Median:387.10 stddev:105139.56 (27160.6%) stddev-sans-first:105400.60 09:56:10 INFO - PROCESS | 1648 | Values: 308.4 382.6 391.6 392.2 413.1 15688.0 16461.0 20933.6 21378.7 21396.7 21124.9 21355.3 21686.9 21788.2 21886.8 5329.9 5910.9 6145.1 6154.6 6179.9 4246.5 4473.7 6053.2 6500.7 6832.6 6071.2 6768.8 6806.9 6822.2 6846.8 6595.8 6650.1 6672.2 6679.9 6708.7 3720.2 5546.7 5792.5 5852.1 5859.7 25666.6 26430.6 26797.7 26936.9 27266.9 2383.0 2419.1 2518.9 2926.3 3241.9 14989.4 16009.5 16297.4 16436.8 16715.0 1900.5 1971.1 1974.8 2000.1 2055.5 110758.0 112060.4 112535.4 112687.0 115928.0 10558.0 10808.8 10979.3 11041.7 11205.4 13532.9 13586.3 13610.0 13611.6 13613.9 8321.3 8508.1 8519.0 8583.0 8602.7 8570.4 8579.4 8589.4 8698.6 8707.5 5639.6 6556.3 6686.7 6687.9 6770.8 1652.8 1717.9 1722.7 1743.0 1748.7 370.7 414.9 431.6 788.9 1077.8 9789.7 11786.7 11889.0 11891.9 12230.0 434.5 437.1 452.9 455.1 456.5 5275.9 6082.6 6204.0 6243.9 6244.8 623839.0 624076.0 626316.0 635294.0 638492.0 136052.0 137545.0 138082.0 138874.0 140624.3 441.6 445.4 449.5 451.4 451.6 10265.4 10911.9 11229.3 11497.9 11501.5 51704.5 56017.2 56435.7 56640.4 56798.6 82818.8 84390.6 84954.6 84976.0 85058.1 6965.8 7357.0 7375.0 7978.4 8413.2 9341.7 12401.6 13081.3 13318.6 13364.7 7021.9 9406.6 11238.2 11478.0 11559.2 11313.9 11373.8 11418.8 11443.5 11600.0 10645.2 10702.8 11733.5 11888.2 12055.6 5807.5 7460.8 7555.4 8255.3 8496.4 11009.2 11343.7 11551.6 11706.9 11707.6 09:56:10 INFO - PROCESS | 1648 | 09:56:10 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5653.87 Median:2709.09 stddev:3170.83 (117.0%) stddev-sans-first:3164.70 09:56:10 INFO - PROCESS | 1648 | Values: 1545.6 1581.7 1770.2 1864.7 1906.6 8367.0 8676.3 8820.5 8832.2 8832.8 7740.5 7964.6 8359.7 8587.9 8651.0 2338.5 2357.2 2362.5 2367.6 2369.5 2327.7 2329.6 2378.0 2378.9 2379.5 2079.4 2165.8 2281.1 2350.6 2355.5 2025.2 2348.3 2366.2 2372.4 2379.5 2341.5 2359.2 2360.0 2364.7 2368.5 8747.0 8816.6 8822.2 8839.8 8840.7 5120.0 5179.8 5234.9 5242.7 5243.6 3543.6 3569.8 3591.7 3624.6 3635.1 1080.4 1087.9 1103.9 1110.6 1110.6 11481.0 11493.9 11499.0 11503.3 11505.6 4508.6 4539.3 4731.1 4736.1 4745.0 9752.7 9755.6 9758.9 9760.0 9764.9 5286.5 5294.3 5307.7 5312.9 5316.3 4717.8 4857.2 4868.5 4872.5 4921.8 3721.2 3735.7 3767.1 3793.9 3824.1 976.4 1102.7 1147.4 1165.5 1167.3 725.3 762.4 813.6 872.3 889.8 5689.5 5861.5 5955.9 6019.2 6043.0 475.3 482.8 496.3 498.2 633.0 3643.9 3801.2 3952.8 4099.4 4133.6 9523.3 10479.2 10537.4 10664.5 10675.8 9652.7 9938.0 10519.7 10601.4 10611.7 7760.6 8016.6 8122.6 8124.4 8132.1 5867.4 5992.6 6166.0 6200.6 6240.5 9488.8 9585.6 9903.8 9933.0 9936.9 9682.6 10022.1 10025.8 10102.8 10148.0 7764.1 8043.4 8145.2 8161.6 8286.0 8252.3 8269.1 8334.0 8348.9 8353.0 7280.6 7360.9 7390.9 7423.2 7646.4 7273.9 7323.0 7327.6 7395.9 7413.7 6950.9 6963.0 7010.5 7024.0 7029.5 4636.6 4758.2 4788.0 4860.8 4889.5 4873.3 4911.7 4912.9 4914.7 5028.6 09:56:10 INFO - PROCESS | 1648 | 09:56:10 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4459.03 Median:5063.68 stddev:1379.33 (27.2%) stddev-sans-first:1347.43 09:56:10 INFO - PROCESS | 1648 | Values: 301.2 311.8 313.0 318.5 319.9 5257.9 5320.0 5324.6 5327.0 5355.0 5236.0 5239.8 5245.9 5313.7 5317.8 3805.9 3877.2 3958.8 4070.8 4092.9 3960.4 4038.9 4049.3 4062.3 4080.7 3970.2 3997.1 4003.3 4011.1 4023.5 3760.7 3809.0 3813.0 3929.5 3954.9 2904.9 2918.0 3443.6 3490.5 3493.8 4228.4 5105.0 5126.1 5171.7 5211.7 4226.8 4229.6 4553.1 4566.0 4593.5 4884.8 5086.8 5556.2 5562.4 5583.8 3329.4 3550.1 3822.4 3825.7 3827.8 4815.0 5606.2 5804.3 5804.6 5842.2 3821.8 3832.2 3837.9 3857.9 3879.1 3909.2 3926.7 3926.9 3931.6 3948.3 4274.0 4391.7 4590.5 4593.5 4626.0 4657.7 5193.9 5605.6 5609.4 5617.6 1226.6 1313.1 1313.2 1324.5 1336.7 703.7 721.5 749.5 826.9 834.3 4668.2 5019.7 5027.1 5078.4 5229.6 4779.3 5370.8 5642.5 5723.4 5758.0 5044.4 5197.8 5220.7 5227.2 5411.5 2416.8 2811.9 2846.4 2972.2 3027.9 5886.0 5923.3 5930.5 5972.6 5994.1 5490.6 5493.8 5834.0 5871.3 5880.3 5024.0 5070.8 5432.5 5597.3 5854.5 4718.7 5102.9 5253.7 5700.4 5772.7 4510.4 4682.5 5592.3 5619.0 5640.1 4934.6 5803.6 5816.7 5827.7 5867.8 3237.6 3322.4 3764.8 3770.9 3788.2 5261.9 5528.3 5644.1 5689.0 5720.3 4865.7 5056.6 5297.5 5599.4 5607.0 4186.5 4467.7 4775.4 4960.2 5597.4 5262.9 5377.2 5404.2 5463.7 5519.9 4341.6 5230.3 5423.0 5572.8 5581.3 5094.9 5128.4 5338.3 5414.4 5441.9 09:56:10 INFO - PROCESS | 1648 | 09:56:10 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:24076.66 Median:3651.46 stddev:37614.61 (1030.1%) stddev-sans-first:37679.34 09:56:10 INFO - PROCESS | 1648 | Values: 745.8 786.8 841.6 845.9 846.4 15094.1 16390.5 16395.1 16398.3 16405.8 14835.5 15048.0 15059.2 15079.2 15104.0 712.7 728.2 735.1 735.3 893.6 726.7 728.6 884.2 913.0 917.5 952.6 965.4 967.6 968.3 968.6 958.2 959.3 959.3 960.0 960.6 555.6 682.0 704.9 705.0 709.3 14908.4 14945.6 14959.6 14987.6 14994.7 13534.3 13945.2 14001.3 14116.6 14118.4 12487.3 12627.4 12628.9 12644.9 13116.7 4502.4 4556.3 4590.0 4672.8 4738.7 81278.6 106023.9 107826.5 109981.9 110905.4 10843.9 11141.8 11163.7 11249.3 11474.4 21699.8 22027.9 22073.6 22084.6 22098.7 7561.3 7842.2 8004.6 8022.0 8167.1 4131.9 4263.9 4493.4 4519.5 4706.3 3033.9 3650.2 3652.7 3654.9 3666.3 684.8 723.1 732.1 732.5 740.5 597.3 678.7 689.2 699.3 704.4 9294.4 9415.6 9782.1 9872.2 10071.6 509.4 616.6 656.0 663.0 690.4 4489.8 4709.5 4724.0 4732.5 4786.4 131680.0 140125.3 140548.0 141670.0 146123.3 122999.0 128770.1 129342.0 130567.3 132074.3 91283.5 110819.0 111896.4 112722.0 114475.4 8902.6 8957.2 8969.9 8986.0 9084.5 56542.0 61378.7 61646.7 63428.4 64000.0 58322.0 66256.7 72199.0 87846.6 88203.0 12142.2 12584.0 12625.5 13435.5 13816.4 15356.4 15356.5 15451.3 15644.3 15972.2 14235.1 14257.4 14335.6 14706.7 14718.0 13891.7 14176.9 14193.1 14485.5 14622.6 11691.6 13344.8 13790.7 14087.8 14255.4 11239.4 11456.7 11559.5 11804.2 12098.7 11203.8 11255.9 11659.4 11683.4 11697.8 09:56:10 INFO - PROCESS | 1648 | -------- Summary: end -------- 09:56:10 INFO - PROCESS | 1648 | 09:56:10 INFO - TEST-INFO | 1648: exit 0 09:56:10 INFO - TEST-OK | dromaeo_css | took 1117170ms 09:56:10 INFO - TEST-START | kraken 09:56:10 INFO - operating with platform_type : mac_ 09:56:10 INFO - Initialising browser for kraken test... 09:56:10 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmpwfb_a6/profile http://localhost:49266/getInfo.html) 09:56:13 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 09:56:13 INFO - PROCESS | 1658 | colorDepth:24 09:56:13 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 09:56:13 INFO - PROCESS | 1658 | __metrics 09:56:13 INFO - PROCESS | 1658 | 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" 09:56:13 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:56:13 INFO - TEST-INFO | 1658: exit 0 09:56:13 INFO - Browser initialized. 09:56:13 INFO - Running cycle 1/1 for kraken test... 09:56:13 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/T/tmpwfb_a6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:25 INFO - PROCESS | 1659 | RSS: Main: 243699712 09:56:25 INFO - PROCESS | 1659 | 09:56:28 INFO - PROCESS | 1659 | 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) 09:56:29 INFO - PROCESS | 1659 | RSS: Main: 294416384 09:56:29 INFO - PROCESS | 1659 | 09:56:32 INFO - PROCESS | 1659 | 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) 09:56:32 INFO - PROCESS | 1659 | RSS: Main: 304820224 09:56:32 INFO - PROCESS | 1659 | 09:56:36 INFO - PROCESS | 1659 | 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) 09:56:37 INFO - PROCESS | 1659 | RSS: Main: 390008832 09:56:37 INFO - PROCESS | 1659 | 09:56:38 INFO - PROCESS | 1659 | 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) 09:56:39 INFO - PROCESS | 1659 | RSS: Main: 291151872 09:56:39 INFO - PROCESS | 1659 | 09:56:41 INFO - PROCESS | 1659 | 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) 09:56:42 INFO - PROCESS | 1659 | RSS: Main: 295022592 09:56:42 INFO - PROCESS | 1659 | 09:56:44 INFO - PROCESS | 1659 | 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) 09:56:45 INFO - PROCESS | 1659 | RSS: Main: 331264000 09:56:45 INFO - PROCESS | 1659 | 09:56:48 INFO - PROCESS | 1659 | 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) 09:56:49 INFO - PROCESS | 1659 | RSS: Main: 333357056 09:56:49 INFO - PROCESS | 1659 | 09:56:52 INFO - PROCESS | 1659 | 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) 09:56:52 INFO - PROCESS | 1659 | RSS: Main: 330559488 09:56:52 INFO - PROCESS | 1659 | 09:56:54 INFO - PROCESS | 1659 | 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) 09:56:54 INFO - PROCESS | 1659 | RSS: Main: 311603200 09:56:54 INFO - PROCESS | 1659 | 09:56:56 INFO - PROCESS | 1659 | 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) 09:56:57 INFO - PROCESS | 1659 | RSS: Main: 318361600 09:56:57 INFO - PROCESS | 1659 | 09:56:59 INFO - PROCESS | 1659 | 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) 09:57:00 INFO - PROCESS | 1659 | RSS: Main: 319934464 09:57:00 INFO - PROCESS | 1659 | 09:57:03 INFO - PROCESS | 1659 | 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) 09:57:03 INFO - PROCESS | 1659 | RSS: Main: 326111232 09:57:03 INFO - PROCESS | 1659 | 09:57:06 INFO - PROCESS | 1659 | 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) 09:57:07 INFO - PROCESS | 1659 | RSS: Main: 328355840 09:57:07 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | 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) 09:57:09 INFO - PROCESS | 1659 | RSS: Main: 371761152 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | __start_tp_report 09:57:09 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 09:57:09 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 09:57:09 INFO - PROCESS | 1659 | |i|pagename|runs| 09:57:09 INFO - PROCESS | 1659 | |0;ai-astar;100;113;113;112;114;112;113;114;111;114 09:57:09 INFO - PROCESS | 1659 | |1;audio-beat-detection;162;142;132;154;111;134;128;107;157;106 09:57:09 INFO - PROCESS | 1659 | |2;audio-dft;186;186;189;185;185;194;255;184;184;182 09:57:09 INFO - PROCESS | 1659 | |3;audio-fft;77;73;58;108;57;57;64;62;96;56 09:57:09 INFO - PROCESS | 1659 | |4;audio-oscillator;83;91;92;72;70;68;97;70;68;100 09:57:09 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;87;85;89;87;89;85;89;86;89;85 09:57:09 INFO - PROCESS | 1659 | |6;imaging-darkroom;93;93;94;94;92;93;93;95;107;146 09:57:09 INFO - PROCESS | 1659 | |7;imaging-desaturate;88;86;84;87;91;132;139;109;87;87 09:57:09 INFO - PROCESS | 1659 | |8;json-parse-financial;56;56;55;54;54;56;54;55;55;54 09:57:09 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;66;70;82;78;73;71;70;70;71;71 09:57:09 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;86;86;84;82;83;80;84;83;86;80 09:57:09 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;132;134;137;135;134;137;150;143;234;142 09:57:09 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;164;160;200;230;188;163;162;161;163;162 09:57:09 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;67;66;65;65;64;65;66;65;65 09:57:09 INFO - PROCESS | 1659 | __end_tp_report 09:57:09 INFO - PROCESS | 1659 | __start_cc_report 09:57:09 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2043 09:57:09 INFO - PROCESS | 1659 | __end_cc_report 09:57:09 INFO - PROCESS | 1659 | __startTimestamp1468342629419__endTimestamp 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | ------- Summary: start ------- 09:57:09 INFO - PROCESS | 1659 | Number of tests: 14 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:4.20 (3.7%) stddev-sans-first:1.05 09:57:09 INFO - PROCESS | 1659 | Values: 100.0 113.0 113.0 112.0 114.0 112.0 113.0 114.0 111.0 114.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:138.00 stddev:20.69 (15.0%) stddev-sans-first:19.17 09:57:09 INFO - PROCESS | 1659 | Values: 162.0 142.0 132.0 154.0 111.0 134.0 128.0 107.0 157.0 106.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:193.00 Median:186.00 stddev:22.04 (11.8%) stddev-sans-first:23.23 09:57:09 INFO - PROCESS | 1659 | Values: 186.0 186.0 189.0 185.0 185.0 194.0 255.0 184.0 184.0 182.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:70.80 Median:63.00 stddev:18.10 (28.7%) stddev-sans-first:19.06 09:57:09 INFO - PROCESS | 1659 | Values: 77.0 73.0 58.0 108.0 57.0 57.0 64.0 62.0 96.0 56.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:77.50 stddev:12.92 (16.7%) stddev-sans-first:13.69 09:57:09 INFO - PROCESS | 1659 | Values: 83.0 91.0 92.0 72.0 70.0 68.0 97.0 70.0 68.0 100.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:88.00 stddev:1.79 (2.0%) stddev-sans-first:1.90 09:57:09 INFO - PROCESS | 1659 | Values: 87.0 85.0 89.0 87.0 89.0 85.0 89.0 86.0 89.0 85.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:100.00 Median:93.00 stddev:16.74 (18.0%) stddev-sans-first:17.56 09:57:09 INFO - PROCESS | 1659 | Values: 93.0 93.0 94.0 94.0 92.0 93.0 93.0 95.0 107.0 146.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:87.00 stddev:20.55 (23.6%) stddev-sans-first:21.41 09:57:09 INFO - PROCESS | 1659 | Values: 88.0 86.0 84.0 87.0 91.0 132.0 139.0 109.0 87.0 87.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.88 (1.6%) stddev-sans-first:0.83 09:57:09 INFO - PROCESS | 1659 | Values: 56.0 56.0 55.0 54.0 54.0 56.0 54.0 55.0 55.0 54.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:71.00 stddev:4.57 (6.4%) stddev-sans-first:4.26 09:57:09 INFO - PROCESS | 1659 | Values: 66.0 70.0 82.0 78.0 73.0 71.0 70.0 70.0 71.0 71.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:84.00 stddev:2.27 (2.7%) stddev-sans-first:2.20 09:57:09 INFO - PROCESS | 1659 | Values: 86.0 86.0 84.0 82.0 83.0 80.0 84.0 83.0 86.0 80.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:139.50 stddev:30.77 (22.1%) stddev-sans-first:32.10 09:57:09 INFO - PROCESS | 1659 | Values: 132.0 134.0 137.0 135.0 134.0 137.0 150.0 143.0 234.0 142.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:163.50 stddev:23.54 (14.4%) stddev-sans-first:24.61 09:57:09 INFO - PROCESS | 1659 | Values: 164.0 160.0 200.0 230.0 188.0 163.0 162.0 161.0 163.0 162.0 09:57:09 INFO - PROCESS | 1659 | 09:57:09 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.50 stddev:0.97 (1.5%) stddev-sans-first:0.87 09:57:09 INFO - PROCESS | 1659 | Values: 67.0 67.0 66.0 65.0 65.0 64.0 65.0 66.0 65.0 65.0 09:57:09 INFO - PROCESS | 1659 | -------- Summary: end -------- 09:57:09 INFO - PROCESS | 1659 | 09:57:10 INFO - TEST-INFO | 1659: exit 0 09:57:10 INFO - TEST-OK | kraken | took 59307ms 09:57:10 INFO - SUITE-END | took 1176s 09:57:10 INFO - Completed test suite (00:19:36) 09:57:10 INFO - javascript benchmark 09:57:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3335.0328404762618, 3350.162459385154, 3365.3101114271576, 3374.865005399785, 3418.3163367326542, 5622.662640241589, 5655.20827084208, 5709.029464990951, 5774.335951365589, 5775.133729940511, 5727.997600419931, 5753.65478071315, 5763.452472015162, 5767.10609855472, 5769.3076830780265, 5173.818916337929, 5207.401148867872, 5240.1353776627, 5252.264682944391, 5303.363596368441, 5273.604479664022, 5277.023750606131, 5292.126799078167, 5295.629305948585, 5297.814576489824, 5198.038362902875, 5198.09033419152, 5226.529612334889, 5245.950809838028, 5276.577873770089, 5287.841364759044, 5307.044731948247, 5312.256284120226, 5335.946640533583, 5336.279602253685, 5115.053715062688, 5115.488451154854, 5121.257417674454, 5224.451432599561, 5225.686458812484, 5642.520385767174, 5659.660420374749, 5678.659280443146, 5718.342390625048, 5729.341125770548, 6194.25668919728, 6201.193844800192, 6202.41077097675, 6205.3484384139465, 6234.283057448406, 6098.711721644487, 6119.898632333103, 6156.7845125420845, 6162.075688646694, 6185.165002724664, 5864.06175011996, 5864.76540938362, 5866.413358664142, 5888.617239879394, 5900.586958912921, 6214.378562143749, 6223.315435302113, 6237.4698150657705, 6238.875222495525, 6244.843878903063, 6015.4586087252355, 6083.543734219951, 6096.573239873255, 6142.477889379364, 6171.352008039181, 6167.290761562388, 6170.074488826712, 6174.228221472316, 6205.193324867739, 6206.689665516707, 6127.172831667757, 6201.162843016135, 6220.942439785247, 6230.906536401958, 6234.1583886174785, 6081.391860813883, 6109.358517355702, 6112.969435152796, 6122.9471041527795, 6191.721372538246, 4816.95183048165, 4851.078295123915, 4851.369321988119, 4854.514548545188, 4856.927146092811, 4380.496242932104, 4417.955820441819, 4418.270985287456, 4420.58004489573, 4434.467863856358, 5725.370209276976, 5776.249087618667, 5794.159846822187, 5844.912326315109, 5864.06175011996, 6099.054646529795, 6109.769069418303, 6154.969225153845, 6162.322144564095, 6163.445289923839, 5665.2635157430095, 5700.714964251771, 5789.623674461147, 5815.360310365856, 5817.272840894888, 5400.567954563626, 5406.053940560386, 5408.891822163535, 5412.729363531807, 5416.187571864252, 6097.796915383721, 6184.690765461709, 6230.563860529719, 6259.061140828912, 6272.435480806749, 6128.655903792346, 6139.508839292756, 6164.0, 6165.568410211242, 6186.288576813544, 6235.438810507076, 6243.125962365181, 6246.344133865879, 6250.656213908279, 6273.466755325666, 6004.889095517343, 6134.601250918676, 6139.508839292936, 6169.074638804215, 6188.1027251049245, 4319.5464476229545, 6110.5587591967305, 6119.197913140417, 6135.539834512519, 6225.875482490415, 5978.0, 5985.850353741192, 6043.667598282137, 6062.999605065115, 6072.574919755575, 5876.44173803488, 5951.077582974646, 6006.9699651503215, 6026.0, 6035.486983606443, 5860.765569377348, 5961.612495187799, 5988.401159883977, 6058.333583305921, 6072.969635151796, 6102.4120617988265, 6122.225212563356, 6123.19365787085, 6137.867325793252, 6149.508039356931, 5645.887082258249, 5718.285214348207, 5883.823485295448, 5953.404659534185, 6050.0017497112485], "value": 5678.845997481632, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.7558345243926, 519.4390058736565, 535.0636386323936, 535.1758292229966, 540.7134218864002, 29216.41567168658, 30828.845855770716, 40863.0, 42291.3656295155, 42306.15387692244, 43634.56365436345, 43910.56089439105, 44032.0, 44038.55961440384, 44069.33895991563, 9626.278029147807, 9828.508574571279, 9831.950840245789, 9844.753881152992, 9853.06395892389, 8769.166929141753, 8957.059508751585, 11775.646730598053, 11973.760524789543, 11995.520179192821, 10338.121259692934, 12065.698357541089, 12133.635990920286, 12133.696657583543, 12147.817782733222, 8697.304215662733, 11978.940105299505, 12042.39788010596, 12065.638030859089, 12070.155089143676, 8689.869651955225, 8917.420367676144, 8928.598213080362, 11518.308901465938, 11807.23252988553, 41546.37680434796, 41703.0, 41816.79091604553, 41874.0, 41933.58066419327, 3443.4318337474288, 3543.131932676485, 3610.3681855675413, 3782.92976469366, 4244.596763307481, 30715.539266911015, 31939.84030079858, 32019.839900800347, 32320.838395808107, 32779.6722032779, 3323.4682450807995, 3327.38443387974, 3329.467285234375, 3358.395488812013, 3393.694567488938, 524843.0, 525834.0, 526307.0, 526723.0, 526778.0, 49632.75918102004, 60474.0, 60479.09281360783, 60617.393826062056, 60661.0, 57817.710911445174, 57891.42108578861, 57909.0, 57935.71032144897, 57992.42007579954, 15741.449049283448, 16251.918740406223, 16259.268332925047, 16271.59321016984, 16272.0, 11391.259568128045, 11622.535098595961, 12449.004079673605, 12646.87353126457, 12727.363631818373, 15434.842386820808, 15452.304646290944, 15492.922535387252, 15500.767488487681, 15679.294431750599, 5795.710214489259, 5860.970695146582, 6205.758848230372, 6475.611463312122, 6475.7733479328, 900.4822227219375, 979.1725991537139, 999.5202302894651, 1010.6412223660642, 1110.5002748763088, 30600.08199754011, 30615.38769224603, 30873.382532349675, 31006.0, 31097.0, 1116.815725405299, 1119.7424592343643, 1126.3636045634191, 1168.894799468052, 1184.6623712242142, 14069.2965351732, 15440.768388474182, 15989.12059836697, 16024.919875400548, 16055.678886422205, 156458.0, 175619.0, 177441.0, 177644.11177944028, 177663.0, 59265.703671481366, 59371.40628593659, 59403.702981484814, 59422.0, 59553.106703399484, 1271.5549557654747, 1383.2461308586844, 1410.2314238739707, 1422.3386125451714, 1424.8646378594017, 30716.846415767777, 31064.22339441532, 31409.21476963094, 31649.050528484182, 31757.8412107938, 47949.04101917872, 48027.75986120187, 48357.27464088112, 48410.03179936311, 48486.0, 462356.0, 462406.0, 462809.0, 463912.0, 466197.3380266154, 34294.326750982735, 35277.0, 39064.0, 40749.592504075765, 42308.57691423046, 47751.0, 48758.5124148754, 48834.26748598703, 49158.50841491539, 49302.767430814514, 38659.03352416212, 40077.398839018024, 40552.0, 40665.0, 41576.168476630904, 39263.28840539039, 39356.21287574175, 39942.80028599838, 40505.59494405018, 40680.79659601683, 38325.23349532938, 38910.0, 39082.60917390903, 39200.01999950024, 39228.62699805493, 34296.14259643529, 34775.608975641706, 35175.47236791501, 35737.463938041474, 35763.46354804732, 23682.289531314087, 25559.105431309814, 25561.488770224118, 25665.615015774405, 25937.481250375266], "value": 19668.800969019812, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.3693846084756, 382.5562347676695, 391.6494737210199, 392.24492851261317, 413.1282992885012, 15687.980281281709, 16461.012339259654, 20933.58132837346, 21378.679319810217, 21396.679049814265, 21124.8943755281, 21355.252566160187, 21686.89156554215, 21788.237411690596, 21886.781132188673, 5329.946700533003, 5910.881782364361, 6145.078238264251, 6154.569180157391, 6179.876402471948, 4246.4691913510815, 4473.686841921067, 6053.213082299295, 6500.707468163944, 6832.555883867532, 6071.210742603455, 6768.7969360919005, 6806.897896531556, 6822.215445223788, 6846.7945961621235, 6595.835104122411, 6650.135482387307, 6672.199336079701, 6679.899801502957, 6708.698108585077, 3720.21875406165, 5546.69493177875, 5792.478676919055, 5852.063669812809, 5859.707014649251, 25666.61500077482, 26430.6035409469, 26797.660116994073, 26936.86531567349, 27266.863665681743, 2383.0, 2419.068658566447, 2518.937026574332, 2926.3415731460464, 3241.9351612967844, 14989.400423983028, 16009.519714408587, 16297.429589964295, 16436.75344869828, 16715.0, 1900.5058684741928, 1971.1425529894398, 1974.8420126389854, 2000.0599718132455, 2055.5169535159225, 110758.0, 112060.4396978018, 112535.43732281368, 112687.0, 115928.0, 10558.04977552024, 10808.837867431996, 10979.267049203308, 11041.668749937515, 11205.439728013567, 13532.93233533826, 13586.252756098509, 13609.979251556173, 13611.591652250449, 13613.86386136126, 8321.292690121287, 8508.10664880191, 8519.0, 8582.970043594809, 8602.741917742478, 8570.4000719949, 8579.356548258906, 8589.44168629037, 8698.565071746387, 8707.521086340312, 5639.605227634026, 6556.311587283362, 6686.665666716644, 6687.899681504781, 6770.83072923171, 1652.8099268584267, 1717.9398721044704, 1722.6640805043146, 1742.9912850435921, 1748.720204767231, 370.7108455404789, 414.94813148356457, 431.5835219013667, 788.9250521200477, 1077.7628921637227, 9789.657361992297, 11786.705332366759, 11889.048876089892, 11891.881081189078, 12230.021598272117, 434.5458995349867, 437.12792978009077, 452.92300308947557, 455.11480171066955, 456.4796132409056, 5275.868103297448, 6082.60463069899, 6203.995269154201, 6243.906341404882, 6244.812655620339, 623839.0, 624076.0, 626316.0, 635294.0, 638492.0, 136052.0, 137545.0, 138082.0, 138874.0, 140624.296878517, 441.6157942589903, 445.44319600499375, 449.5302408982621, 451.42668810610996, 451.56649616368, 10265.384076955406, 10911.908809119024, 11229.27009744364, 11497.885021150016, 11501.53993840237, 51704.482955169966, 56017.15974260308, 56435.717821412276, 56640.43359566516, 56798.58003549944, 82818.75771863306, 84390.57804710937, 84954.57522712347, 84976.0, 85058.1494185075, 6965.825854353681, 7357.0404587447465, 7375.0, 7978.361731061617, 8413.158684131782, 9341.719748407559, 12401.62795116112, 13081.280529570584, 13318.600441986757, 13364.665883352605, 7021.929780702332, 9406.623735050794, 11238.213325067167, 11478.0, 11559.190856639954, 11313.943430282796, 11373.772524549628, 11418.771624567296, 11443.48504317291, 11600.0, 10645.201609879445, 10702.769181544221, 11733.471993760131, 11888.167828251939, 12055.638330850088, 5807.506361959281, 7460.776176714708, 7555.395568354628, 8255.339572834278, 8496.362772791941, 11009.174311926477, 11343.716407089889, 11551.595694150665, 11706.941465292619, 11707.590234341757], "value": 8916.712880729403, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1545.6213227759197, 1581.659943112231, 1770.238797317154, 1864.6830038893386, 1906.5710215201582, 8366.958165209173, 8676.349273804459, 8820.470771753691, 8832.20510154086, 8832.823343533126, 7740.535567865918, 7964.561949092812, 8359.749207523784, 8587.871181932276, 8651.0, 2338.462153704649, 2357.233898982829, 2362.5393048355577, 2367.585672507313, 2369.455025344172, 2327.7323107842562, 2329.5923213437663, 2377.988110059447, 2378.940526486834, 2379.5121999990047, 2079.386580958613, 2165.8159056480217, 2281.0989659084735, 2350.612148995414, 2355.5053438777877, 2025.2000459818362, 2348.3072493614427, 2366.171839856054, 2372.3950392649763, 2379.464620460383, 2341.519988402373, 2359.1624973134462, 2359.976400235993, 2364.6689463475127, 2368.46709490365, 8747.037825839152, 8816.559172041436, 8822.2060014599, 8839.823203535958, 8840.734777956672, 5120.001599688062, 5179.844604661828, 5234.9476505235225, 5242.7378631068295, 5243.6067294952645, 3543.645635436436, 3569.8215089245696, 3591.6587924147166, 3624.58317293512, 3635.054885729703, 1080.443571560647, 1087.9456027198607, 1103.906167975723, 1110.5502271580024, 1110.572429614596, 11481.0, 11493.942530287379, 11499.022583080448, 11503.309801411942, 11505.597304094317, 4508.571685689854, 4539.296409056601, 4731.124741922756, 4736.100140973204, 4745.0, 9752.658656947115, 9755.560999755027, 9758.902410975941, 9760.0, 9764.853527197098, 5286.497782710637, 5294.2852714883, 5307.65500242483, 5312.92030619541, 5316.308879845596, 4717.8112875485285, 4857.1742803723455, 4868.537488938545, 4872.4883887192045, 4921.827736029221, 3721.1627383838963, 3735.6637902588895, 3767.1335592813807, 3793.943090853639, 3824.0822202671156, 976.3995142987012, 1102.7408558988632, 1147.4090843215752, 1165.5046605192758, 1167.270455965022, 725.3073314984177, 762.4319881688177, 813.6379311206456, 872.3370238618695, 889.782003409156, 5689.459501347365, 5861.501772349313, 5955.910661340084, 6019.217501724747, 6043.0, 475.3060531623799, 482.7972251654243, 496.3497817858617, 498.2352089542535, 632.9905051424232, 3643.9453408198897, 3801.1827457096306, 3952.8418863245724, 4099.426080348753, 4133.586641335843, 9523.28575356851, 10479.21405894561, 10537.420441875616, 10664.520096595672, 10675.786484270426, 9652.662156824477, 9937.950310248403, 10519.737006574896, 10601.363918164934, 10611.681649550372, 7760.611969401507, 8016.6392512335915, 8122.634481448349, 8124.390670699721, 8132.064812546633, 5867.413258674099, 5992.64044157352, 6166.013437849922, 6200.627962322275, 6240.497649876182, 9488.762780930532, 9585.568649410654, 9903.752406189615, 9932.957039511037, 9936.850947235942, 9682.612695492102, 10022.148117410103, 10025.799484010426, 10102.848457273294, 10147.985201480089, 7764.107127680389, 8043.3565314773505, 8145.185481451807, 8161.632726527439, 8286.0, 8252.33981281508, 8269.09040005587, 8334.0, 8348.916510834813, 8353.0, 7280.599567023864, 7360.852782944418, 7390.852182956418, 7423.1834498206235, 7646.388288936983, 7273.854522909618, 7322.96338518304, 7327.63361831917, 7395.889061663972, 7413.703451861865, 6950.895736563854, 6963.0, 7010.509264351446, 7024.0, 7029.472789540702, 4636.582707556337, 4758.16732071893, 4787.952120478751, 4860.805567777249, 4889.511048895082, 4873.269009648581, 4911.7298548580175, 4912.8771780705765, 4914.70511769295, 5028.59771218309], "value": 4470.670430061625, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.1642691531001, 311.78798417076376, 312.96244450665915, 318.485645682223, 319.88324261644505, 5257.86855328617, 5319.973400132994, 5324.600654950875, 5327.0, 5354.973225133878, 5236.0, 5239.764210610522, 5245.8950820983555, 5313.707746073965, 5317.776911310401, 3805.923881522368, 3877.205172939555, 3958.7624742515395, 4070.796460176994, 4092.9181416371653, 3960.3663563730083, 4038.8788336349958, 4049.3318602430563, 4062.2890994076065, 4080.714574908909, 3970.2258059678375, 3997.0806714455694, 4003.319435695939, 4011.1348223076266, 4023.4568333274924, 3760.699144068468, 3808.9809550952205, 3813.046738315421, 3929.548101968253, 3954.901127471822, 2904.8983285584904, 2917.970820291791, 3443.6039855416698, 3490.5462289902152, 3493.807840568768, 4228.4080228768, 5104.979004199175, 5126.128558145124, 5171.715555644438, 5211.7133557654315, 4226.837619654589, 4229.598188172119, 4553.089382123589, 4566.018306064212, 4593.5406459354135, 4884.829030983934, 5086.753745332418, 5556.222128901957, 5562.443755624446, 5583.832485025456, 3329.3840639481773, 3550.1302180965827, 3822.4075268333454, 3825.7321987460887, 3827.770333779982, 4814.975925120352, 5606.24315717377, 5804.303483581997, 5804.622699524517, 5842.24050873388, 3821.808909554511, 3832.2143960488593, 3837.9424308635394, 3857.9035524112123, 3879.0690234343556, 3909.198614284122, 3926.6858651307825, 3926.9214615708097, 3931.6264954829244, 3948.309045916953, 4274.0, 4391.692581519327, 4590.472095709036, 4593.517680643551, 4625.95374046262, 4657.67396282257, 5193.870153246124, 5605.579581531401, 5609.439056094439, 5617.556362982034, 1226.558438961973, 1313.1070871807071, 1313.1923866822026, 1324.4834514539336, 1336.6858788184768, 703.6903762344552, 721.5490318550906, 749.5090715581302, 826.8837069955512, 834.2866848844272, 4668.183067963161, 5019.723915184627, 5027.114831938024, 5078.390593128848, 5229.6339256251695, 4779.259214821708, 5370.812021579226, 5642.548596112301, 5723.370429252779, 5757.955925646335, 5044.394672639307, 5197.764961066778, 5220.686758794409, 5227.18978558324, 5411.540019098342, 2416.8066554675584, 2811.917411796432, 2846.4449432360493, 2972.1677930179585, 3027.9243018924703, 5885.970570147208, 5923.289205295306, 5930.525557955353, 5972.641641501524, 5994.100884867217, 5490.56075513958, 5493.818828951715, 5833.979053665592, 5871.266091738533, 5880.2943646761805, 5023.974880125649, 5070.797168113234, 5432.483914028161, 5597.313289620924, 5854.502367298742, 4718.669693121586, 5102.948970510248, 5253.711045892513, 5700.372958974593, 5772.7402266898935, 4510.413646225969, 4682.484926657997, 5592.328920529562, 5618.977168219356, 5640.097584386478, 4934.605231581537, 5803.622764520293, 5816.738246778989, 5827.73646757817, 5867.765289388547, 3237.6114866216203, 3322.352141332417, 3764.774113553086, 3770.943435848519, 3788.223414200027, 5261.947380526146, 5528.28623126832, 5644.10922341004, 5688.975984322861, 5720.313562372542, 4865.7323847188745, 5056.57019153376, 5297.5232229099565, 5599.440055994368, 5607.046802043581, 4186.518550366747, 4467.709598876063, 4775.355327030807, 4960.156773348467, 5597.44025597437, 5262.9210561842365, 5377.230726176101, 5404.216388623706, 5463.672179669233, 5519.944800551943, 4341.587549182823, 5230.29391032221, 5423.023855706011, 5572.804951826666, 5581.325602319369, 5094.879126592144, 5128.358955130609, 5338.3060202173465, 5414.350277966511, 5441.945580544302], "value": 4013.3108379102086, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.783722720411, 786.8150984518637, 841.5539763925121, 845.8688903220001, 846.420202161519, 15094.094354338733, 16390.508284751446, 16395.098269595168, 16398.26207820648, 16405.83594164058, 14835.480758173466, 15048.0, 15059.247037648101, 15079.170645614488, 15104.01823881447, 712.6508011074562, 728.2462651156054, 735.0811485642946, 735.3418690272204, 893.6246776353914, 726.6584705188553, 728.5810658871144, 884.2439714044496, 913.0367462327242, 917.5091326140516, 952.599908038622, 965.4303960663242, 967.6468089147481, 968.2641192693533, 968.6464440479209, 958.2334132693817, 959.3045042344314, 959.3476436023501, 960.0207788056214, 960.5869476125264, 555.5500044963593, 681.9727210911558, 704.9083619129481, 705.0059416223152, 709.3474003916409, 14908.403663853433, 14945.551633451014, 14959.626009349853, 14987.550373488813, 14994.70010599782, 13534.323283835769, 13945.233012184326, 14001.299935003311, 14116.576502704935, 14118.435262589484, 12487.31319777412, 12627.368631568384, 12628.948420631728, 12644.936775316157, 13116.6720831979, 4502.421621190651, 4556.29377446493, 4590.0, 4672.766361681936, 4738.739369334685, 81278.5616431508, 106023.93976060295, 107826.46086769595, 109981.90018099797, 110905.44547277293, 10843.891561084445, 11141.827343668068, 11163.720906977229, 11249.268797528135, 11474.426278686033, 21699.78300217009, 22027.889860550593, 22073.558528829333, 22084.558308834054, 22098.668519972212, 7561.281678240558, 7842.215778422226, 8004.5597492138495, 8022.0373555173755, 8167.060788009455, 4131.938020929689, 4263.87208383749, 4493.438320209973, 4519.480259770103, 4706.317583950308, 3033.9393212136074, 3650.178709790329, 3652.7443078984215, 3654.926901462009, 3666.32173047987, 684.7706018483764, 723.0672432562042, 732.1104857597937, 732.4909188114209, 740.5075624709508, 597.2713289786453, 678.6945874356559, 689.1557841643946, 699.3251512290664, 704.411816133524, 9294.442333460014, 9415.576299066559, 9782.072675649339, 9872.210223182128, 10071.648920290489, 509.40399732313284, 616.6146158650844, 656.0028756290412, 662.9569078009914, 690.413148823496, 4489.753063581535, 4709.481956984729, 4724.00795832879, 4732.52674732524, 4786.425628924551, 131680.0, 140125.2993735025, 140548.0, 141670.0, 146123.26938365452, 122999.0, 128770.05454536281, 129342.0, 130567.34716326547, 132074.33962830124, 91283.54358228167, 110819.0, 111896.44051780015, 112722.0, 114475.42762286302, 8902.59938302765, 8957.238634716123, 8969.910300896907, 8986.0, 9084.454932704059, 56542.0, 61378.693106534185, 61646.691766540884, 63428.365716344095, 64000.0, 58322.0, 66256.6687166561, 72199.0, 87846.56076719576, 88203.0, 12142.150049496451, 12584.0, 12625.494980200689, 13435.529756458476, 13816.378262977989, 15356.385744570092, 15356.539303820904, 15451.304691289144, 15644.296006679499, 15972.20138993069, 14235.074720143157, 14257.42970281177, 14335.56993290203, 14706.65889538189, 14718.0, 13891.652708682364, 14176.858231417966, 14193.148411095368, 14485.493007744679, 14622.561323160322, 11691.649250522498, 13344.799828002393, 13790.724185516434, 14087.803517718097, 14255.429782808571, 11239.438028098726, 11456.713582160513, 11559.479823407799, 11804.232724872856, 12098.697532561757, 11203.775924481628, 11255.879492726403, 11659.358735269641, 11683.415829208676, 11697.824532632187], "value": 7941.771849357487, "unit": "score"}], "name": "dromaeo_css", "value": 7222.380307316577, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 113.0, 112.0, 114.0, 112.0, 113.0, 114.0, 111.0, 114.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 142.0, 132.0, 154.0, 111.0, 134.0, 128.0, 107.0, 157.0, 106.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 189.0, 185.0, 185.0, 194.0, 255.0, 184.0, 184.0, 182.0], "value": 193.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 58.0, 108.0, 57.0, 57.0, 64.0, 62.0, 96.0, 56.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 91.0, 92.0, 72.0, 70.0, 68.0, 97.0, 70.0, 68.0, 100.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 87.0, 89.0, 85.0, 89.0, 86.0, 89.0, 85.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 92.0, 93.0, 93.0, 95.0, 107.0, 146.0], "value": 100.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 84.0, 87.0, 91.0, 132.0, 139.0, 109.0, 87.0, 87.0], "value": 99.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 54.0, 54.0, 56.0, 54.0, 55.0, 55.0, 54.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 82.0, 78.0, 73.0, 71.0, 70.0, 70.0, 71.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 82.0, 83.0, 80.0, 84.0, 83.0, 86.0, 80.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 134.0, 137.0, 135.0, 134.0, 137.0, 150.0, 143.0, 234.0, 142.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 200.0, 230.0, 188.0, 163.0, 162.0, 161.0, 163.0, 162.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 65.0, 65.0, 64.0, 65.0, 66.0, 65.0, 65.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1475.0, "alertThreshold": 2.0}]} 09:57:10 INFO - Return code: 0 09:57:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:57:10 INFO - # TBPL SUCCESS # 09:57:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:57:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:57:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:57:10 INFO - 2016-07-12 09:57:10.746 screenresolution[1663:9583] starting screenresolution argv=screenresolution get 09:57:10 INFO - 2016-07-12 09:57:10.748 screenresolution[1663:9583] Display 0: 1600x1200x32@60 09:57:10 INFO - 2016-07-12 09:57:10.754 screenresolution[1664:9586] starting screenresolution argv=screenresolution list 09:57:10 INFO - Available Modes on Display 0 09:57:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:57:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:57:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:57:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:57:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:57:10 INFO - Intel Iris: 09:57:10 INFO - Chipset Model: Intel Iris 09:57:10 INFO - Type: GPU 09:57:10 INFO - Bus: Built-In 09:57:10 INFO - VRAM (Dynamic, Max): 1536 MB 09:57:10 INFO - Vendor: Intel (0x8086) 09:57:10 INFO - Device ID: 0x0a2e 09:57:10 INFO - Revision ID: 0x0009 09:57:10 INFO - Displays: 09:57:10 INFO - Display: 09:57:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:57:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:57:10 INFO - Display Serial Number: 1600x1200 60 09:57:10 INFO - Main Display: Yes 09:57:10 INFO - Mirror: Off 09:57:10 INFO - Online: Yes 09:57:10 INFO - Rotation: Supported 09:57:10 INFO - Return code: 0 09:57:10 INFO - Running post-action listener: _resource_record_post_action 09:57:10 INFO - ##### 09:57:10 INFO - ##### Finished run-tests step (success) 09:57:10 INFO - ##### 09:57:10 INFO - Running post-run listener: _resource_record_post_run 09:57:11 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 38065664; Write bytes: 705805312; Read time: 396; Write time: 2021 09:57:11 INFO - TinderboxPrint: CPU usage
53.5% 09:57:11 INFO - TinderboxPrint: I/O read bytes / time
38,065,664 / 396 09:57:11 INFO - TinderboxPrint: I/O write bytes / time
705,805,312 / 2,021 09:57:11 INFO - TinderboxPrint: CPU idle
2,256.9 (46.5%) 09:57:11 INFO - TinderboxPrint: CPU system
967.7 (20.0%) 09:57:11 INFO - TinderboxPrint: CPU user
1,624.9 (33.5%) 09:57:11 INFO - TinderboxPrint: Swap in / out
444,301,312 / 0 09:57:11 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391854592; Write bytes: 387274752; Read time: 29431; Write time: 771 09:57:11 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 37099008; Write bytes: 295498752; Read time: 380; Write time: 1203 09:57:11 INFO - Running post-run listener: _upload_blobber_files 09:57:11 INFO - Blob upload gear active. 09:57:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:57:11 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 09:57:11 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'] 09:57:11 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 09:57:11 INFO - (blobuploader) - INFO - Open directory for files ... 09:57:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:57:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:57:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:12 INFO - (blobuploader) - INFO - Done attempting. 09:57:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:57:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:57:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:13 INFO - (blobuploader) - INFO - Done attempting. 09:57:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:57:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:57:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:57:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:16 INFO - (blobuploader) - INFO - Done attempting. 09:57:16 INFO - (blobuploader) - INFO - Iteration through files over. 09:57:16 INFO - Return code: 0 09:57:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:57:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:57:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a14711d50e183c17ed483912aa92c2d532c7cff67233b0251c853ad9c26d9a54256cbbafe9c01fe7b319d3f4950d3a481e8ecc6ba4a37d232c8021e133806c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0810f1f18668d0dbb8f65b9f4e29f6187aeae88a78837073fdd0a4730ae199eb75a335df744116314f73b098ae78138f3d970c9f53991b2837fb846eb3a1fa6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24ab4ebe4cd587bd6fa3ccfacdb825e1e4160661285cbf7b6e428a366735685f7d9e0cefccaa996e86b94404769a3b7915dc65d3555f2b3eeda3b07bc1585528"} 09:57:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:57:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:57:16 INFO - Contents: 09:57:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a14711d50e183c17ed483912aa92c2d532c7cff67233b0251c853ad9c26d9a54256cbbafe9c01fe7b319d3f4950d3a481e8ecc6ba4a37d232c8021e133806c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0810f1f18668d0dbb8f65b9f4e29f6187aeae88a78837073fdd0a4730ae199eb75a335df744116314f73b098ae78138f3d970c9f53991b2837fb846eb3a1fa6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24ab4ebe4cd587bd6fa3ccfacdb825e1e4160661285cbf7b6e428a366735685f7d9e0cefccaa996e86b94404769a3b7915dc65d3555f2b3eeda3b07bc1585528"} 09:57:16 INFO - Running post-run listener: copy_logs_to_upload_dir 09:57:16 INFO - Copying logs to upload dir... 09:57:16 INFO - mkdir: /builds/slave/test/build/upload/logs 09:57:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.117104 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-12 09:57:16.280630) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 09:57:16.284505) ========= 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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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/3a14711d50e183c17ed483912aa92c2d532c7cff67233b0251c853ad9c26d9a54256cbbafe9c01fe7b319d3f4950d3a481e8ecc6ba4a37d232c8021e133806c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0810f1f18668d0dbb8f65b9f4e29f6187aeae88a78837073fdd0a4730ae199eb75a335df744116314f73b098ae78138f3d970c9f53991b2837fb846eb3a1fa6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24ab4ebe4cd587bd6fa3ccfacdb825e1e4160661285cbf7b6e428a366735685f7d9e0cefccaa996e86b94404769a3b7915dc65d3555f2b3eeda3b07bc1585528"} build_url:https://queue.taskcluster.net/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010555 build_url: 'https://queue.taskcluster.net/v1/task/U0kpDf_fTmyHH8AVSmrnxQ/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/3a14711d50e183c17ed483912aa92c2d532c7cff67233b0251c853ad9c26d9a54256cbbafe9c01fe7b319d3f4950d3a481e8ecc6ba4a37d232c8021e133806c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0810f1f18668d0dbb8f65b9f4e29f6187aeae88a78837073fdd0a4730ae199eb75a335df744116314f73b098ae78138f3d970c9f53991b2837fb846eb3a1fa6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24ab4ebe4cd587bd6fa3ccfacdb825e1e4160661285cbf7b6e428a366735685f7d9e0cefccaa996e86b94404769a3b7915dc65d3555f2b3eeda3b07bc1585528"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 09:57:16.318400) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:57:16.318948) ========= 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.BUQY7QdhTT/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.mLJRKd0Gmm/Listeners TMPDIR=/var/folders/_w/5sv6_fds1bq91k_r0pg26gd800000w/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.005108 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:57:16.367978) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 09:57:16.368339) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 09:57:16.368683) ========= ========= Total master_lag: 0.18 =========