Menu
▾
▴
#555 Windows machine doesn't boot until connect to local console via vnc
open
nobody
1
2015-03-02
2014-10-08
No
- Host kernel version: Linux 3.2.0-4-amd64 #1 SMP Debian 3.2.46-1+deb7u1 x86_64 GNU/Linux
- Host kernel arch: x86_64
- Host OS: Debian GNU/Linux 7.1 (wheezy)
- Host CPU:
vendor_id : AuthenticAMD
cpu family : 21
model : 1
model name : AMD Opteron(TM) Processor 6272
stepping : 2
microcode : 0x6000626
cpu MHz : 2099.959
cache size : 2048 KB
physical id : 1
siblings : 16
core id : 7
cpu cores : 8
apicid : 47
initial apicid : 47
fpu : yes
fpu_exception : yes
cpuid level : 13
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 ht syscall nx mmxext fxsr_opt pdpe1gb rdtscp lm constant_tsc rep_good nopl nonstop_tsc extd_apicid amd_dcm aperfmperf pni pclmulqdq monitor ssse3 cx16 sse4_1 sse4_2 popcnt aes xsave avx lahf_lm cmp_legacy svm extapic cr8_legacy abm sse4a misalignsse 3dnowprefetch osvw ibs xop skinit wdt lwp fma4 nodeid_msr topoext perfctr_core arat cpb hw_pstate npt lbrv svm_lock nrip_save tsc_scale vmcb_clean flushbyasid decodeassists pausefilter pfthreshold
bogomips : 4200.00
TLB size : 1536 4K pages
clflush size : 64
cache_alignment : 64
address sizes : 48 bits physical, 48 bits virtual
power management: ts ttp tm 100mhzsteps hwpstate [9] - Guest OS: Windows 7 32bits
- qemu command:
/usr/bin/kvm -S -M pc-1.1 -enable-kvm -m 4096 -smp 2,sockets=2,cores=1,threads=1 -name vm-win7-selenium-03 -uuid f82a36f8-bc08-0579-6267-96364d285f15 -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/vm-win7-selenium-03.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=localtime -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -drive file=/dev/vm-storage/vm-win7-selenium-03,if=none,id=drive-virtio-disk0,format=raw,cache=none -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=21,id=hostnet0 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=52:54:00:d1:25:e3,bus=pci.0,addr=0x3 -chardev pty,id=charserial0 -device isa-serial,chardev=charserial0,id=serial0 -device usb-tablet,id=input0 -vnc 0.0.0.0:24 -k es -vga vmware -device intel-hda,id=sound0
,bus=pci.0,addr=0x4 -device hda-duplex,id=sound0-codec0,bus=sound0.0,cad=0 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x5 - qemu version: QEMU emulator version 1.1.2 (qemu-kvm-1.1.2+dfsg-6, Debian), Copyright (c) 2003-2008 Fabrice Bellard
Hi, I'm facing an intermitent problem with the configuration from above.
Most of the times the machine boot without no problem, but sometimes (I couldn't reproduce it at will) it does not boot until I connect to the local console using VNC (in this case using port 5924). I've tried changing the -vga parameter from vga (stdvga) to vmware, and nothing changed.
Here you have more info about the kvm process when the machine is not starting:
# strace -f -p 23392
Process 23392 attached with 3 threads - interrupt to quit
[pid 23394] rt_sigtimedwait([BUS USR1], <unfinished ...>
[pid 23393] futex(0x7f6ea86e9a80, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 23394] <... rt_sigtimedwait resumed> 0x7f6e9bbedb40, {0, 0}, 8) = -1 EAGAIN (Resource temporarily unavailable)
[pid 23392] select(19, [7 8 11 15 16 17 18], [], [], NULL <unfinished ...>
[pid 23394] rt_sigpending([]) = 0
[pid 23394] futex(0x7f6ea86e9a80, FUTEX_WAKE_PRIVATE, 1) = 1
[pid 23393] <... futex resumed> ) = 0
[pid 23394] ioctl(14, KVM_RUN <unfinished ...>
[pid 23393] rt_sigtimedwait([BUS USR1], 0x7f6e9c3eeb40, {0, 0}, 8) = -1 EAGAIN (Resource temporarily unavailable)
[pid 23393] rt_sigpending([]) = 0
[pid 23393] futex(0x7f6ea86e9a80, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 23393] ioctl(13, KVM_RUN
(it stopped here)
# lsof -n -p 23392
COMMAND PID USER FD TYPE DEVICE SIZE/OFF NODE NAME
kvm 23392 libvirt-qemu cwd DIR 253,0 4096 128 /
kvm 23392 libvirt-qemu rtd DIR 253,0 4096 128 /
kvm 23392 libvirt-qemu txt REG 253,0 4698608 33668514 /usr/bin/kvm
kvm 23392 libvirt-qemu mem REG 253,0 1585496 33621605 /usr/lib/x86_64-linux-gnu/libdb-5.1.so
kvm 23392 libvirt-qemu mem REG 253,0 26600 33621989 /usr/lib/x86_64-linux-gnu/sasl2/libsasldb.so.2.0.25
kvm 23392 libvirt-qemu mem REG 253,0 25848 33603676 /usr/lib/x86_64-linux-gnu/libogg.so.0.8.0
kvm 23392 libvirt-qemu mem REG 253,0 183064 33603680 /usr/lib/x86_64-linux-gnu/libvorbis.so.0.4.5
kvm 23392 libvirt-qemu mem REG 253,0 2943152 33627828 /usr/lib/x86_64-linux-gnu/libvorbisenc.so.2.0.8
kvm 23392 libvirt-qemu mem REG 253,0 312176 33603770 /usr/lib/x86_64-linux-gnu/libFLAC.so.8.2.0
kvm 23392 libvirt-qemu mem REG 253,0 89056 16777380 /lib/x86_64-linux-gnu/libnsl-2.13.so
kvm 23392 libvirt-qemu mem REG 253,0 60456 33623751 /usr/lib/x86_64-linux-gnu/libXi.so.6.1.0
kvm 23392 libvirt-qemu mem REG 253,0 20648 33620976 /usr/lib/x86_64-linux-gnu/libXdmcp.so.6.0.0
kvm 23392 libvirt-qemu mem REG 253,0 10896 33620843 /usr/lib/x86_64-linux-gnu/libXau.so.6.0.0
kvm 23392 libvirt-qemu mem REG 253,0 196576 16777478 /lib/x86_64-linux-gnu/libncursesw.so.5.9
kvm 23392 libvirt-qemu mem REG 253,0 1252128 16777449 /lib/x86_64-linux-gnu/libslang.so.2.2.4
kvm 23392 libvirt-qemu mem REG 253,0 18672 16777348 /lib/x86_64-linux-gnu/libattr.so.1.1.0
kvm 23392 libvirt-qemu mem REG 253,0 19536 33621083 /usr/lib/x86_64-linux-gnu/libasyncns.so.0.3.1
kvm 23392 libvirt-qemu mem REG 253,0 405552 33627830 /usr/lib/x86_64-linux-gnu/libsndfile.so.1.0.25
kvm 23392 libvirt-qemu mem REG 253,0 40656 16804831 /lib/x86_64-linux-gnu/libwrap.so.0.7.6
kvm 23392 libvirt-qemu mem REG 253,0 21880 33627836 /usr/lib/x86_64-linux-gnu/libXtst.so.6.1.0
kvm 23392 libvirt-qemu mem REG 253,0 30096 33627826 /usr/lib/x86_64-linux-gnu/libSM.so.6.0.1
kvm 23392 libvirt-qemu mem REG 253,0 96320 33627798 /usr/lib/x86_64-linux-gnu/libICE.so.6.3.0
kvm 23392 libvirt-qemu mem REG 253,0 4472 33625625 /usr/lib/x86_64-linux-gnu/libX11-xcb.so.1.0.0
kvm 23392 libvirt-qemu mem REG 253,0 14112 16777471 /lib/x86_64-linux-gnu/libgpg-error.so.0.8.0
kvm 23392 libvirt-qemu mem REG 253,0 14320 16778222 /lib/x86_64-linux-gnu/libkeyutils.so.1.4
kvm 23392 libvirt-qemu mem REG 253,0 35400 33621746 /usr/lib/x86_64-linux-gnu/libkrb5support.so.0.1
kvm 23392 libvirt-qemu mem REG 253,0 14672 16777357 /lib/x86_64-linux-gnu/libcom_err.so.2.1
kvm 23392 libvirt-qemu mem REG 253,0 162632 33621338 /usr/lib/x86_64-linux-gnu/libk5crypto.so.3.1
kvm 23392 libvirt-qemu mem REG 253,0 868096 33621125 /usr/lib/x86_64-linux-gnu/libkrb5.so.3.3
kvm 23392 libvirt-qemu mem REG 253,0 1966560 33621660 /usr/lib/x86_64-linux-gnu/libcrypto.so.1.0.0
kvm 23392 libvirt-qemu mem REG 253,0 387800 33621661 /usr/lib/x86_64-linux-gnu/libssl.so.1.0.0
kvm 23392 libvirt-qemu mem REG 253,0 548824 33620972 /usr/lib/x86_64-linux-gnu/libpixman-1.so.0.26.0
kvm 23392 libvirt-qemu mem REG 253,0 129840 33620978 /usr/lib/x86_64-linux-gnu/libxcb.so.1.1.0
kvm 23392 libvirt-qemu mem REG 253,0 10936 33603747 /usr/lib/x86_64-linux-gnu/libts-0.0.so.0.1.1
kvm 23392 libvirt-qemu mem REG 253,0 95568 33603751 /usr/lib/x86_64-linux-gnu/libdirect-1.2.so.9.0.1
kvm 23392 libvirt-qemu mem REG 253,0 40120 33603752 /usr/lib/x86_64-linux-gnu/libfusion-1.2.so.9.0.1
kvm 23392 libvirt-qemu mem REG 253,0 543736 33603759 /usr/lib/x86_64-linux-gnu/libdirectfb-1.2.so.9.0.1
kvm 23392 libvirt-qemu mem REG 253,0 74408 33623745 /usr/lib/x86_64-linux-gnu/libXext.so.6.4.0
kvm 23392 libvirt-qemu mem REG 253,0 18816 33627839 /usr/lib/x86_64-linux-gnu/libpulse-simple.so.0.0.3
kvm 23392 libvirt-qemu mem REG 253,0 72808 33621662 /usr/lib/x86_64-linux-gnu/libp11-kit.so.0.0.0
kvm 23392 libvirt-qemu mem REG 253,0 68064 33621637 /usr/lib/x86_64-linux-gnu/libtasn1.so.3.1.16
kvm 23392 libvirt-qemu mem REG 253,0 80712 16777378 /lib/x86_64-linux-gnu/libresolv-2.13.so
kvm 23392 libvirt-qemu mem REG 253,0 17112 16778036 /lib/x86_64-linux-gnu/libcap.so.2.22
kvm 23392 libvirt-qemu mem REG 253,0 286488 16805289 /lib/x86_64-linux-gnu/libdbus-1.so.3.7.2
kvm 23392 libvirt-qemu mem REG 253,0 404440 16831623 /usr/lib/x86_64-linux-gnu/pulseaudio/libpulsecommon-2.0.so
kvm 23392 libvirt-qemu mem REG 253,0 39744 16831616 /lib/x86_64-linux-gnu/libjson.so.0.1.0
kvm 23392 libvirt-qemu mem REG 253,0 14768 16777369 /lib/x86_64-linux-gnu/libdl-2.13.so
kvm 23392 libvirt-qemu mem REG 253,0 108216 33603709 /usr/lib/x86_64-linux-gnu/librtmp.so.0
kvm 23392 libvirt-qemu mem REG 253,0 516128 16777493 /lib/x86_64-linux-gnu/libgcrypt.so.11.7.0
kvm 23392 libvirt-qemu mem REG 253,0 257288 33621128 /usr/lib/x86_64-linux-gnu/libgssapi_krb5.so.2.2
kvm 23392 libvirt-qemu mem REG 253,0 323088 33621129 /usr/lib/x86_64-linux-gnu/libldap_r-2.4.so.2.8.3
kvm 23392 libvirt-qemu mem REG 253,0 59640 33618046 /usr/lib/x86_64-linux-gnu/liblber-2.4.so.2.8.3
kvm 23392 libvirt-qemu mem REG 253,0 167080 33603710 /usr/lib/x86_64-linux-gnu/libssh2.so.1.0.1
kvm 23392 libvirt-qemu mem REG 253,0 211376 33621631 /usr/lib/x86_64-linux-gnu/libidn.so.11.6.8
kvm 23392 libvirt-qemu mem REG 253,0 248968 16805648 /lib/x86_64-linux-gnu/libpcre.so.3.13.1
kvm 23392 libvirt-qemu mem REG 253,0 1595408 16777376 /lib/x86_64-linux-gnu/libc-2.13.so
kvm 23392 libvirt-qemu mem REG 253,0 131107 16777366 /lib/x86_64-linux-gnu/libpthread-2.13.so
kvm 23392 libvirt-qemu mem REG 253,0 92752 16777459 /lib/x86_64-linux-gnu/libz.so.1.2.7
kvm 23392 libvirt-qemu mem REG 253,0 530736 16777373 /lib/x86_64-linux-gnu/libm-2.13.so
kvm 23392 libvirt-qemu mem REG 253,0 1065224 33627882 /usr/lib/x86_64-linux-gnu/libspice-server.so.1.1.0
kvm 23392 libvirt-qemu mem REG 253,0 5464 16805139 /lib/x86_64-linux-gnu/libaio.so.1.0.1
kvm 23392 libvirt-qemu mem REG 253,0 1289744 33603652 /usr/lib/x86_64-linux-gnu/libX11.so.6.3.0
kvm 23392 libvirt-qemu mem REG 253,0 453232 33627859 /usr/lib/x86_64-linux-gnu/libSDL-1.2.so.0.11.4
kvm 23392 libvirt-qemu mem REG 253,0 785552 33621617 /usr/lib/x86_64-linux-gnu/libgnutls.so.26.22.4
kvm 23392 libvirt-qemu mem REG 253,0 109360 33621853 /usr/lib/x86_64-linux-gnu/libsasl2.so.2.0.25
kvm 23392 libvirt-qemu mem REG 253,0 239336 33603774 /usr/lib/x86_64-linux-gnu/libjpeg.so.8.4.0
kvm 23392 libvirt-qemu mem REG 253,0 158640 16805393 /lib/x86_64-linux-gnu/libpng12.so.0.49.0
kvm 23392 libvirt-qemu mem REG 253,0 18896 16777455 /lib/x86_64-linux-gnu/libuuid.so.1.3.0
kvm 23392 libvirt-qemu mem REG 253,0 21416 50147 /usr/lib/libvdeplug.so.2.1.0
kvm 23392 libvirt-qemu mem REG 253,0 999464 33621081 /usr/lib/x86_64-linux-gnu/libasound.so.2.0.0
kvm 23392 libvirt-qemu mem REG 253,0 303864 33627838 /usr/lib/x86_64-linux-gnu/libpulse.so.0.14.2
kvm 23392 libvirt-qemu mem REG 253,0 51328 16827005 /lib/x86_64-linux-gnu/libbrlapi.so.0.5.7
kvm 23392 libvirt-qemu mem REG 253,0 167952 16777441 /lib/x86_64-linux-gnu/libtinfo.so.5.9
kvm 23392 libvirt-qemu mem REG 253,0 138864 16777407 /lib/x86_64-linux-gnu/libncurses.so.5.9
kvm 23392 libvirt-qemu mem REG 253,0 410216 33603728 /usr/lib/x86_64-linux-gnu/libcurl-gnutls.so.4.2.0
kvm 23392 libvirt-qemu mem REG 253,0 116832 33621103 /usr/lib/x86_64-linux-gnu/libbluetooth.so.3.12.0
kvm 23392 libvirt-qemu mem REG 253,0 10640 16777365 /lib/x86_64-linux-gnu/libutil-2.13.so
kvm 23392 libvirt-qemu mem REG 253,0 22552 33627864 /usr/lib/x86_64-linux-gnu/libusbredirparser.so.0.0.0
kvm 23392 libvirt-qemu mem REG 253,0 80512 33627800 /usr/lib/x86_64-linux-gnu/libiscsi.so.1.0.10400
kvm 23392 libvirt-qemu mem REG 253,0 1012792 16805163 /lib/x86_64-linux-gnu/libglib-2.0.so.0.3200.4
kvm 23392 libvirt-qemu mem REG 253,0 6120 33621088 /usr/lib/x86_64-linux-gnu/libgthread-2.0.so.0.3200.4
kvm 23392 libvirt-qemu mem REG 253,0 31744 16777371 /lib/x86_64-linux-gnu/librt-2.13.so
kvm 23392 libvirt-qemu mem REG 253,0 136936 16777379 /lib/x86_64-linux-gnu/ld-2.13.so
kvm 23392 libvirt-qemu mem REG 253,47 217016 19701 /var/cache/nscd/group
kvm 23392 libvirt-qemu mem REG 253,0 817640 33621108 /usr/lib/x86_64-linux-gnu/libcaca.so.0.99.18
kvm 23392 libvirt-qemu mem REG 0,9 2540 anon_inode:kvm-vcpu (stat: No such file or directory)
kvm 23392 libvirt-qemu 0u CHR 1,3 0t0 1028 /dev/null
kvm 23392 libvirt-qemu 1w REG 253,47 31810 402656955 /var/log/libvirt/qemu/vm-win7-selenium-03.log
kvm 23392 libvirt-qemu 2w REG 253,47 31810 402656955 /var/log/libvirt/qemu/vm-win7-selenium-03.log
kvm 23392 libvirt-qemu 3u unix 0xffff883018edc780 0t0 30759843 /var/lib/libvirt/qemu/vm-win7-selenium-03.monitor
kvm 23392 libvirt-qemu 4u CHR 5,2 0t0 1108 /dev/ptmx
kvm 23392 libvirt-qemu 5u CHR 10,232 0t0 20536 /dev/kvm
kvm 23392 libvirt-qemu 6u 0000 0,9 0 2540 anon_inode
kvm 23392 libvirt-qemu 7u 0000 0,9 0 2540 anon_inode
kvm 23392 libvirt-qemu 8u 0000 0,9 0 2540 anon_inode
kvm 23392 libvirt-qemu 9u 0000 0,9 0 2540 anon_inode
kvm 23392 libvirt-qemu 10u BLK 253,55 0xa00000000 11375075 /dev/dm-55
kvm 23392 libvirt-qemu 11r FIFO 0,8 0t0 30897160 pipe
kvm 23392 libvirt-qemu 12w FIFO 0,8 0t0 30897160 pipe
kvm 23392 libvirt-qemu 13u 0000 0,9 0 2540 anon_inode
kvm 23392 libvirt-qemu 14u 0000 0,9 0 2540 anon_inode
kvm 23392 libvirt-qemu 15u IPv4 30823067 0t0 TCP *:5924 (LISTEN)
kvm 23392 libvirt-qemu 16u 0000 0,9 0 2540 anon_inode
kvm 23392 libvirt-qemu 17u unix 0xffff8803b56261c0 0t0 30823068 /var/lib/libvirt/qemu/vm-win7-selenium-03.monitor
kvm 23392 libvirt-qemu 18u 0000 0,9 0 2540 anon_inode
kvm 23392 libvirt-qemu 21u CHR 10,200 0t0 5144 /dev/net/tun
# netstat -apon | grep 23392
tcp 0 0 0.0.0.0:5924 0.0.0.0:* LISTEN 23392/kvm off (0.00/0/0)
unix 2 [ ACC ] STREAM LISTENING 30759843 23392/kvm /var/lib/libvirt/qemu/vm-win7-selenium-03.monitor
unix 3 [ ] STREAM CONNECTED 30823068 23392/kvm /var/lib/libvirt/qemu/vm-win7-selenium-03.monitor
And after I connect to the local console via vnc the strace continue as follows:
# strace -s 1024 -f -p 23392
<unfinished ...>
[pid 23392] <... select resumed> ) = 1 (in [15])
[pid 23392] accept4(15, {sa_family=AF_INET, sin_port=htons(36990), sin_addr=inet_addr("192.168.10.19")}, [16], SOCK_CLOEXEC) = 19
[pid 23392] fcntl(19, F_GETFL) = 0x2 (flags O_RDWR)
[pid 23392] fcntl(19, F_SETFL, O_RDWR|O_NONBLOCK) = 0
[pid 23392] write(9, "\1\0\0\0\0\0\0\0", 8) = 8
[pid 23392] getpeername(19, {sa_family=AF_INET, sin_port=htons(36990), sin_addr=inet_addr("192.168.10.19")}, [16]) = 0
[pid 23392] getsockname(15, {sa_family=AF_INET, sin_port=htons(5924), sin_addr=inet_addr("0.0.0.0")}, [16]) = 0
[pid 23392] write(17, "{\"timestamp\": {\"seconds\": 1412753973, \"microseconds\": 283489}, \"event\": \"VNC_CONNECTED\", \"data\": {\"server\": {\"auth\": \"none\", \"family\": \"ipv4\", \"service\": \"5924\", \"host\": \"0.0.0.0\"}, \"client\": {\"family\": \"ipv4\", \"service\": \"36990\", \"host\": \"192.168.10.19\"}}}\r\n", 259) = 259
[pid 23392] ioctl(6, KVM_GET_DIRTY_LOG, 0x7fffc247ac10) = 0
[pid 23392] ioctl(6, KVM_GET_DIRTY_LOG, 0x7fffc247ac10) = 0
[pid 23392] write(9, "\1\0\0\0\0\0\0\0", 8) = 8
[pid 23392] sendto(19, "RFB 003.008\n", 12, 0, NULL, 0) = 12
[pid 23392] write(9, "\1\0\0\0\0\0\0\0", 8) = 8
[pid 23392] ioctl(6, KVM_GET_DIRTY_LOG, 0x7fffc247abd0) = 0
[pid 23392] ioctl(6, KVM_GET_DIRTY_LOG, 0x7fffc247abd0) = 0
[pid 23392] ioctl(6, KVM_IRQ_LINE_STATUS, 0x7fffc247aed0) = 0
[pid 23392] ioctl(6, KVM_IRQ_LINE_STATUS, 0x7fffc247aef0) = 0
[pid 23392] ioctl(6, KVM_IRQ_LINE_STATUS, 0x7fffc247aec0) = 0
[pid 23394] <... ioctl resumed> , 0) = 0
[pid 23392] ioctl(6, KVM_IRQ_LINE_STATUS <unfinished ...>
[pid 23394] futex(0x7f6ea86e9a80, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 23392] <... ioctl resumed> , 0x7fffc247aed0) = 0
[pid 23393] <... ioctl resumed> , 0) = 0
[pid 23392] ioctl(6, KVM_IRQ_LINE_STATUS <unfinished ...>
[pid 23393] futex(0x7f6ea86e9a80, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 23392] <... ioctl resumed> , 0x7fffc247aef0) = 0
[pid 23392] write(9, "\1\0\0\0\0\0\0\0", 8) = 8
[pid 23392] timer_gettime(0x19, {it_interval={0, 0}, it_value={0, 0}}) = 0
[pid 23392] timer_settime(0x19, 0, {it_interval={0, 0}, it_value={0, 250000}}, NULL) = 0
[pid 23392] timer_gettime(0x19, {it_interval={0, 0}, it_value={0, 192795}}) = 0
[pid 23392] timer_gettime(0x19, {it_interval={0, 0}, it_value={0, 138247}}) = 0
In the cases it works without intervention it seems to continue the "select" call from the fd 7.
Any ideas?
Thanks.
Jordi.
