Hello, I receive strange problem with running FreeBSD guest (7 and 8, x86 and amd64) on KVM. When begins boot menu, there is time countdown 10..9..8 etc, and time between 10 and 9 can be different than 1 sec 0.9, 1.1 etc. And sometimes countdown hangs on some number. QEMU emulator version 0.14.0 (qemu-kvm-0.14.0 Debian 0.14.0+dfsg-1~tls), Copyright (c) 2003-2008 Fabrice Bellard libvirt 0.9.1 Linux 2.6.38 x86_64 libvirt.xml http://pastebin.com/03vJKhVc strace -c -p VMPID % time seconds usecs/call calls errors syscall ------ ----------- ----------- --------- --------- ---------------- 99.81 2.432429 334 7277 select 0.11 0.002591 0 18310 timer_gettime 0.06 0.001355 0 14404 ioctl 0.01 0.000218 0 10542 3514 read 0.01 0.000217 0 3514 timer_settime 0.00 0.000070 0 3514 rt_sigaction 0.00 0.000053 0 3869 write 0.00 0.000033 0 303 recvmsg ------ ----------- ----------- --------- --------- ---------------- 100.00 2.436966 61733 3514 total strace -p VMPID write(7, "\1\0\0\0\0\0\0\0", 8) = 8 read(13, 0x7fff119dae90, 128) = -1 EAGAIN (Resource temporarily unavailable) timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 0}}) = 0 timer_settime(0x2, 0, {it_interval={0, 0}, it_value={0, 250000}}, NULL) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 138172}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0}) = 1 (in [6], left {0, 999998}) read(6, "\1\0\0\0\0\0\0\0", 512) = 8 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0}) = 1 (in [13], left {0, 998108}) read(13, "\16\0\0\0\0\0\0\0\376\377\377\377\0\0\0\0\0\0\0\0\0\0\0\0\2\0\0\0\0\0\0\0"..., 128) = 128 rt_sigaction(SIGALRM, NULL, {0x4d0490, ~[KILL STOP RTMIN RT_1], SA_RESTORER, 0x7fb09b6c9f60}, 8) = 0 write(7, "\1\0\0\0\0\0\0\0", 8) = 8 read(13, 0x7fff119dae90, 128) = -1 EAGAIN (Resource temporarily unavailable) timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 0}}) = 0 timer_settime(0x2, 0, {it_interval={0, 0}, it_value={0, 250000}}, NULL) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 144317}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0}) = 1 (in [6], left {0, 999998}) read(6, "\1\0\0\0\0\0\0\0", 512) = 8 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0}) = 1 (in [13], left {0, 997811}) read(13, "\16\0\0\0\0\0\0\0\376\377\377\377\0\0\0\0\0\0\0\0\0\0\0\0\2\0\0\0\0\0\0\0"..., 128) = 128 rt_sigaction(SIGALRM, NULL, {0x4d0490, ~[KILL STOP RTMIN RT_1], SA_RESTORER, 0x7fb09b6c9f60}, 8) = 0 write(7, "\1\0\0\0\0\0\0\0", 8) = 8 read(13, 0x7fff119dae90, 128) = -1 EAGAIN (Resource temporarily unavailable) timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 0}}) = 0 timer_settime(0x2, 0, {it_interval={0, 0}, it_value={0, 250000}}, NULL) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 133651}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0}) = 1 (in [6], left {0, 999998}) read(6, "\1\0\0\0\0\0\0\0", 512) = 8 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0}) = 1 (in [13], left {0, 998027}) read(13, "\16\0\0\0\0\0\0\0\376\377\377\377\0\0\0\0\0\0\0\0\0\0\0\0\2\0\0\0\0\0\0\0"..., 128) = 128 rt_sigaction(SIGALRM, NULL, {0x4d0490, ~[KILL STOP RTMIN RT_1], SA_RESTORER, 0x7fb09b6c9f60}, 8) = 0 write(7, "\1\0\0\0\0\0\0\0", 8) = 8 read(13, 0x7fff119dae90, 128) = -1 EAGAIN (Resource temporarily unavailable) timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 0}}) = 0 timer_settime(0x2, 0, {it_interval={0, 0}, it_value={0, 250000}}, NULL) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 136813}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0}) = 1 (in [6], left {0, 999998}) read(6, "\1\0\0\0\0\0\0\0", 512) = 8 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0}) = 1 (in [13], left {0, 998035}) read(13, "\16\0\0\0\0\0\0\0\376\377\377\377\0\0\0\0\0\0\0\0\0\0\0\0\2\0\0\0\0\0\0\0"..., 128) = 128 rt_sigaction(SIGALRM, NULL, {0x4d0490, ~[KILL STOP RTMIN RT_1], SA_RESTORER, 0x7fb09b6c9f60}, 8) = 0 write(7, "\1\0\0\0\0\0\0\0", 8) = 8 read(13, 0x7fff119dae90, 128) = -1 EAGAIN (Resource temporarily unavailable) timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 0}}) = 0 timer_settime(0x2, 0, {it_interval={0, 0}, it_value={0, 250000}}, NULL) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 139724}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0}) = 1 (in [6], left {0, 999998}) read(6, "\1\0\0\0\0\0\0\0", 512) = 8 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0}) = 1 (in [13], left {0, 997792}) read(13, "\16\0\0\0\0\0\0\0\376\377\377\377\0\0\0\0\0\0\0\0\0\0\0\0\2\0\0\0\0\0\0\0"..., 128) = 128 rt_sigaction(SIGALRM, NULL, {0x4d0490, ~[KILL STOP RTMIN RT_1], SA_RESTORER, 0x7fb09b6c9f60}, 8) = 0 write(7, "\1\0\0\0\0\0\0\0", 8) = 8 read(13, 0x7fff119dae90, 128) = -1 EAGAIN (Resource temporarily unavailable) timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 0}}) = 0 timer_settime(0x2, 0, {it_interval={0, 0}, it_value={0, 250000}}, NULL) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 139130}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0}) = 1 (in [6], left {0, 999998}) read(6, "\1\0\0\0\0\0\0\0", 512) = 8 timer_gettime(0x2, {it_interval={0, 0}, it_value={0, 1}}) = 0 ioctl(5, KVM_IRQ_LINE_STATUS, 0x7fff119daeb0) = 0 select(16, [6 9 12 13 14 15], [], [], {1, 0} <unfinished ...> Sometime boot do not hang on coundown and guest boots fine. -- Nikita A Menkovich http://libc6.org/ JID: menkovich@xxxxxxxxx -- To unsubscribe from this list: send the line "unsubscribe kvm" in the body of a message to majordomo@xxxxxxxxxxxxxxx More majordomo info at http://vger.kernel.org/majordomo-info.html
