Re: FreeBSD startup timer hangs on boot

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 

Problem solved by upgrading to 2.6.39 kernel from 2.6.38, at another
server I use 2.6.38 kernel, but there is no such problem
Seems some problem between newer versions of qemu-kvm and kvm kernel
version and also result of fixing "KVM: fix kvmclock regression due to
missing clock update"


On 24 May 2011 16:43, Nikita A Menkovich <menkovich@xxxxxxxxx> wrote:
> 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
>



-- 
Nikita A Menkovich
http://libc6.org/
JID: menkovich@xxxxxxxxx
Tel: +7 (921) 423-96-48
--
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
[Index of Archives]     [KVM ARM]     [KVM ia64]     [KVM ppc]     [Virtualization Tools]     [Spice Development]     [Libvirt]     [Libvirt Users]     [Linux USB Devel]     [Linux Audio Users]     [Yosemite Questions]     [Linux Kernel]     [Linux SCSI]     [XFree86]
  Powered by Linux