qemu-devel
[Top][All Lists]
Advanced

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

[Qemu-devel] [Bug 889827] [NEW] Qemu hangs on loadvm


From: Arsky Arsen
Subject: [Qemu-devel] [Bug 889827] [NEW] Qemu hangs on loadvm
Date: Sun, 13 Nov 2011 13:29:30 -0000

Public bug reported:

Hello.

I'm new here but I believe this is a bug in Qemu since I did nothing
special and it stoped to work. Please excuse me if this is not a bug but
my error.

Suddenly loadvm command stoped to work. It restores state (I see this in
SDL window) and after that Qemu hangs, you even can close it only with
SIGKILL. I did nothing special to cause this. Just only saved one more
state with savevm, don't know if this is related.

I already had this trouble, started from a new image and the trouble
disappeared. And now this happened again.

Here is a piece of strace output (at the end, last few syscalls clock_gettime - 
ioctl repeat infinitely):
[pid 32681] ioctl(6, KVM_SET_USER_MEMORY_REGION, 0xbfd6305c) = 0
[pid 32681] ioctl(6, KVM_SET_USER_MEMORY_REGION, 0xbfd6305c) = 0
[pid 32681] ioctl(6, KVM_SET_USER_MEMORY_REGION, 0xbfd6305c) = 0
[pid 32681] ioctl(6, KVM_GET_DIRTY_LOG, 0xbfd6313c) = 0
[pid 32681] ioctl(6, KVM_GET_DIRTY_LOG, 0xbfd6313c) = 0
[pid 32681] ioctl(6, KVM_SET_PIT2 or KVM_SET_VCPU_EVENTS, 0xbfd6332c) = 0
[pid 32681] ioctl(13, KVM_SET_REGS, 0xbfd62d9c) = 0
[pid 32681] ioctl(13, KVM_SET_FPU, 0xbfd62c7c) = 0
[pid 32681] ioctl(13, KVM_SET_SREGS, 0xbfd62e84) = 0
[pid 32681] ioctl(13, KVM_SET_MSRS, 0xbfd62e84) = 49
[pid 32681] ioctl(13, KVM_SET_MP_STATE, 0xbfd62e80) = 0
[pid 32681] ioctl(13, KVM_SET_LAPIC, 0xbfd62a2c) = 0
[pid 32681] ioctl(13, KVM_SET_PIT2 or KVM_SET_VCPU_EVENTS, 0xbfd62e84) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 743993495}) = 0
[pid 32681] write(9, "\1\0\0\0\0\0\0\0", 8) = 8
[pid 32681] write(9, "\1\0\0\0\0\0\0\0", 8) = 8
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 744620600}) = 0
[pid 32681] gettimeofday({1321185168, 995648}, NULL) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 744994949}) = 0
[pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
[pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
[pid 32681] tgkill(32681, 32682, SIGRT_6) = 0
[pid 32681] gettimeofday({1321185168, 996771}, NULL) = 0
[pid 32681] write(5, "\0", 1)           = 1
[pid 32679] <... read resumed> "\0", 1) = 1
[pid 32679] exit_group(0)               = ?
[pid 32681] chdir("/")                  = 0
[pid 32681] open("/dev/null", O_RDWR|O_LARGEFILE|O_CLOEXEC) = 16
[pid 32681] dup2(16, 0)                 = 0
[pid 32681] dup2(16, 1)                 = 1
[pid 32681] dup2(16, 2)                 = 2
[pid 32681] close(16)                   = 0
[pid 32681] futex(0x8314124, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 
0x88ea1d4, 2 <unfinished ...>
[pid 32682] <... futex resumed> )       = 0
[pid 32682] futex(0x88ea1d4, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 32681] <... futex resumed> )       = 1
[pid 32681] futex(0x88ea1d4, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 32682] <... futex resumed> )       = 0
[pid 32682] futex(0x88ea1d4, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 32682] ioctl(13, KVM_RUN, 0)       = -1 EINTR (Interrupted system call)
[pid 32682] rt_sigtimedwait([BUS RT_6],  <unfinished ...>
[pid 32681] <... futex resumed> )       = 1
[pid 32682] <... rt_sigtimedwait resumed> {si_signo=SIGRT_6, si_code=SI_TKILL, 
si_pid=32681, si_uid=1000, si_value={int=0, ptr=0}}, {0, 0}, 8) = 38
[pid 32681] select(13, [3 7 8 10 12], [], [], {0, 0} <unfinished ...>
[pid 32682] rt_sigpending( <unfinished ...>
[pid 32681] <... select resumed> )      = 3 (in [7 8 12], left {0, 0})
[pid 32682] <... rt_sigpending resumed> [USR2]) = 0
[pid 32681] futex(0x88ea1d4, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 32682] futex(0x88ea1d4, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 32681] <... futex resumed> )       = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32682] <... futex resumed> )       = 0
[pid 32681] read(12,  <unfinished ...>
[pid 32682] ioctl(13, KVM_RUN <unfinished ...>
[pid 32681] <... read resumed> 
"\f\0\0\0\0\0\0\0\0\0\0\0\251\177\0\0\350\3\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 
128) = 128
[pid 32681] read(8, "~*\0\0\0\0\0\0", 512) = 8
[pid 32681] read(7,  <unfinished ...>
[pid 32682] <... ioctl resumed> , 0)    = 0
[pid 32681] <... read resumed> 
"\16\0\0\0\0\0\0\0\376\377\377\377\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 
128) = 128
[pid 32682] futex(0x88ea1d4, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 32681] rt_sigaction(SIGALRM, NULL, {0x811bd80, ~[KILL STOP RTMIN RT_1], 
0}, 8) = 0
[pid 32681] write(9, "\1\0\0\0\0\0\0\0", 8) = 8
[pid 32681] read(7, 0xbfd635ac, 128)    = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 757376201}) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 757540467}) = 0
[pid 32681] gettimeofday({1321185169, 8534}, NULL) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 757846302}) = 0
[pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
[pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 758327369}) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 758470124}) = 0
[pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN|POLLOUT}])
[pid 32681] read(14, "\34\0i\0\3\0 
\2j\1\0\0:\321\275\4\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096) = 192
[pid 32681] writev(14, [{"\22\0\7\0\3\0 
\2'\0\0\0\37\0\0\0\10\1\4\0\4\0\0\0QEMU\22\0\7\0"..., 116}, {NULL, 0}, {"", 
0}], 3) = 116
[pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
[pid 32681] read(14, "\34\0j\0\3\0 
\2'\0\0\0\2708\277\4\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096) = 160
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=15, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=15, 
revents=POLLOUT}])
[pid 32681] writev(15, [{"+\0\1\0", 4}, {NULL, 0}, {"", 0}], 3) = 4
[pid 32681] poll([{fd=15, events=POLLIN}], 1, -1) = 1 ([{fd=15, 
revents=POLLIN}])
[pid 32681] read(15, 
"\1\2\n\0\0\0\0\0\4\0\340\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
[pid 32681] read(15, 0xa540448, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
[pid 32681] writev(14, [{"\22\0\30\0\3\0 \2(\0\0\0)\0\0\0 
\1\4\0\22\0\0\0\0\0\0\0\0\0\0\0"..., 136}, {NULL, 0}, {"", 0}], 3) = 136
[pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
[pid 32681] read(14, "\34\0o\0\3\0 
\2(\0\0\0\3038\277\4\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096) = 64
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
[pid 32681] writev(14, [{"\20\1\5\0\n\0 \2_WIN_HINTS\4\0", 20}, {NULL, 0}, {"", 
0}], 3) = 20
[pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
[pid 32681] read(14, 
"\1\0r\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
[pid 32681] writev(14, [{"\f\1\5\0\3\0 \2\f\0IN \3\0\0X\2\0\0\f\0\5\0\r\0 
\2\f\0\0\0"..., 44}, {NULL, 0}, {"", 0}], 3) = 44
[pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
[pid 32681] read(14, 
"\0010u\0\2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096) = 
40
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
[pid 32681] writev(14, [{"&\1\2\0s\0\0\0", 8}, {NULL, 0}, {"", 0}], 3) = 8
[pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
[pid 32681] read(14, "\26\0u\0\3\0 \2\3\0 \2\0\0\0\0\4\0\30\0 
\3X\2\0\0\0\0\0\0\0\0", 4096) = 32
[pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
[pid 32681] read(14, 
"\1\1v\0\0\0\0\0s\0\0\0*\1\0\1\352\0\303\2\352\0\303\2\0\0\0\0\0\0\0\0", 4096) 
= 32
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
[pid 32681] writev(14, [{"\214\2\2\0\17\0 \2+\0\1\0", 12}, {NULL, 0}, {"", 0}], 
3) = 12
[pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
[pid 32681] read(14, 
"\1\2x\0\0\0\0\0\4\0\340\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] shmdt(0x747ac000)           = 0
[pid 32681] shmget(IPC_PRIVATE, 1920000, IPC_CREAT|0777) = 6848525
[pid 32681] shmat(6848525, 0, 0)        = 0x73dd7000
[pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
[pid 32681] writev(14, [{"\214\1\4\0\24\0 \2\r\200h\0\0\3\0\0+\2\1\0", 20}, 
{NULL, 0}, {"", 0}], 3) = 20
[pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
[pid 32681] read(14, 
"\1\2z\0\0\0\0\0\4\0\340\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] shmctl(6848525, IPC_64|IPC_RMID, 0) = 0
[pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
[pid 32681] writev(14, [{"+\1\1\0", 4}, {NULL, 0}, {"", 0}], 3) = 4
[pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
[pid 32681] read(14, 
"\1\2{\0\0\0\0\0\4\0\340\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
[pid 32681] writev(14, [{"\2\1\4\0\r\0 address@hidden \2+\2\1\0", 20}, {NULL, 
0}, {"", 0}], 3) = 20
[pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
[pid 32681] read(14, 
"\1\2}\0\0\0\0\0\4\0\340\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
[pid 32681] writev(14, [{"&\1\2\0\r\0 \2", 8}, {NULL, 0}, {"", 0}], 3) = 8
[pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
[pid 32681] read(14, 
"\1\1~\0\0\0\0\0s\0\0\0\0\0\0\0\352\0\303\2\346\0z\2\0\0\0\0\0\0\0\0", 4096) = 
32
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=15, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=15, 
revents=POLLOUT}])
[pid 32681] writev(15, [{"b\0\4\0\7\0 \2", 8}, {"MIT-SHM", 7}, {"\0", 1}], 3) = 
16
[pid 32681] poll([{fd=15, events=POLLIN}], 1, -1) = 1 ([{fd=15, 
revents=POLLIN}])
[pid 32681] read(15, 
"\1\0\v\0\0\0\0\0\1\214M\221\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 
32
[pid 32681] read(15, 0xa540448, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=15, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=15, 
revents=POLLOUT}])
[pid 32681] writev(15, [{"b\0\10\0\27\0 \2", 8}, {"Generic Event Extension", 
23}, {"\0", 1}], 3) = 32
[pid 32681] poll([{fd=15, events=POLLIN}], 1, -1) = 1 ([{fd=15, 
revents=POLLIN}])
[pid 32681] read(15, 
"\1\0\f\0\0\0\0\0\1\212\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
[pid 32681] read(15, 0xa540448, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=15, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=15, 
revents=POLLOUT}])
[pid 32681] writev(15, [{"\212\0\2\0\1\0\0\0", 8}, {NULL, 0}, {"", 0}], 3) = 8
[pid 32681] poll([{fd=15, events=POLLIN}], 1, -1) = 1 ([{fd=15, 
revents=POLLIN}])
[pid 32681] read(15, 
"\1\0\r\0\0\0\0\0\1\0\0\0w\26\10\10\0\0\0\0\0\0\0\0\310\316\357\10\n\204\17\10",
 4096) = 32
[pid 32681] read(15, 0xa540448, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] poll([{fd=15, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=15, 
revents=POLLOUT}])
[pid 32681] writev(15, [{"\214\3\n\0\r\0 \2\16\0 \2 \3X\2\0\0\0\0 
\3X\2\0\0\0\0\30\2\0\0"..., 40}, {NULL, 0}, {"", 0}], 3) = 40
[pid 32681] read(15, 0xa540448, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] select(15, [14], NULL, NULL, {0, 0}) = 0 (Timeout)
[pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
[pid 32681] select(15, [14], NULL, NULL, {0, 0}) = 0 (Timeout)
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 111491325}) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 111667535}) = 0
[pid 32681] gettimeofday({1321185169, 362674}, NULL) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 111990481}) = 0
[pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
[pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
[pid 32681] gettimeofday({1321185169, 363389}, NULL) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 112738342}) = 0
[pid 32681] gettimeofday({1321185169, 363745}, NULL) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 113119396}) = 0
[pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
[pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
[pid 32681] ioctl(6, KVM_IRQ_LINE_STATUS, 0xbfd63594) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 113866418}) = 0
[pid 32681] gettimeofday({1321185169, 364876}, NULL) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 114204170}) = 0
[pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
[pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
[pid 32681] ioctl(6, KVM_IRQ_LINE_STATUS, 0xbfd63594) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 114923815}) = 0
[pid 32681] gettimeofday({1321185169, 365931}, NULL) = 0
[pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 117299536}) = 0
[pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
[pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
[pid 32681] ioctl(6, KVM_IRQ_LINE_STATUS, 0xbfd63594) = 0

Host system is Debian stable 32 bit, guest is Windows XP 32 bit, qemu
version is 0.15.1.

I start qemu with this commandline:
qemu -m 1024 -hda image -localtime -monitor tcp:127.0.0.1:10000,server,nowait 
-net nic,model=rtl8139 -net user,hostfwd=tcp:127.0.0.1:4444-:4444 -cpu host 
-daemonize -loadvm somestate
(Or without -loadvm switch and use loadvm command in the monitor, result is the 
same)

Now I have 4 states/snapshots in the image, the last one I added is 995
Mb, but I can't load old one either (170Mb). My computer has 2Gb of RAM,
I also tried to add 2Gb of swap and that does not help. Tried to delete
new state with qemu-img snapshot -d, but still can't load old states.

I'm not much help in identifying the reason... Probably this is related to 
rather big states comparing with amount of memory...
May be related: https://bugzilla.redhat.com/show_bug.cgi?id=586643

** Affects: qemu
     Importance: Undecided
         Status: New

-- 
You received this bug notification because you are a member of qemu-
devel-ml, which is subscribed to QEMU.
https://bugs.launchpad.net/bugs/889827

Title:
  Qemu hangs on loadvm

Status in QEMU:
  New

Bug description:
  Hello.

  I'm new here but I believe this is a bug in Qemu since I did nothing
  special and it stoped to work. Please excuse me if this is not a bug
  but my error.

  Suddenly loadvm command stoped to work. It restores state (I see this
  in SDL window) and after that Qemu hangs, you even can close it only
  with SIGKILL. I did nothing special to cause this. Just only saved one
  more state with savevm, don't know if this is related.

  I already had this trouble, started from a new image and the trouble
  disappeared. And now this happened again.

  Here is a piece of strace output (at the end, last few syscalls clock_gettime 
- ioctl repeat infinitely):
  [pid 32681] ioctl(6, KVM_SET_USER_MEMORY_REGION, 0xbfd6305c) = 0
  [pid 32681] ioctl(6, KVM_SET_USER_MEMORY_REGION, 0xbfd6305c) = 0
  [pid 32681] ioctl(6, KVM_SET_USER_MEMORY_REGION, 0xbfd6305c) = 0
  [pid 32681] ioctl(6, KVM_GET_DIRTY_LOG, 0xbfd6313c) = 0
  [pid 32681] ioctl(6, KVM_GET_DIRTY_LOG, 0xbfd6313c) = 0
  [pid 32681] ioctl(6, KVM_SET_PIT2 or KVM_SET_VCPU_EVENTS, 0xbfd6332c) = 0
  [pid 32681] ioctl(13, KVM_SET_REGS, 0xbfd62d9c) = 0
  [pid 32681] ioctl(13, KVM_SET_FPU, 0xbfd62c7c) = 0
  [pid 32681] ioctl(13, KVM_SET_SREGS, 0xbfd62e84) = 0
  [pid 32681] ioctl(13, KVM_SET_MSRS, 0xbfd62e84) = 49
  [pid 32681] ioctl(13, KVM_SET_MP_STATE, 0xbfd62e80) = 0
  [pid 32681] ioctl(13, KVM_SET_LAPIC, 0xbfd62a2c) = 0
  [pid 32681] ioctl(13, KVM_SET_PIT2 or KVM_SET_VCPU_EVENTS, 0xbfd62e84) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 743993495}) = 0
  [pid 32681] write(9, "\1\0\0\0\0\0\0\0", 8) = 8
  [pid 32681] write(9, "\1\0\0\0\0\0\0\0", 8) = 8
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 744620600}) = 0
  [pid 32681] gettimeofday({1321185168, 995648}, NULL) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 744994949}) = 0
  [pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
  [pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
  [pid 32681] tgkill(32681, 32682, SIGRT_6) = 0
  [pid 32681] gettimeofday({1321185168, 996771}, NULL) = 0
  [pid 32681] write(5, "\0", 1)           = 1
  [pid 32679] <... read resumed> "\0", 1) = 1
  [pid 32679] exit_group(0)               = ?
  [pid 32681] chdir("/")                  = 0
  [pid 32681] open("/dev/null", O_RDWR|O_LARGEFILE|O_CLOEXEC) = 16
  [pid 32681] dup2(16, 0)                 = 0
  [pid 32681] dup2(16, 1)                 = 1
  [pid 32681] dup2(16, 2)                 = 2
  [pid 32681] close(16)                   = 0
  [pid 32681] futex(0x8314124, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 
0x88ea1d4, 2 <unfinished ...>
  [pid 32682] <... futex resumed> )       = 0
  [pid 32682] futex(0x88ea1d4, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
  [pid 32681] <... futex resumed> )       = 1
  [pid 32681] futex(0x88ea1d4, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
  [pid 32682] <... futex resumed> )       = 0
  [pid 32682] futex(0x88ea1d4, FUTEX_WAKE_PRIVATE, 1) = 0
  [pid 32682] ioctl(13, KVM_RUN, 0)       = -1 EINTR (Interrupted system call)
  [pid 32682] rt_sigtimedwait([BUS RT_6],  <unfinished ...>
  [pid 32681] <... futex resumed> )       = 1
  [pid 32682] <... rt_sigtimedwait resumed> {si_signo=SIGRT_6, 
si_code=SI_TKILL, si_pid=32681, si_uid=1000, si_value={int=0, ptr=0}}, {0, 0}, 
8) = 38
  [pid 32681] select(13, [3 7 8 10 12], [], [], {0, 0} <unfinished ...>
  [pid 32682] rt_sigpending( <unfinished ...>
  [pid 32681] <... select resumed> )      = 3 (in [7 8 12], left {0, 0})
  [pid 32682] <... rt_sigpending resumed> [USR2]) = 0
  [pid 32681] futex(0x88ea1d4, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
  [pid 32682] futex(0x88ea1d4, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
  [pid 32681] <... futex resumed> )       = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32682] <... futex resumed> )       = 0
  [pid 32681] read(12,  <unfinished ...>
  [pid 32682] ioctl(13, KVM_RUN <unfinished ...>
  [pid 32681] <... read resumed> 
"\f\0\0\0\0\0\0\0\0\0\0\0\251\177\0\0\350\3\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 
128) = 128
  [pid 32681] read(8, "~*\0\0\0\0\0\0", 512) = 8
  [pid 32681] read(7,  <unfinished ...>
  [pid 32682] <... ioctl resumed> , 0)    = 0
  [pid 32681] <... read resumed> 
"\16\0\0\0\0\0\0\0\376\377\377\377\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 
128) = 128
  [pid 32682] futex(0x88ea1d4, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
  [pid 32681] rt_sigaction(SIGALRM, NULL, {0x811bd80, ~[KILL STOP RTMIN RT_1], 
0}, 8) = 0
  [pid 32681] write(9, "\1\0\0\0\0\0\0\0", 8) = 8
  [pid 32681] read(7, 0xbfd635ac, 128)    = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 757376201}) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 757540467}) = 0
  [pid 32681] gettimeofday({1321185169, 8534}, NULL) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 757846302}) = 0
  [pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
  [pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 758327369}) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79640, 758470124}) = 0
  [pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN|POLLOUT}])
  [pid 32681] read(14, "\34\0i\0\3\0 
\2j\1\0\0:\321\275\4\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096) = 192
  [pid 32681] writev(14, [{"\22\0\7\0\3\0 
\2'\0\0\0\37\0\0\0\10\1\4\0\4\0\0\0QEMU\22\0\7\0"..., 116}, {NULL, 0}, {"", 
0}], 3) = 116
  [pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
  [pid 32681] read(14, "\34\0j\0\3\0 
\2'\0\0\0\2708\277\4\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096) = 160
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=15, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=15, 
revents=POLLOUT}])
  [pid 32681] writev(15, [{"+\0\1\0", 4}, {NULL, 0}, {"", 0}], 3) = 4
  [pid 32681] poll([{fd=15, events=POLLIN}], 1, -1) = 1 ([{fd=15, 
revents=POLLIN}])
  [pid 32681] read(15, 
"\1\2\n\0\0\0\0\0\4\0\340\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
  [pid 32681] read(15, 0xa540448, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
  [pid 32681] writev(14, [{"\22\0\30\0\3\0 \2(\0\0\0)\0\0\0 
\1\4\0\22\0\0\0\0\0\0\0\0\0\0\0"..., 136}, {NULL, 0}, {"", 0}], 3) = 136
  [pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
  [pid 32681] read(14, "\34\0o\0\3\0 
\2(\0\0\0\3038\277\4\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096) = 64
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
  [pid 32681] writev(14, [{"\20\1\5\0\n\0 \2_WIN_HINTS\4\0", 20}, {NULL, 0}, 
{"", 0}], 3) = 20
  [pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
  [pid 32681] read(14, 
"\1\0r\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
  [pid 32681] writev(14, [{"\f\1\5\0\3\0 \2\f\0IN \3\0\0X\2\0\0\f\0\5\0\r\0 
\2\f\0\0\0"..., 44}, {NULL, 0}, {"", 0}], 3) = 44
  [pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
  [pid 32681] read(14, 
"\0010u\0\2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096) = 
40
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
  [pid 32681] writev(14, [{"&\1\2\0s\0\0\0", 8}, {NULL, 0}, {"", 0}], 3) = 8
  [pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
  [pid 32681] read(14, "\26\0u\0\3\0 \2\3\0 \2\0\0\0\0\4\0\30\0 
\3X\2\0\0\0\0\0\0\0\0", 4096) = 32
  [pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
  [pid 32681] read(14, 
"\1\1v\0\0\0\0\0s\0\0\0*\1\0\1\352\0\303\2\352\0\303\2\0\0\0\0\0\0\0\0", 4096) 
= 32
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
  [pid 32681] writev(14, [{"\214\2\2\0\17\0 \2+\0\1\0", 12}, {NULL, 0}, {"", 
0}], 3) = 12
  [pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
  [pid 32681] read(14, 
"\1\2x\0\0\0\0\0\4\0\340\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] shmdt(0x747ac000)           = 0
  [pid 32681] shmget(IPC_PRIVATE, 1920000, IPC_CREAT|0777) = 6848525
  [pid 32681] shmat(6848525, 0, 0)        = 0x73dd7000
  [pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
  [pid 32681] writev(14, [{"\214\1\4\0\24\0 \2\r\200h\0\0\3\0\0+\2\1\0", 20}, 
{NULL, 0}, {"", 0}], 3) = 20
  [pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
  [pid 32681] read(14, 
"\1\2z\0\0\0\0\0\4\0\340\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] shmctl(6848525, IPC_64|IPC_RMID, 0) = 0
  [pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
  [pid 32681] writev(14, [{"+\1\1\0", 4}, {NULL, 0}, {"", 0}], 3) = 4
  [pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
  [pid 32681] read(14, 
"\1\2{\0\0\0\0\0\4\0\340\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
  [pid 32681] writev(14, [{"\2\1\4\0\r\0 address@hidden \2+\2\1\0", 20}, {NULL, 
0}, {"", 0}], 3) = 20
  [pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
  [pid 32681] read(14, 
"\1\2}\0\0\0\0\0\4\0\340\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=14, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=14, 
revents=POLLOUT}])
  [pid 32681] writev(14, [{"&\1\2\0\r\0 \2", 8}, {NULL, 0}, {"", 0}], 3) = 8
  [pid 32681] poll([{fd=14, events=POLLIN}], 1, -1) = 1 ([{fd=14, 
revents=POLLIN}])
  [pid 32681] read(14, 
"\1\1~\0\0\0\0\0s\0\0\0\0\0\0\0\352\0\303\2\346\0z\2\0\0\0\0\0\0\0\0", 4096) = 
32
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=15, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=15, 
revents=POLLOUT}])
  [pid 32681] writev(15, [{"b\0\4\0\7\0 \2", 8}, {"MIT-SHM", 7}, {"\0", 1}], 3) 
= 16
  [pid 32681] poll([{fd=15, events=POLLIN}], 1, -1) = 1 ([{fd=15, 
revents=POLLIN}])
  [pid 32681] read(15, 
"\1\0\v\0\0\0\0\0\1\214M\221\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 
32
  [pid 32681] read(15, 0xa540448, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=15, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=15, 
revents=POLLOUT}])
  [pid 32681] writev(15, [{"b\0\10\0\27\0 \2", 8}, {"Generic Event Extension", 
23}, {"\0", 1}], 3) = 32
  [pid 32681] poll([{fd=15, events=POLLIN}], 1, -1) = 1 ([{fd=15, 
revents=POLLIN}])
  [pid 32681] read(15, 
"\1\0\f\0\0\0\0\0\1\212\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 4096) = 32
  [pid 32681] read(15, 0xa540448, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=15, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=15, 
revents=POLLOUT}])
  [pid 32681] writev(15, [{"\212\0\2\0\1\0\0\0", 8}, {NULL, 0}, {"", 0}], 3) = 8
  [pid 32681] poll([{fd=15, events=POLLIN}], 1, -1) = 1 ([{fd=15, 
revents=POLLIN}])
  [pid 32681] read(15, 
"\1\0\r\0\0\0\0\0\1\0\0\0w\26\10\10\0\0\0\0\0\0\0\0\310\316\357\10\n\204\17\10",
 4096) = 32
  [pid 32681] read(15, 0xa540448, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] poll([{fd=15, events=POLLIN|POLLOUT}], 1, -1) = 1 ([{fd=15, 
revents=POLLOUT}])
  [pid 32681] writev(15, [{"\214\3\n\0\r\0 \2\16\0 \2 \3X\2\0\0\0\0 
\3X\2\0\0\0\0\30\2\0\0"..., 40}, {NULL, 0}, {"", 0}], 3) = 40
  [pid 32681] read(15, 0xa540448, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] select(15, [14], NULL, NULL, {0, 0}) = 0 (Timeout)
  [pid 32681] read(14, 0xa535ed8, 4096)   = -1 EAGAIN (Resource temporarily 
unavailable)
  [pid 32681] select(15, [14], NULL, NULL, {0, 0}) = 0 (Timeout)
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 111491325}) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 111667535}) = 0
  [pid 32681] gettimeofday({1321185169, 362674}, NULL) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 111990481}) = 0
  [pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
  [pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
  [pid 32681] gettimeofday({1321185169, 363389}, NULL) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 112738342}) = 0
  [pid 32681] gettimeofday({1321185169, 363745}, NULL) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 113119396}) = 0
  [pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
  [pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
  [pid 32681] ioctl(6, KVM_IRQ_LINE_STATUS, 0xbfd63594) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 113866418}) = 0
  [pid 32681] gettimeofday({1321185169, 364876}, NULL) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 114204170}) = 0
  [pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
  [pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
  [pid 32681] ioctl(6, KVM_IRQ_LINE_STATUS, 0xbfd63594) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 114923815}) = 0
  [pid 32681] gettimeofday({1321185169, 365931}, NULL) = 0
  [pid 32681] clock_gettime(CLOCK_MONOTONIC, {79641, 117299536}) = 0
  [pid 32681] timer_gettime(0, {it_interval={0, 0}, it_value={0, 0}}) = 0
  [pid 32681] timer_settime(0, 0, {it_interval={0, 0}, it_value={0, 250000}}, 
NULL) = 0
  [pid 32681] ioctl(6, KVM_IRQ_LINE_STATUS, 0xbfd63594) = 0

  Host system is Debian stable 32 bit, guest is Windows XP 32 bit, qemu
  version is 0.15.1.

  I start qemu with this commandline:
  qemu -m 1024 -hda image -localtime -monitor tcp:127.0.0.1:10000,server,nowait 
-net nic,model=rtl8139 -net user,hostfwd=tcp:127.0.0.1:4444-:4444 -cpu host 
-daemonize -loadvm somestate
  (Or without -loadvm switch and use loadvm command in the monitor, result is 
the same)

  Now I have 4 states/snapshots in the image, the last one I added is
  995 Mb, but I can't load old one either (170Mb). My computer has 2Gb
  of RAM, I also tried to add 2Gb of swap and that does not help. Tried
  to delete new state with qemu-img snapshot -d, but still can't load
  old states.

  I'm not much help in identifying the reason... Probably this is related to 
rather big states comparing with amount of memory...
  May be related: https://bugzilla.redhat.com/show_bug.cgi?id=586643

To manage notifications about this bug go to:
https://bugs.launchpad.net/qemu/+bug/889827/+subscriptions



reply via email to

[Prev in Thread] Current Thread [Next in Thread]