Bug#600992: further logs
Hello there, with versions above 2.6.32-23 of
xen-linux-system-2.6.32-5-xen-amd64 (we tried *-25 and *-26) we found
the following bug:
We cannot suspend any domU machine so migration and save are broken,
because they both use the suspension. On /var/log/xen/xend.log I have:
[2010-10-21 09:42:54 1847] INFO (XendDomain:1206) Domain
vpn2.localadvert.it (10) unpaused.
[2010-10-21 09:44:43 1847] DEBUG (XendCheckpoint:124) [xc_save]:
/usr/lib/xen-4.0/bin/xc_save 5 4 0 0 1
[2010-10-21 09:44:43 1847] INFO (XendCheckpoint:423) xc_save: failed
to get the suspend evtchn port
[2010-10-21 09:44:43 1847] INFO (XendCheckpoint:423)
[2010-10-21 09:44:43 1847] INFO (XendCheckpoint:423) Had 0 unexplained
entries in p2m table
[2010-10-21 09:44:55 1847] INFO (XendCheckpoint:423) Saving memory
pages: iter 1 0%^H^H^H^H 5%^H^H^H^H 10%^H^H^H^H 15%^H^H^H^H
20%^H^H^H^H 25%^H^H^H^H 30%^H^H^H^H 35%^H^H^H^H 40%^H^H^H^H 45%^H^H^H^H
50%^H^H^H^H 55%^H^H^H^H 60%^H^H^H^H 65%^H^H^H^H 70%^H^H^H^H 75%^H^H^H^H
80%^H^H^H^H 85%^H^H^H^H 90%^H^H^H^H 95%^M 1: sent 258645, skipped 2539,
delta 12610ms, dom0 36%, target 1%, sent 672Mb/s, dirtied 6Mb/s 2659 pages
[2010-10-21 09:44:55 1847] INFO (XendCheckpoint:423) Saving memory
pages: iter 2 0%^H^H^H^H 25%^H^H^H^H 93%^M 2: sent 2638, skipped 21,
delta 131ms, dom0 32%, target 0%, sent 659Mb/s, dirtied 28Mb/s 112 pages
[2010-10-21 09:44:55 1847] INFO (XendCheckpoint:423) Saving memory
pages: iter 3 0%^M 3: sent 112, skipped 0, delta 3ms, dom0 100%,
target 0%, sent 1223Mb/s, dirtied 0Mb/s 0 pages
[2010-10-21 09:44:55 1847] INFO (XendCheckpoint:423) Saving memory
pages: iter 4 0%^M 4: sent 0, skipped 0, Start last iteration
[2010-10-21 09:44:55 1847] DEBUG (XendCheckpoint:394) suspend
[2010-10-21 09:44:55 1847] DEBUG (XendCheckpoint:127) In
saveInputHandler suspend
[2010-10-21 09:44:55 1847] DEBUG (XendCheckpoint:129) Suspending 4 ...
[2010-10-21 09:44:55 1847] DEBUG (XendDomainInfo:519)
XendDomainInfo.shutdown(suspend)
[2010-10-21 09:44:55 1847] DEBUG (XendDomainInfo:1891)
XendDomainInfo.handleShutdownWatch
[2010-10-21 09:44:55 1847] DEBUG (XendDomainInfo:1891)
XendDomainInfo.handleShutdownWatch
and the suspend hangs there, the correct output should be:
[2010-10-22 08:58:13 1869] DEBUG (XendCheckpoint:124) [xc_save]:
/usr/lib/xen-4.0/bin/xc_save 4 1 0 0 1
[2010-10-22 08:58:13 1869] INFO (XendCheckpoint:423) xc_save: failed
to get the suspend evtchn port
[2010-10-22 08:58:13 1869] INFO (XendCheckpoint:423)
[2010-10-22 08:58:13 1869] INFO (XendCheckpoint:423) Had 0 unexplained
entries in p2m table
[2010-10-22 08:58:16 1869] INFO (XendCheckpoint:423) Saving memory
pages: iter 1 0%^H^H^H^H 6%^H^H^H^H 12%^H^H^H^H 18%^H^H^H^H
24%^H^H^H^H 29%^H^H^H^H 35%^H^H^H^H 40%^H^H^H^H 46%^H^H^H^H 52%^H^H^H^H
57%^H^H^H^H 63%^H^H^H^H 68%^H^H^H^H 74%^H^H^H^H 79%^H^H^H^H 85%^H^H^H^H
90%^H^H^H^H 95%^M 1: sent 64474, skipped 102, delta 3231ms, dom0 37%,
target 0%, sent 653Mb/s, dirtied 1Mb/s 152 pages
[2010-10-22 08:58:16 1869] INFO (XendCheckpoint:423) Saving memory
pages: iter 2 0%^M 2: sent 152, skipped 0, delta 5ms, dom0 60%, target
0%, sent 996Mb/s, dirtied 0Mb/s 0 pages
[2010-10-22 08:58:16 1869] INFO (XendCheckpoint:423) Saving memory
pages: iter 3 0%^M 3: sent 0, skipped 0, Start last iteration
[2010-10-22 08:58:16 1869] DEBUG (XendCheckpoint:394) suspend
[2010-10-22 08:58:16 1869] DEBUG (XendCheckpoint:127) In
saveInputHandler suspend
[2010-10-22 08:58:16 1869] DEBUG (XendCheckpoint:129) Suspending 1 ...
[2010-10-22 08:58:16 1869] DEBUG (XendDomainInfo:519)
XendDomainInfo.shutdown(suspend)
[2010-10-22 08:58:16 1869] DEBUG (XendDomainInfo:1891)
XendDomainInfo.handleShutdownWatch
[2010-10-22 08:58:16 1869] DEBUG (XendDomainInfo:1891)
XendDomainInfo.handleShutdownWatch
[2010-10-22 08:58:16 1869] INFO (XendDomainInfo:2088) Domain has
shutdown: name=migrating-ldap.localadvert.it id=1 reason=suspend.
[2010-10-22 08:58:16 1869] INFO (XendCheckpoint:135) Domain 1 suspended.
[2010-10-22 08:58:16 1869] INFO (XendCheckpoint:423) SUSPEND shinfo
0002e58d
[2010-10-22 08:58:16 1869] INFO (XendCheckpoint:423) delta 34ms, dom0
76%, target 5%, sent 0Mb/s, dirtied 137Mb/s 143 pages
[2010-10-22 08:58:16 1869] DEBUG (XendCheckpoint:144) Written done
[2010-10-22 08:58:16 1869] INFO (XendCheckpoint:423) Saving memory
pages: iter 4 0%^M 4: sent 143, skipped 0, delta 2ms, dom0 100%,
target 0%, sent 2342Mb/s, dirtied 2342Mb/s 143 pages
[2010-10-22 08:58:16 1869] INFO (XendCheckpoint:423) Total pages sent=
64769 (0.99x)
[2010-10-22 08:58:16 1869] INFO (XendCheckpoint:423) (of which 0 were
fixups)
[2010-10-22 08:58:16 1869] INFO (XendCheckpoint:423) All memory is saved
[2010-10-22 08:58:16 1869] INFO (XendCheckpoint:423) Save exit rc=0
reverting to 2.6.32-23 made the suspension work again
cheers
Reply to: