[root@c80g105 ~]# kubectl logs virt-launcher-c771-cp2nk compute {"component":"virt-launcher","level":"info","msg":"Collected all requested hook sidecar sockets","pos":"manager.go:68","timestamp":"2019-12-14T23:55:42.788957Z"} {"component":"virt-launcher","level":"info","msg":"Sorted all collected sidecar sockets per hook point based on their priority and name: map[]","pos":"manager.go:71","timestamp":"2019-12-14T23:55:42.789081Z"} {"component":"virt-launcher","level":"info","msg":"Watchdog file created at /var/run/kubevirt/watchdog-files/default_c771","pos":"virt-launcher.go:142","timestamp":"2019-12-14T23:55:42.789904Z"} {"component":"virt-launcher","level":"info","msg":"Connecting to libvirt daemon: qemu:///system","pos":"libvirt.go:355","timestamp":"2019-12-14T23:55:42.821937Z"} {"component":"virt-launcher","level":"info","msg":"Connecting to libvirt daemon failed: virError(Code=38, Domain=7, Message='Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory')","pos":"libvirt.go:363","timestamp":"2019-12-14T23:55:42.827294Z"} {"component":"virt-launcher","level":"info","msg":"libvirt version: 5.6.0, package: 7.fc31 (Unknown, 2019-11-22-21:16:59, )","subcomponent":"libvirt","thread":"65","timestamp":"2019-12-14T23:55:42.952000Z"} {"component":"virt-launcher","level":"info","msg":"hostname: c771","subcomponent":"libvirt","thread":"65","timestamp":"2019-12-14T23:55:42.952000Z"} {"component":"virt-launcher","level":"error","msg":"internal error: Unable to get DBus system bus connection: Failed to connect to socket /run/dbus/system_bus_socket: No such file or directory","pos":"virDBusGetSystemBus:108","subcomponent":"libvirt","thread":"65","timestamp":"2019-12-14T23:55:42.952000Z"} {"component":"virt-launcher","level":"error","msg":"internal error: Child process (/usr/sbin/dmidecode -q -t 0,1,2,3,4,17) unexpected exit status 1: /dev/mem: No such file or directory","pos":"virCommandWait:2792","subcomponent":"libvirt","thread":"65","timestamp":"2019-12-14T23:55:43.010000Z"} {"component":"virt-launcher","level":"info","msg":"Connected to libvirt daemon","pos":"libvirt.go:371","timestamp":"2019-12-14T23:55:44.116053Z"} {"component":"virt-launcher","level":"info","msg":"Registered libvirt event notify callback","pos":"client.go:292","timestamp":"2019-12-14T23:55:44.119049Z"} {"component":"virt-launcher","level":"info","msg":"Marked as ready","pos":"virt-launcher.go:72","timestamp":"2019-12-14T23:55:44.119308Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.3","pos":"converter.go:1137","timestamp":"2019-12-14T23:55:45.317302Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.7","pos":"converter.go:1137","timestamp":"2019-12-14T23:55:45.317422Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Executing PreStartHook on VMI pod environment","name":"c771","namespace":"default","pos":"manager.go:763","timestamp":"2019-12-14T23:55:45.317833Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","kind":"","level":"info","msg":"Starting PreCloudInitIso hook","name":"c771","namespace":"default","pos":"manager.go:772","timestamp":"2019-12-14T23:55:45.317940Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"updated MAC for eth0 interface: old: 2e:0f:79:a6:f9:41 -\u003e new: 2e:0f:79:6f:5d:80","pos":"common.go:255","timestamp":"2019-12-14T23:55:45.319083Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"bridge pod interface: VIF: { Name: eth0, IP: 10.244.1.27, Mask: ffffff00, MAC: 2e:0f:79:a6:f9:41, Gateway: 10.244.1.1, MTU: 1450}","name":"c771","namespace":"default","pos":"podinterface.go:258","timestamp":"2019-12-14T23:55:45.319988Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"Found nameservers in /etc/resolv.conf: \n`\u0000\n","pos":"converter.go:1491","timestamp":"2019-12-14T23:55:45.320089Z"} {"component":"virt-launcher","level":"info","msg":"Found search domains in /etc/resolv.conf: default.svc.cluster.local svc.cluster.local cluster.local md.jp","pos":"converter.go:1492","timestamp":"2019-12-14T23:55:45.320112Z"} {"component":"virt-launcher","level":"info","msg":"Starting SingleClientDHCPServer","pos":"dhcp.go:64","timestamp":"2019-12-14T23:55:45.320308Z"} {"component":"virt-launcher","level":"info","msg":"Driver cache mode for /var/run/kubevirt-ephemeral-disks/disk-data/containerdisk/disk.qcow2 set to none","pos":"converter.go:174","timestamp":"2019-12-14T23:55:45.326774Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.3/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.3/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.3/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.3/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.3/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.3/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.7/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.7/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.7/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.7/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.7/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","level":"warning","msg":"Failed to read from '/sys/bus/pci/devices/0000:04:11.7/config' : Success","pos":"virPCIDeviceRead:341","subcomponent":"libvirt","thread":"53","timestamp":"2019-12-14T23:55:45.328000Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Domain defined.","name":"c771","namespace":"default","pos":"manager.go:1023","timestamp":"2019-12-14T23:55:45.331318Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"DomainLifecycle event 0 with reason 0 received","pos":"client.go:257","timestamp":"2019-12-14T23:55:45.331448Z"} {"component":"virt-launcher","level":"error","msg":"internal error: Unable to get DBus system bus connection: Failed to connect to socket /run/dbus/system_bus_socket: No such file or directory","pos":"virDBusGetSystemBus:108","subcomponent":"libvirt","thread":"50","timestamp":"2019-12-14T23:55:45.334000Z"} {"component":"virt-launcher","level":"error","msg":"At least one cgroup controller is required: No such device or address","pos":"virCgroupDetect:419","subcomponent":"libvirt","thread":"50","timestamp":"2019-12-14T23:55:45.438000Z"} {"component":"virt-launcher","level":"info","msg":"kubevirt domain status: Paused(3):StartingUp(11)","pos":"client.go:180","timestamp":"2019-12-14T23:55:45.440638Z"} {"component":"virt-launcher","level":"info","msg":"Detected domain with UUID c39efc56-4519-4822-a79f-03d1c6cbfc67","pos":"virt-launcher.go:233","timestamp":"2019-12-14T23:55:45.443540Z"} {"component":"virt-launcher","level":"info","msg":"Monitoring loop: rate 1s start timeout 5m0s","pos":"monitor.go:237","timestamp":"2019-12-14T23:55:45.443585Z"} {"component":"virt-launcher","level":"info","msg":"processed event","pos":"client.go:242","timestamp":"2019-12-14T23:55:45.444117Z"} {"component":"virt-launcher","level":"info","msg":"Found PID for c39efc56-4519-4822-a79f-03d1c6cbfc67: 96","pos":"monitor.go:206","timestamp":"2019-12-14T23:55:46.445118Z"} {"component":"virt-launcher","level":"info","msg":"GuestAgentLifecycle event state 2 with reason 1 received","pos":"client.go:275","timestamp":"2019-12-14T23:55:46.564249Z"} {"component":"virt-launcher","level":"info","msg":"kubevirt domain status: Paused(3):StartingUp(11)","pos":"client.go:180","timestamp":"2019-12-14T23:55:46.566555Z"} {"component":"virt-launcher","level":"info","msg":"processed event","pos":"client.go:242","timestamp":"2019-12-14T23:55:46.567829Z"} {"component":"virt-launcher","level":"info","msg":"DomainLifecycle event 4 with reason 0 received","pos":"client.go:257","timestamp":"2019-12-14T23:55:46.767821Z"} {"component":"virt-launcher","level":"info","msg":"DomainLifecycle event 2 with reason 0 received","pos":"client.go:257","timestamp":"2019-12-14T23:55:46.768479Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Domain started.","name":"c771","namespace":"default","pos":"manager.go:1054","timestamp":"2019-12-14T23:55:46.769034Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","kind":"","level":"info","msg":"Synced vmi","name":"c771","namespace":"default","pos":"server.go:167","timestamp":"2019-12-14T23:55:46.770078Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"kubevirt domain status: Running(1):Unknown(1)","pos":"client.go:180","timestamp":"2019-12-14T23:55:46.770262Z"} {"component":"virt-launcher","level":"info","msg":"processed event","pos":"client.go:242","timestamp":"2019-12-14T23:55:46.771365Z"} {"component":"virt-launcher","level":"info","msg":"kubevirt domain status: Running(1):Unknown(1)","pos":"client.go:180","timestamp":"2019-12-14T23:55:46.772748Z"} {"component":"virt-launcher","level":"info","msg":"processed event","pos":"client.go:242","timestamp":"2019-12-14T23:55:46.773308Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.3","pos":"converter.go:1137","timestamp":"2019-12-14T23:55:46.824731Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.7","pos":"converter.go:1137","timestamp":"2019-12-14T23:55:46.824814Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Synced vmi","name":"c771","namespace":"default","pos":"server.go:167","timestamp":"2019-12-14T23:55:46.826397Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.3","pos":"converter.go:1137","timestamp":"2019-12-14T23:55:46.844625Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.7","pos":"converter.go:1137","timestamp":"2019-12-14T23:55:46.844715Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Synced vmi","name":"c771","namespace":"default","pos":"server.go:167","timestamp":"2019-12-14T23:55:46.845817Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"GuestAgentLifecycle event state 1 with reason 2 received","pos":"client.go:275","timestamp":"2019-12-14T23:56:00.002265Z"} {"component":"virt-launcher","level":"info","msg":"kubevirt domain status: Running(1):Unknown(1)","pos":"client.go:180","timestamp":"2019-12-14T23:56:00.003963Z"} {"component":"virt-launcher","level":"info","msg":"processed event","pos":"client.go:242","timestamp":"2019-12-14T23:56:00.005330Z"} {"component":"virt-launcher","level":"info","msg":"Qemu agent poller started","pos":"agent_poller.go:110","timestamp":"2019-12-14T23:56:00.005377Z"} {"component":"virt-launcher","level":"info","msg":"Qemu agent poller started","pos":"agent_poller.go:110","timestamp":"2019-12-14T23:56:00.005421Z"} {"component":"virt-launcher","level":"info","msg":"Qemu agent poller started","pos":"agent_poller.go:110","timestamp":"2019-12-14T23:56:00.005445Z"} {"component":"virt-launcher","level":"info","msg":"Qemu agent poller started","pos":"agent_poller.go:110","timestamp":"2019-12-14T23:56:00.005460Z"} {"component":"virt-launcher","level":"info","msg":"Qemu agent poller started","pos":"agent_poller.go:110","timestamp":"2019-12-14T23:56:00.005476Z"} {"component":"virt-launcher","level":"info","msg":"Qemu agent poller started","pos":"agent_poller.go:110","timestamp":"2019-12-14T23:56:00.005490Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.3","pos":"converter.go:1137","timestamp":"2019-12-14T23:56:00.024426Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.7","pos":"converter.go:1137","timestamp":"2019-12-14T23:56:00.024489Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Synced vmi","name":"c771","namespace":"default","pos":"server.go:167","timestamp":"2019-12-14T23:56:00.025684Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.3","pos":"converter.go:1137","timestamp":"2019-12-14T23:56:00.056857Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.7","pos":"converter.go:1137","timestamp":"2019-12-14T23:56:00.056945Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Synced vmi","name":"c771","namespace":"default","pos":"server.go:167","timestamp":"2019-12-14T23:56:00.058490Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.3","pos":"converter.go:1137","timestamp":"2019-12-14T23:56:00.080008Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.7","pos":"converter.go:1137","timestamp":"2019-12-14T23:56:00.080098Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Synced vmi","name":"c771","namespace":"default","pos":"server.go:167","timestamp":"2019-12-14T23:56:00.081567Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"warning","msg":"Domain id=1 name='default_c771' uuid=c39efc56-4519-4822-a79f-03d1c6cbfc67 is tainted: custom-ga-command","pos":"qemuDomainObjTaint:8699","subcomponent":"libvirt","thread":"51","timestamp":"2019-12-14T23:57:00.005000Z"} {"component":"virt-launcher","level":"error","msg":"Cannot open log file: '/var/log/libvirt/qemu/default_c771.log': Device or resource busy","pos":"virNetClientProgramDispatchError:172","subcomponent":"libvirt","thread":"51","timestamp":"2019-12-14T23:57:00.006000Z"} {"component":"virt-launcher","level":"warning","msg":"Unable to open domainlog","pos":"qemuDomainObjTaint:8717","subcomponent":"libvirt","thread":"51","timestamp":"2019-12-14T23:57:00.006000Z"} {"component":"virt-launcher","level":"info","msg":"Qemu agent poller started","pos":"agent_poller.go:110","timestamp":"2019-12-14T23:57:00.009060Z"} {"component":"virt-launcher","level":"info","msg":"kubevirt domain status: Running(1):Unknown(1)","pos":"client.go:180","timestamp":"2019-12-14T23:57:00.010627Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.3","pos":"converter.go:1137","timestamp":"2019-12-14T23:57:00.031504Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.7","pos":"converter.go:1137","timestamp":"2019-12-14T23:57:00.031567Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Synced vmi","name":"c771","namespace":"default","pos":"server.go:167","timestamp":"2019-12-14T23:57:00.032811Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.3","pos":"converter.go:1137","timestamp":"2019-12-14T23:57:00.063450Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.7","pos":"converter.go:1137","timestamp":"2019-12-14T23:57:00.063523Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Synced vmi","name":"c771","namespace":"default","pos":"server.go:167","timestamp":"2019-12-14T23:57:00.064738Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.3","pos":"converter.go:1137","timestamp":"2019-12-14T23:57:00.084397Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.7","pos":"converter.go:1137","timestamp":"2019-12-14T23:57:00.084457Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Synced vmi","name":"c771","namespace":"default","pos":"server.go:167","timestamp":"2019-12-14T23:57:00.085653Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.3","pos":"converter.go:1137","timestamp":"2019-12-14T23:57:00.104356Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.7","pos":"converter.go:1137","timestamp":"2019-12-14T23:57:00.104422Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Synced vmi","name":"c771","namespace":"default","pos":"server.go:167","timestamp":"2019-12-14T23:57:00.105498Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.3","pos":"converter.go:1137","timestamp":"2019-12-14T23:57:00.138456Z"} {"component":"virt-launcher","level":"info","msg":"SR-IOV PCI device allocated: 0000:04:11.7","pos":"converter.go:1137","timestamp":"2019-12-14T23:57:00.138533Z"} {"component":"virt-launcher","kind":"","level":"info","msg":"Synced vmi","name":"c771","namespace":"default","pos":"server.go:167","timestamp":"2019-12-14T23:57:00.139884Z","uid":"4ffed30a-c52a-4b02-bfad-753f2602c6e2"} {"component":"virt-launcher","level":"info","msg":"Qemu agent poller started","pos":"agent_poller.go:110","timestamp":"2019-12-14T23:58:00.011185Z"} {"component":"virt-launcher","level":"info","msg":"Qemu agent poller started","pos":"agent_poller.go:110","timestamp":"2019-12-14T23:59:00.013748Z"}