Attachment 1977520 Details for Bug 2225667 – libvirtd log

libvirtd log

libvirtd-bug.log (text/plain), 13.16 KB, created by Mario Cattamo on 2023-07-25 15:59:14 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Mario Cattamo

Created: 2023-07-25 15:59:14 UTC

Size: 13.16 KB

patch

obsolete

>Journalctl:
>
>Jul 25 10:19:27 rhel-9-3-230725 systemd[1]: Starting Virtualization daemon...
>ââ Subject: A start job for unit libvirtd.service has begun execution
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ A start job for unit libvirtd.service has begun execution.
>ââ 
>ââ The job identifier is 1943.
>Jul 25 10:19:27 rhel-9-3-230725 systemd[1]: Started Virtualization daemon.
>ââ Subject: A start job for unit libvirtd.service has finished successfully
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ A start job for unit libvirtd.service has finished successfully.
>ââ 
>ââ The job identifier is 1943.
>Jul 25 10:19:28 rhel-9-3-230725 dnsmasq[22359]: started, version 2.85 cachesize 150
>Jul 25 10:19:28 rhel-9-3-230725 dnsmasq[22359]: compile time options: IPv6 GNU-getopt DBus no-UBus no-i18n IDN2 DHCP DHCPv6 no-Lua TFTP no-conntrack ipset auth cryptohash DNSSEC loop-detect inotify dumpfile
>Jul 25 10:19:28 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCP, IP range 192.168.100.2 -- 192.168.100.254, lease time 1h
>Jul 25 10:19:28 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCP, sockets bound exclusively to interface integration
>Jul 25 10:19:28 rhel-9-3-230725 dnsmasq[22359]: reading /etc/resolv.conf
>Jul 25 10:19:28 rhel-9-3-230725 dnsmasq[22359]: using nameserver 10.11.5.19#53
>Jul 25 10:19:28 rhel-9-3-230725 dnsmasq[22359]: using nameserver 10.2.32.1#53
>Jul 25 10:19:28 rhel-9-3-230725 dnsmasq[22359]: read /etc/hosts - 2 addresses
>Jul 25 10:19:28 rhel-9-3-230725 dnsmasq[22359]: read /var/lib/libvirt/dnsmasq/integration.addnhosts - 0 addresses
>Jul 25 10:19:28 rhel-9-3-230725 dnsmasq-dhcp[22359]: read /var/lib/libvirt/dnsmasq/integration.hostsfile
>Jul 25 11:02:15 rhel-9-3-230725 systemd[1]: libvirtd.service: Deactivated successfully.
>ââ Subject: Unit succeeded
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ The unit libvirtd.service has successfully entered the 'dead' state.
>Jul 25 11:02:15 rhel-9-3-230725 systemd[1]: libvirtd.service: Unit process 22359 (dnsmasq) remains running after unit stopped.
>Jul 25 11:02:15 rhel-9-3-230725 systemd[1]: libvirtd.service: Unit process 22361 (dnsmasq) remains running after unit stopped.
>Jul 25 11:02:15 rhel-9-3-230725 systemd[1]: Stopped Virtualization daemon.
>ââ Subject: A stop job for unit libvirtd.service has finished
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ A stop job for unit libvirtd.service has finished.
>ââ 
>ââ The job identifier is 2233 and the job result is done.
>Jul 25 11:02:15 rhel-9-3-230725 systemd[1]: Stopping Virtualization daemon...
>ââ Subject: A stop job for unit libvirtd.service has begun execution
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ A stop job for unit libvirtd.service has begun execution.
>ââ 
>ââ The job identifier is 2233.
>Jul 25 11:02:15 rhel-9-3-230725 systemd[1]: Starting Virtualization daemon...
>ââ Subject: A start job for unit libvirtd.service has begun execution
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ A start job for unit libvirtd.service has begun execution.
>â 
>ââ The job identifier is 2233.
>Jul 25 11:02:15 rhel-9-3-230725 systemd[1]: Started Virtualization daemon.
>ââ Subject: A start job for unit libvirtd.service has finished successfully
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ A start job for unit libvirtd.service has finished successfully.
>ââ 
>ââ The job identifier is 2233.
>Jul 25 11:02:16 rhel-9-3-230725 dnsmasq[22359]: read /etc/hosts - 2 addresses
>Jul 25 11:02:16 rhel-9-3-230725 dnsmasq[22359]: read /var/lib/libvirt/dnsmasq/integration.addnhosts - 0 addresses
>Jul 25 11:02:16 rhel-9-3-230725 dnsmasq-dhcp[22359]: read /var/lib/libvirt/dnsmasq/integration.hostsfile
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: libvirt version: 9.3.0, package: 2.el9 (Red Hat, Inc. <http://bugzilla.redhat.com/bugzilla>, 2023-05-16-10:12:41, )
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: hostname: rhel-9-3-230725
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: internal error: Cannot find start time for pid 43172
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: internal error: Cannot find start time for pid 52115
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: internal error: Cannot find start time for pid 52152
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: internal error: Cannot find start time for pid 52316
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: internal error: Cannot find start time for pid 52646
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: internal error: Cannot find start time for pid 52649
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: internal error: Cannot find start time for pid 52657
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: internal error: Cannot find start time for pid 52673
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: internal error: Cannot find start time for pid 52677
>Jul 25 11:02:16 rhel-9-3-230725 libvirtd[52740]: internal error: Cannot find start time for pid 52695
>Jul 25 11:02:25 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPDISCOVER(integration) 34:49:22:b0:83:30
>Jul 25 11:02:25 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPOFFER(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:02:29 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPDISCOVER(integration) 34:49:22:b0:83:30
>Jul 25 11:02:29 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPOFFER(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:02:37 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPDISCOVER(integration) 34:49:22:b0:83:30
>Jul 25 11:02:37 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPOFFER(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:02:53 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPDISCOVER(integration) 34:49:22:b0:83:30
>Jul 25 11:02:53 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPOFFER(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:04:28 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPDISCOVER(integration) 34:49:22:b0:83:30
>Jul 25 11:04:28 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPOFFER(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:04:32 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPREQUEST(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:04:32 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPACK(integration) 192.168.100.50 34:49:22:b0:83:30 vm-1
>Jul 25 11:04:58 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPDISCOVER(integration) 34:49:22:b0:83:30
>Jul 25 11:04:58 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPOFFER(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:04:58 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPREQUEST(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:04:58 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPACK(integration) 192.168.100.50 34:49:22:b0:83:30 vm-1
>Jul 25 11:05:13 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPDISCOVER(integration) 34:49:22:b0:83:30
>Jul 25 11:05:13 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPOFFER(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:05:13 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPREQUEST(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:05:13 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPACK(integration) 192.168.100.50 34:49:22:b0:83:30 vm-1
>Jul 25 11:05:23 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPDISCOVER(integration) 34:49:22:b0:83:30
>Jul 25 11:05:23 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPOFFER(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:05:23 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPREQUEST(integration) 192.168.100.50 34:49:22:b0:83:30
>Jul 25 11:05:23 rhel-9-3-230725 dnsmasq-dhcp[22359]: DHCPACK(integration) 192.168.100.50 34:49:22:b0:83:30 vm-1
>Jul 25 11:07:14 rhel-9-3-230725 libvirtd[52740]: cannot parse process status data
>Jul 25 11:34:31 rhel-9-3-230725 systemd[1]: libvirtd.service: Deactivated successfully.
>ââ Subject: Unit succeeded
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ The unit libvirtd.service has successfully entered the 'dead' state.
>Jul 25 11:34:31 rhel-9-3-230725 systemd[1]: libvirtd.service: Unit process 22359 (dnsmasq) remains running after unit stopped.
>Jul 25 11:34:31 rhel-9-3-230725 systemd[1]: libvirtd.service: Unit process 22361 (dnsmasq) remains running after unit stopped.
>Jul 25 11:34:31 rhel-9-3-230725 systemd[1]: Stopped Virtualization daemon.
>ââ Subject: A stop job for unit libvirtd.service has finished
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ A stop job for unit libvirtd.service has finished.
>ââ 
>ââ The job identifier is 2694 and the job result is done.
>Jul 25 11:34:31 rhel-9-3-230725 systemd[1]: libvirtd.service: Consumed 3.851s CPU time.
>ââ Subject: Resources consumed by unit runtime
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ The unit libvirtd.service completed and consumed the indicated resources.
>Jul 25 11:34:31 rhel-9-3-230725 systemd[1]: Stopping Virtualization daemon...
>ââ Subject: A stop job for unit libvirtd.service has begun execution
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ A stop job for unit libvirtd.service has begun execution.
>ââ 
>ââ The job identifier is 2694.
>Jul 25 11:34:31 rhel-9-3-230725 systemd[1]: Starting Virtualization daemon...
>ââ Subject: A start job for unit libvirtd.service has begun execution
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ A start job for unit libvirtd.service has begun execution.
>ââ 
>ââ The job identifier is 2694.
>Jul 25 11:34:31 rhel-9-3-230725 systemd[1]: Started Virtualization daemon.
>ââ Subject: A start job for unit libvirtd.service has finished successfully
>ââ Defined-By: systemd
>ââ Support: https://access.redhat.com/support
>ââ 
>ââ A start job for unit libvirtd.service has finished successfully.
>ââ 
>ââ The job identifier is 2694.
>Jul 25 11:34:31 rhel-9-3-230725 dnsmasq[22359]: read /etc/hosts - 2 addresses
>Jul 25 11:34:31 rhel-9-3-230725 dnsmasq[22359]: read /var/lib/libvirt/dnsmasq/integration.addnhosts - 0 addresses
>Jul 25 11:34:31 rhel-9-3-230725 dnsmasq-dhcp[22359]: read /var/lib/libvirt/dnsmasq/integration.hostsfile
>
>LIBVIRTD SERVICE STATUS:
>[admin@rhel-9-3-230725 rhel-edge]$ sudo systemctl status libvirtd
>â libvirtd.service - Virtualization daemon
>     Loaded: loaded (/usr/lib/systemd/system/libvirtd.service; disabled; preset: disabled)
>     Active: active (running) since Tue 2023-07-25 11:34:31 EDT; 12min ago
>TriggeredBy: â libvirtd-tls.socket
>             â libvirtd-admin.socket
>             â libvirtd-tcp.socket
>             â libvirtd.socket
>             â libvirtd-ro.socket
>       Docs: man:libvirtd(8)
>             https://libvirt.org
>    Process: 53113 ExecStart=/usr/sbin/libvirtd $LIBVIRTD_ARGS (code=exited, status=0/SUCCESS)
>   Main PID: 53113 (code=exited, status=0/SUCCESS)
>      Tasks: 2 (limit: 32768)
>     Memory: 23.4M
>        CPU: 276ms
>     CGroup: /system.slice/libvirtd.service
>             ââ22359 /usr/sbin/dnsmasq --conf-file=/var/lib/libvirt/dnsmasq/integration.conf --leasefile-ro --dhcp-script=/usr/libexec/libvirt_leaseshelper
>             ââ22361 /usr/sbin/dnsmasq --conf-file=/var/lib/libvirt/dnsmasq/integration.conf --leasefile-ro --dhcp-script=/usr/libexec/libvirt_leaseshelper
>
>Jul 25 11:34:31 rhel-9-3-230725 systemd[1]: Starting Virtualization daemon...
>Jul 25 11:34:31 rhel-9-3-230725 systemd[1]: Started Virtualization daemon.
>Jul 25 11:34:31 rhel-9-3-230725 dnsmasq[22359]: read /etc/hosts - 2 addresses
>Jul 25 11:34:31 rhel-9-3-230725 dnsmasq[22359]: read /var/lib/libvirt/dnsmasq/integration.addnhosts - 0 addresses
>Jul 25 11:34:31 rhel-9-3-230725 dnsmasq-dhcp[22359]: read /var/lib/libvirt/dnsmasq/integration.hostsfile
>
>VIRT-INSTALL HANGS
>[admin@rhel-9-3-230725 rhel-edge]$ sudo virt-install --name=ostree-79698da7-8f2a-453f-990a-491073bf6750 --disk path=/var/lib/libvirt/images/ostree-79698da7-8f2a-453f-990a-491073bf6750.qcow2,format=qcow2 --ram 3072 --vcpus 2 --network network=integration,mac=34:49:22:B0:83:30 --os-type linux --os-variant rhel9-unknown --pxe --boot uefi --nographics --noreboot --debug
>[Tue, 25 Jul 2023 10:36:18 virt-install 52691] DEBUG (cli:204) Version 4.1.0 launched with command line: /bin/virt-install --name=ostree-79698da7-8f2a-453f-990a-491073bf6750 --disk path=/var/lib/libvirt/images/ostree-79698da7-8f2a-453f-990a-491073bf6750.qcow2,format=qcow2 --ram 3072 --vcpus 2 --network network=integration,mac=34:49:22:B0:83:30 --os-type linux --os-variant rhel9-unknown --pxe --boot uefi --nographics --noreboot --debug
>[Tue, 25 Jul 2023 10:36:18 virt-install 52691] DEBUG (virtinstall:212) Distilled --network options: ['network=integration,mac=34:49:22:B0:83:30']
>[Tue, 25 Jul 2023 10:36:18 virt-install 52691] DEBUG (virtinstall:249) --graphics compat generated: none
>[Tue, 25 Jul 2023 10:36:18 virt-install 52691] DEBUG (virtinstall:142) Distilled --disk options: ['path=/var/lib/libvirt/images/ostree-79698da7-8f2a-453f-990a-491073bf6750.qcow2,format=qcow2']
>[Tue, 25 Jul 2023 10:36:18 virt-install 52691] WARNING (virtinstall:148) --os-type is deprecated and does nothing. Please stop using it.
>[Tue, 25 Jul 2023 10:36:18 virt-install 52691] DEBUG (cli:217) Requesting libvirt URI default

Actions: View

Attachments on bug 2225667: 1977520