vdsm-issue.md

• on the engine sockets

[root@hosted-engine-07 fd]# ss -ntp | grep 54321
ESTAB      0      0        [::ffff:10.46.16.255]:57846                 [::ffff:10.46.16.44]:54321               users:(("java",pid=2836,fd=592))�
CLOSE-WAIT 1      0        [::ffff:10.46.16.255]:50840                 [::ffff:10.46.16.45]:54321               users:(("java",pid=2836,fd=593))�
STAB      0      0        [::ffff:10.46.16.255]:35584                 [::ffff:10.46.16.46]:54321               users:(("java",pid=2836,fd=611))�
STAB      0      0        [::ffff:10.46.16.255]:57882                 [::ffff:10.46.16.44]:54321           

• check the FD timestamp

[root@hosted-engine-07 fd]# ls -latr  /proc/2836/fd | grep 593
lrwx------. 1 ovirt ovirt 64 Feb 12 05:32 593 -> socket:[62175]

• on the engine.log

2020-02-12 05:18:06,826-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-18) [1c7a270f] EVENT_ID: ANSIBLE_RUNNER_EVENT_NOTIFICATION(559), Installing Host host_mixed_2. WA by mskrivanek - restart libvirtd.
2020-02-12 05:18:06,848-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-18) [1c7a270f] EVENT_ID: VDS_ANSIBLE_INSTALL_FINISHED(561), Ansible host-deploy playbook execution has successfully finished on host host_mixed_2.
2020-02-12 05:18:06,885-05 INFO  [org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient] (SSL Stomp Reactor) [] Connecting to lynx26.lab.eng.tlv2.redhat.com/10.46.16.45
2020-02-12 05:18:06,886-05 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (EE-ManagedThreadFactory-engine-Thread-18) [1c7a270f] Error: org.ovirt.engine.core.vdsbroker.TransportRunTimeException: Connection issues during send request
2020-02-12 05:18:06,887-05 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PollVDSCommand] (EE-ManagedThreadFactory-engine-Thread-18) [1c7a270f] Command 'PollVDSCommand(HostName = host_mixed_2, TimeBoundPollVDSCommandParameters:{hostId='ab9aa09b-d945-49b4-aa2c-a7a945b1ce59'})' execution failed: java.util.concurrent.ExecutionException: org.ovirt.engine.core.vdsbroker.TransportRunTimeException: Connection issues during send reques

• on the host - lynx26 vdsm.log (vdsm.log ):

Feb 12 05:18:04 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running mkdirs
Feb 12 05:18:04 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running configure_coredump
Feb 12 05:18:04 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running configure_vdsm_logs
Feb 12 05:18:04 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running wait_for_network
Feb 12 05:18:05 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running run_init_hooks
Feb 12 05:18:05 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running check_is_configured
Feb 12 05:18:07 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: abrt is already configured for vdsm
Feb 12 05:18:07 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: Current revision of multipath.conf detected, preserving
Feb 12 05:18:07 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: lvm is configured for vdsm
Feb 12 05:18:07 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: Managed volume database is already configured
Feb 12 05:18:07 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: libvirtd socket units status: [{'Names': 'libvirtd-ro.socket', 'LoadState': 'loaded'}, {'Names': 'libvirtd-tls.socket', 'LoadState': 'loaded'}, {'Names': 'libvirtd.socket', 'LoadState': 'loaded'}, {'Names': 'libvirtd-tcp.socket', 'LoadState': 'loaded'}, {'Names': 'libvirtd-admin.socket', 'LoadState': 'loaded'}]
Feb 12 05:18:07 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: libvirtd uses socket activation
Feb 12 05:18:07 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: libvirt is already configured for vdsm
Feb 12 05:18:07 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running validate_configuration
Feb 12 05:18:07 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: SUCCESS: ssl configured to true. No conflicts
Feb 12 05:18:07 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running prepare_transient_repository
Feb 12 05:18:09 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running syslog_available
Feb 12 05:18:09 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running nwfilter
Feb 12 05:18:10 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running dummybr
Feb 12 05:18:10 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running tune_system
Feb 12 05:18:10 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running test_space
Feb 12 05:18:10 lynx26.lab.eng.tlv2.redhat.com vdsmd_init_common.sh[6337]: vdsm: Running test_lo
Feb 12 05:18:10 lynx26.lab.eng.tlv2.redhat.com systemd[1]: Started Virtual Desktop Server Manager.
Feb 12 05:18:10 lynx26.lab.eng.tlv2.redhat.com systemd[1]: Started oVirt Hosted Engine High Availability Monitoring Agent.
Feb 12 05:18:10 lynx26.lab.eng.tlv2.redhat.com systemd[1]: Started MOM instance configured for VDSM purposes.