[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

Re: [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


 


Rackspace

Lists.xenproject.org is hosted with RackSpace, monitoring our
servers 24x7x365 and backed by RackSpace's Fanatical Support®.