[Xen-API] xenserver-core tech preview: Console going crazy

[John Morris <john@xxxxxxxxxxx>]

Next problem:  the VM console proxy exits immediately, resulting in a
tight loop that eats up about 1/3 of the CPU.  Below is 1.5 cycles
pasted from the debug log.

        John



http://pastebin.ca/2472371

> Oct 29 00:51:45 xen27 stunnel: LOG5[13264:140387376142272]: stunnel 4.29 on 
> x86_64-redhat-linux-gnu with OpenSSL 1.0.0-fips 29 Mar 2010
> Oct 29 00:51:45 xen27 stunnel: LOG5[13264:140387376142272]: Threading:PTHREAD 
> SSL:ENGINE Sockets:POLL,IPv6 Auth:LIBWRAP
> Oct 29 00:51:45 xen27 stunnel: LOG5[13264:140387376142272]: stunnel accepted 
> connection from localhost:#015ÿ#177
> Oct 29 00:51:45 xen27 stunnel: LOG5[13264:140387376142272]: connect_blocking: 
> connected 192.168.7.26:443
> Oct 29 00:51:45 xen27 stunnel: LOG5[13264:140387376142272]: stunnel connected 
> remote server from 192.168.7.26:45115
> Oct 29 00:51:45 xen27 stunnel: LOG5[2220:140308671461120]: xapi accepted 
> connection from ::ffff:192.168.7.26:45115
> Oct 29 00:51:45 xen27 stunnel: LOG5[2220:140308671461120]: connect_blocking: 
> connected 127.0.0.1:80
> Oct 29 00:51:45 xen27 stunnel: LOG5[2220:140308671461120]: xapi connected 
> remote server from 127.0.0.1:46203
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2352 INET :::80||http] 
> Request { frame = false; method = CONNECT; uri = /console; query = [ 
> uuid=6cdba8a9-7be2-2917-ef6d-da09cd31ccb4; 
> session_id=OpaqueRef:b251dcb4-8f33-a895-1374-2f9e83466c1d ]; content_length = 
> [ 0 ]; transfer encoding = ; version = 1.0; cookie = [  ]; task = ; 
> subtask_of = ; content-type = ; host = ; user_agent =  }
> Oct 29 00:51:45 xen27 xapi: 
> [20131029T05:51:45.560Z|audit|xen27.zultron.com|2352 INET 
> :::80|handler:http/connect_console D:19a8eca9ab12|audit] 
> ('trackid=abc41a624d6cd5ae06e7b279aec28aaf' 'LOCAL_SUPERUSER' 'root' 
> 'ALLOWED' 'OK' 'HTTP' 'http/connect_console' ())
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2352 INET 
> :::80||db_write] create_row task 
> (OpaqueRef:df82fe46-fd33-01ac-a218-5362e2de912c) 
> [(_ref,v),(uuid,v),(name__label,v),(name__description,v),(allowed_operations,v),(current_operations,v),(created,v),(finished,v),(status,v),(session,v),(resident_on,v),(progress,v),(externalpid,v),(stunnelpid,v),(forwarded,v),(forwarded_to,v),(type,v),(result,v),(error_info,v),(other_config,v),(subtask_of,v)]
> Oct 29 00:51:45 xen27 xenopsd-xenlight: [xenops_server] VM.stat 
> 50c8ab69-7fbc-dacd-78e2-230777596993
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2352 INET 
> :::80|Connection to VM console R:df82fe46fd33|console] VM 
> OpaqueRef:0ae73118-540b-fc53-4405-05c72422eee9 console on: localhost:9501
> Oct 29 00:51:45 xen27 stunnel: LOG5[2220:140308671461120]: Connection closed: 
> 78 bytes sent to SSL, 148 bytes sent to socket
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2352 INET 
> :::80|Connection to VM console R:df82fe46fd33|console] Connected; running 
> proxy (between fds: 26 and 27)
> Oct 29 00:51:45 xen27 stunnel: LOG5[13264:140387376142272]: Connection 
> closed: 148 bytes sent to SSL, 78 bytes sent to socket
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2352 INET 
> :::80|Connection to VM console R:df82fe46fd33|console] Proxy exited
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2352 INET 
> :::80|Connection to VM console R:df82fe46fd33|redo_log] WriteField(task, 
> OpaqueRef:df82fe46-fd33-01ac-a218-5362e2de912c, allowed_operations, (), ())
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2352 INET 
> :::80|Connection to VM console R:df82fe46fd33|redo_log] WriteField(task, 
> OpaqueRef:df82fe46-fd33-01ac-a218-5362e2de912c, finished, 19700101T00:00:00Z, 
> 20131029T05:51:45Z)
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2352 INET 
> :::80|Connection to VM console R:df82fe46fd33|redo_log] WriteField(task, 
> OpaqueRef:df82fe46-fd33-01ac-a218-5362e2de912c, progress, 0, 1)
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2352 INET 
> :::80|Connection to VM console R:df82fe46fd33|redo_log] WriteField(task, 
> OpaqueRef:df82fe46-fd33-01ac-a218-5362e2de912c, status, pending, success)
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2352 INET 
> :::80|Connection to VM console R:df82fe46fd33|db_write] delete_row task 
> (OpaqueRef:df82fe46-fd33-01ac-a218-5362e2de912c)

(Here, stunnel restarts)

> Oct 29 00:51:45 xen27 stunnel: LOG5[13268:140615311181760]: stunnel 4.29 on 
> x86_64-redhat-linux-gnu with OpenSSL 1.0.0-fips 29 Mar 2010
> Oct 29 00:51:45 xen27 stunnel: LOG5[13268:140615311181760]: Threading:PTHREAD 
> SSL:ENGINE Sockets:POLL,IPv6 Auth:LIBWRAP
> Oct 29 00:51:45 xen27 stunnel: LOG5[13268:140615311181760]: stunnel accepted 
> connection from localhost:6#177
> Oct 29 00:51:45 xen27 stunnel: LOG5[13268:140615311181760]: connect_blocking: 
> connected 192.168.7.26:443
> Oct 29 00:51:45 xen27 stunnel: LOG5[13268:140615311181760]: stunnel connected 
> remote server from 192.168.7.26:45118
> Oct 29 00:51:45 xen27 stunnel: LOG5[2220:140308671461120]: xapi accepted 
> connection from ::ffff:192.168.7.26:45118
> Oct 29 00:51:45 xen27 stunnel: LOG5[2220:140308671461120]: connect_blocking: 
> connected 127.0.0.1:80
> Oct 29 00:51:45 xen27 stunnel: LOG5[2220:140308671461120]: xapi connected 
> remote server from 127.0.0.1:46206
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2353 INET :::80||http] 
> Request { frame = false; method = CONNECT; uri = /console; query = [ 
> uuid=6cdba8a9-7be2-2917-ef6d-da09cd31ccb4; 
> session_id=OpaqueRef:b251dcb4-8f33-a895-1374-2f9e83466c1d ]; content_length = 
> [ 0 ]; transfer encoding = ; version = 1.0; cookie = [  ]; task = ; 
> subtask_of = ; content-type = ; host = ; user_agent =  }
> Oct 29 00:51:45 xen27 xapi: 
> [20131029T05:51:45.727Z|audit|xen27.zultron.com|2353 INET 
> :::80|handler:http/connect_console D:10a07e7ce7b6|audit] 
> ('trackid=abc41a624d6cd5ae06e7b279aec28aaf' 'LOCAL_SUPERUSER' 'root' 
> 'ALLOWED' 'OK' 'HTTP' 'http/connect_console' ())
> Oct 29 00:51:45 xen27 xapi: [debug|xen27.zultron.com|2353 INET 
> :::80||db_write] create_row task 
> (OpaqueRef:aaa8b406-5942-4eaf-ce6b-c9ae783c1bc5) [(_ref,v),(uuid,v),(nam



_______________________________________________
Xen-api mailing list
Xen-api@xxxxxxxxxxxxx
http://lists.xen.org/cgi-bin/mailman/listinfo/xen-api