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
