[ovirt-users] Unexpected protocol mismatch with SPICE HTML5

[nicolas at devels.es]

Hi,

I just made a fresh install of ovirt-engine (v. 3.5.3.1-1) on a CentOS-7 
x86_64 box. My previous installation had issues with the TLS certificate 
time of issue, which was successfully fixed in this version 
(https://bugzilla.redhat.com/show_bug.cgi?id=1210486). However, when 
trying to start the SPICE HTML5 client, all I can see is the grey square 
and no activity, and when clicking on "Toggle messages output", the 
error message "Unexpected protocol mismatch" is shown.

Things done:
* ovirt-guest-agent installed on the VM
* On client side, updated browser (Firefox) to latest version (38.0) and 
/ca.crt has been imported into Firefox certs

Regarding logs on the server side, I can see the following:

ovirt-engine
============

2015-06-16 10:49:29,862 INFO  
[org.ovirt.engine.core.bll.SetVmTicketCommand] (ajp--127.0.0.1-8702-11) 
[39c79276] Running command: SetVmTicketCommand internal: false. Entities 
affected :  ID: c2e32d4f-4c54-4e38-bf66-2627dc6dd4bc Type: VMAction 
group CONNECT_TO_VM with role type USER
2015-06-16 10:49:29,917 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] 
(ajp--127.0.0.1-8702-11) [39c79276] START, 
SetVmTicketVDSCommand(HostName = ovirtengine.myorg.es, HostId = 
82fb3c43-3a4b-464e-9077-8e86dea2016b, 
vmId=c2e32d4f-4c54-4e38-bf66-2627dc6dd4bc, ticket=TgrRVYoRhC8T, 
validTime=120,m userName=user, 
userId=5d502bbb-97d6-40b5-abbf-90121cea94d5), log id: 1881c90f
2015-06-16 10:49:29,937 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] 
(ajp--127.0.0.1-8702-11) [39c79276] FINISH, SetVmTicketVDSCommand, log 
id: 1881c90f
2015-06-16 10:49:29,957 INFO  
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] 
(ajp--127.0.0.1-8702-11) [39c79276] Correlation ID: 39c79276, Call 
Stack: null, Custom Event ID: -1, Message: user user at domain initiated 
console session for VM testVM
2015-06-16 10:49:29,987 WARN  
[org.ovirt.engine.core.dal.job.ExecutionMessageDirector] 
(ajp--127.0.0.1-8702-11) [7a71a9e4] The message key VmLogon is missing 
from bundles/ExecutionMessages
2015-06-16 10:49:30,064 INFO  [org.ovirt.engine.core.bll.VmLogonCommand] 
(ajp--127.0.0.1-8702-11) [7a71a9e4] Running command: VmLogonCommand 
internal: false. Entities affected :  ID: 
c2e32d4f-4c54-4e38-bf66-2627dc6dd4bc Type: VMAction group CONNECT_TO_VM 
with role type USER
2015-06-16 10:49:30,114 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.VmLogonVDSCommand] 
(ajp--127.0.0.1-8702-11) [7a71a9e4] START, VmLogonVDSCommand(HostName = 
ovirtengine.myorg.es, HostId = 82fb3c43-3a4b-464e-9077-8e86dea2016b, 
vmId=c2e32d4f-4c54-4e38-bf66-2627dc6dd4bc, domain=domain-authz, 
password=******, userName=user at domain), log id: 68632b24
2015-06-16 10:49:30,120 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.VmLogonVDSCommand] 
(ajp--127.0.0.1-8702-11) [7a71a9e4] FINISH, VmLogonVDSCommand, log id: 
68632b24
2015-06-16 10:49:30,351 INFO  
[org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] 
(DefaultQuartzScheduler_Worker-2) START, 
GlusterVolumesListVDSCommand(HostName = kvmhost.myorg.es, HostId = 
82fb3c43-3a4b-464e-9077-8e86dea2016b), log id: 2a2a439a
2015-06-16 10:49:30,368 INFO  
[org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] 
(DefaultQuartzScheduler_Worker-2) FINISH, GlusterVolumesListVDSCommand, 
return: {}, log id: 2a2a439a

ovirt-websocket-proxy
=====================

jun 16 09:56:39 ovirtengine.myorg.es ovirt-websocket-proxy.py[30728]: 
10.X.X.X - - [16/Jun/2015 09:56:39] 10.X.X.X: SSL/TLS (wss://) WebSocket 
connection
jun 16 09:56:39 ovirtengine.myorg.es ovirt-websocket-proxy.py[30728]: 
10.X.X.X - - [16/Jun/2015 09:56:39] 10.X.X.X: Version hybi-13, base64: 
'False'
jun 16 09:56:39 ovirtengine.myorg.es ovirt-websocket-proxy.py[30728]: 
10.X.X.X - - [16/Jun/2015 09:56:39] 10.X.X.X: Path: 
'/eyJ2YWxpZFRvIjoiMjAxNTA2MTYwODU4MzgiLCJkYXRhIjoiJTdCJTIyaG9zdCUyMjolMjIxMC4xMDcuMjYuMjAxJTIyLCUyMnBvcnQlMjI6JTIyNTkwMCUyMiwlMjJzc2xfdGFyZ2V0JTIyOnRydWUlN0QiLCJ2YWxpZEZyb20iOiIyMDE1MDYxNjA4NTYzOCIsInNpZ25lZEZpZWxkcyI6InZhbGlkVG8sZGF0YSx2YWxpZEZyb20sc2FsdCIsInNpZ25hdHVyZSI6Imk2a3J2bjFCLzFYbUptSEJjVVZZRWVhSExwenA1LzJBQU1DWmJZcDJsaE9heEYvdkZ3azJIa0hibm5iY21mZFp3STJYbnFzMm9vZnMvNWV3NlNiTGtGeTdaVVdjZG4zdUxTcStZTVg4c3huUzg0Ulg3ZmtLTk05Mk9CeFJENm5VSEVDMXQ1eld0OXo3akZYVktMdXFEYkNiK3RmL3pNZGJuS0ZCcGtXaU5JVVB4b09yd2ZxRUQxMWZTell0aXVFeEFscW5xWlBqSXpjcHNDTHRwQXZaTnNWWWFNQlFWR1U2WnZiZlFWUTBlaFIzMnVyaTlVUmI1NDZCZVBuMkVJdytwU3RIWmZRWit2RDZKUjRWeDJFSFRqVEtXZ2ZMbzRWY0ZjSzNnQURYV1BKbUJjaWx5TExQR3dnTm1ncEk0cjVhR1ZCTmZ1VVUxSENJTlhWUVZPVnJJZz09Iiwic2FsdCI6IndFZGwvcmw3VWZJPSJ9'

Java console
============

">> disconnect" spicehtml5-main.html:86:16
"<< disconnect" spicehtml5-main.html:90:16
"ERROR: Error: Unexpected protocol mismatch."

-----------------


I can't see any other relevant info, so I wonder if something else might 
be missing on any of the involved machines. Is there something else to 
do?

Thanks.

Nicolás