Mailing List Archive: connection to pool master is buggy

connection to pool master is buggy

Jul 5, 2014, 7:51 AM

Post #1 of 4 (2322 views)

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

Re: connection to pool master is buggy [ In reply to ]

Jonathan.Ludlam at citrix

Jul 7, 2014, 3:49 AM

Post #2 of 4 (2256 views)

Permalink

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@lists.xen.org [mailto:xen-api-bounces@lists.xen.org] On Behalf Of dobropalka
Sent: 05 July 2014 3:51 PM
To: xen-api@lists.xen.org
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

Re: connection to pool master is buggy [ In reply to ]

thomas.sanders at citrix

Jul 9, 2014, 1:46 AM

Post #3 of 4 (2238 views)

Permalink

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@lists.xen.org [mailto:xen-api-bounces@lists.xen.org] On Behalf Of Jonathan Ludlam
Sent: 07 July 2014 11:50 AM
To: 'dobropalka'; xen-api@lists.xen.org
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@lists.xen.org [mailto:xen-api-bounces@lists.xen.org] On Behalf Of dobropalka
Sent: 05 July 2014 3:51 PM
To: xen-api@lists.xen.org
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@lists.xen.org
http://lists.xen.org/cgi-bin/mailman/listinfo/xen-api

Re: connection to pool master is buggy [ In reply to ]

dobropalka at gmail

Jul 9, 2014, 7:32 AM

Post #4 of 4 (2248 views)

Permalink

Well, thanks for info. Actually, due to these messages it's hard to look
through logs.

Glad that it will be fixed

From: Jonathan Ludlam [mailto:Jonathan.Ludlam@citrix.com]
Sent: Monday, July 7, 2014 2:50 PM
To: 'dobropalka'; xen-api@lists.xen.org
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@lists.xen.org <mailto:xen-api-bounces@lists.xen.org>
[mailto:xen-api-bounces@lists.xen.org] On Behalf Of dobropalka
Sent: 05 July 2014 3:51 PM
To: xen-api@lists.xen.org <mailto:xen-api@lists.xen.org>
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