Xen project Mailing List

Re: [Xen-API] connection to pool master is buggy

To: Jonathan Ludlam <Jonathan.Ludlam@xxxxxxxxxx>, 'dobropalka' <dobropalka@xxxxxxxxx>, "xen-api@xxxxxxxxxxxxx" <xen-api@xxxxxxxxxxxxx>

From: Thomas Sanders <thomas.sanders@xxxxxxxxxx>

Date: Wed, 9 Jul 2014 08:46:24 +0000

Accept-language: en-GB, en-US

Delivery-date: Wed, 09 Jul 2014 08:46:42 +0000

List-id: User and development list for XCP and XAPI <xen-api.lists.xen.org>

Thread-index: Ac+YYC92YgqTlRQGT7C9IFkmVI8m3ABcOPUAAGAYlFA=

Thread-topic: [Xen-API] connection to pool master is buggy

These particular messages no longer appear in current development versions of the software: we made stunnels reusable, and reduced the chattiness of the relevant code somewhat. (Off the top of my head I don't remember for certain if/when the changes got into an official release... maybe XenServer 6.2 SP1?) Jon's point about logging is still true more generally, though. From: xen-api-bounces@xxxxxxxxxxxxx [mailto:xen-api-bounces@xxxxxxxxxxxxx] On Behalf Of Jonathan Ludlam Sent: 07 July 2014 11:50 AM To: 'dobropalka'; xen-api@xxxxxxxxxxxxx Subject: Re: [Xen-API] connection to pool master is buggy Hi Ivan, These messages are benign. At some point we'd like to clean up the logging, as it does tend to be a bit spammy. Cheers, Jon From: xen-api-bounces@xxxxxxxxxxxxx [mailto:xen-api-bounces@xxxxxxxxxxxxx] On Behalf Of dobropalka Sent: 05 July 2014 3:51 PM To: xen-api@xxxxxxxxxxxxx Subject: [Xen-API] connection to pool master is buggy Hello, guys! We got a little pool of 4 xenservers. Everything seems to work fine, but all my xensource logs are full of these debug messages: Jul 4 08:09:56 cloudstack22 xapi: [ info|cloudstack22|221070 UNIX /var/xapi/xapi|session.login_with_password D:9579fb00c72a|xapi] Session.create trackid=d99439bce7424c057acf8204cea45f24 pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221070 UNIX /var/xapi/xapi|session.login_with_password D:9579fb00c72a|mscgen] xapi=>xapi [label="(XML)"]; Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221070 UNIX /var/xapi/xapi|session.login_with_password D:9579fb00c72a|stunnel] check_reusable: caught exception Unix.Unix_error(31, "write", ""); assuming not reusable Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221070 UNIX /var/xapi/xapi|session.login_with_password D:9579fb00c72a|stunnel] get_reusable_stunnel: Found non-reusable stunnel in the cache. disconnecting from 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221070 UNIX /var/xapi/xapi|session.login_with_password D:9579fb00c72a|stunnel] get_reusable_stunnel: stunnel cache is empty; creating a fresh connection to 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [ info|cloudstack22|214513 INET 0.0.0.0:80|sm_exec D:176fe384092b|xapi] Session.destroy trackid=667f0d1664f406698854c89fe03aca7c Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221070 UNIX /var/xapi/xapi|session.login_with_password D:9579fb00c72a|xmlrpc_client] stunnel pid: 26281 (cached = true) connected to 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221070 UNIX /var/xapi/xapi|session.login_with_password D:9579fb00c72a|xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=26281 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221070 UNIX /var/xapi/xapi|session.login_with_password D:9579fb00c72a|xmlrpc_client] stunnel pid: 26281 (cached = true) returned stunnel to cache Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221071 UNIX /var/xapi/xapi||mscgen] xapi=>xapi [label="(XML)"]; Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221071 UNIX /var/xapi/xapi||xmlrpc_client] stunnel pid: 26281 (cached = true) connected to 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221071 UNIX /var/xapi/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=26281 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221071 UNIX /var/xapi/xapi||xmlrpc_client] stunnel pid: 26281 (cached = true) returned stunnel to cache Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221072 UNIX /var/xapi/xapi||mscgen] xapi=>xapi [label="(XML)"]; Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221072 UNIX /var/xapi/xapi||stunnel] check_reusable: caught exception Unix.Unix_error(31, "write", ""); assuming not reusable Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221072 UNIX /var/xapi/xapi||stunnel] get_reusable_stunnel: Found non-reusable stunnel in the cache. disconnecting from 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221072 UNIX /var/xapi/xapi||stunnel] get_reusable_stunnel: stunnel cache is empty; creating a fresh connection to 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221072 UNIX /var/xapi/xapi||xmlrpc_client] stunnel pid: 26287 (cached = true) connected to 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221072 UNIX /var/xapi/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=26287 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221072 UNIX /var/xapi/xapi||xmlrpc_client] stunnel pid: 26287 (cached = true) returned stunnel to cache Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221073 UNIX /var/xapi/xapi||mscgen] xapi=>xapi [label="(XML)"]; Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221073 UNIX /var/xapi/xapi||stunnel] check_reusable: caught exception Unix.Unix_error(31, "write", ""); assuming not reusable Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221073 UNIX /var/xapi/xapi||stunnel] get_reusable_stunnel: Found non-reusable stunnel in the cache. disconnecting from 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221073 UNIX /var/xapi/xapi||stunnel] get_reusable_stunnel: stunnel cache is empty; creating a fresh connection to 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|214513 INET 0.0.0.0:80|SR.scan R:eb94a1324f62|taskhelper] forwarded task destroyed Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221073 UNIX /var/xapi/xapi||xmlrpc_client] stunnel pid: 26292 (cached = true) connected to 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221073 UNIX /var/xapi/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=26292 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221073 UNIX /var/xapi/xapi||xmlrpc_client] stunnel pid: 26292 (cached = true) returned stunnel to cache Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221074 UNIX /var/xapi/xapi||mscgen] xapi=>xapi [label="(XML)"]; Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221074 UNIX /var/xapi/xapi||stunnel] check_reusable: caught exception Unix.Unix_error(31, "write", ""); assuming not reusable Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221074 UNIX /var/xapi/xapi||stunnel] get_reusable_stunnel: Found non-reusable stunnel in the cache. disconnecting from 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221074 UNIX /var/xapi/xapi||stunnel] get_reusable_stunnel: stunnel cache is empty; creating a fresh connection to 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221074 UNIX /var/xapi/xapi||xmlrpc_client] stunnel pid: 26297 (cached = true) connected to 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221074 UNIX /var/xapi/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=26297 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221074 UNIX /var/xapi/xapi||xmlrpc_client] stunnel pid: 26297 (cached = true) returned stunnel to cache Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221075 UNIX /var/xapi/xapi||mscgen] xapi=>xapi [label="(XML)"]; Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221075 UNIX /var/xapi/xapi||stunnel] check_reusable: caught exception Unix.Unix_error(31, "write", ""); assuming not reusable Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221075 UNIX /var/xapi/xapi||stunnel] get_reusable_stunnel: Found non-reusable stunnel in the cache. disconnecting from 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221075 UNIX /var/xapi/xapi||stunnel] get_reusable_stunnel: stunnel cache is empty; creating a fresh connection to 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221075 UNIX /var/xapi/xapi||xmlrpc_client] stunnel pid: 26302 (cached = true) connected to 10.50.128.21:443 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221075 UNIX /var/xapi/xapi||xmlrpc_client] with_recorded_stunnelpid task_opt=None s_pid=26302 Jul 4 08:09:56 cloudstack22 xapi: [debug|cloudstack22|221075 UNIX /var/xapi/xapi||xmlrpc_client] stunnel pid: 26302 (cached = true) returned stunnel to cache The only mention I found in internet didn't give me a clue. Still, cluster works fine, I'm just worried if there's a bug somewhere deeper then I see and it can cause some problems Regards, Ivan Derbenev _______________________________________________ Xen-api mailing list Xen-api@xxxxxxxxxxxxx http://lists.xen.org/cgi-bin/mailman/listinfo/xen-api

©2013 Xen Project, A Linux Foundation Collaborative Project. All Rights Reserved.
Linux Foundation is a registered trademark of The Linux Foundation.
Xen Project is a trademark of The Linux Foundation.