Attachment 155048 Details for Bug 240661 – /var/log/xen/xend.log

/var/log/xen/xend.log

xend.log (text/plain), 15.98 KB, created by Robert Scheck on 2007-05-19 16:34:24 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Robert Scheck

Created: 2007-05-19 16:34:24 UTC

Size: 15.98 KB

patch

obsolete

>[2007-05-19 15:30:30 xend 2365] INFO (SrvDaemon:283) Xend Daemon started
>[2007-05-19 15:30:30 xend 2365] INFO (SrvDaemon:287) Xend changeset: unavailable .
>[2007-05-19 15:30:30 xend.XendDomainInfo 2365] DEBUG (XendDomainInfo:212) XendDomainInfo.recreate({'paused': 0, 'cpu_time': 54196036463L, 'ssidref': 0, 'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'shutdown_reason': 0, 'dying': 0, 'dom': 0, 'mem_kb': 946748, 'maxmem_kb': -4, 'max_vcpu_id': 0, 'crashed': 0, 'running': 1, 'shutdown': 0, 'online_vcpus': 1, 'blocked': 0})
>[2007-05-19 15:30:30 xend.XendDomainInfo 2365] INFO (XendDomainInfo:224) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000.
>[2007-05-19 15:30:30 xend.XendDomainInfo 2365] WARNING (XendDomainInfo:246) No vm path in store for existing domain 0
>[2007-05-19 15:30:30 xend.XendDomainInfo 2365] DEBUG (XendDomainInfo:715) Storing VM details: {'shadow_memory': '0', 'uuid': '00000000-0000-0000-0000-000000000000', 'on_reboot': 'restart', 'on_poweroff': 'destroy', 'name': 'Domain-0', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '925', 'on_crash': 'restart', 'maxmem': '925'}
>[2007-05-19 15:30:30 xend.XendDomainInfo 2365] DEBUG (XendDomainInfo:750) Storing domain details: {'cpu/0/availability': 'online', 'memory/target': '947200', 'name': 'Domain-0', 'console/limit': '1048576', 'vm': '/vm/00000000-0000-0000-0000-000000000000', 'domid': '0'}
>[2007-05-19 15:30:30 xend 2365] DEBUG (XendDomain:153) number of vcpus to use is 0
>[2007-05-19 15:30:30 xend 2365] INFO (SrvServer:116) unix path=/var/lib/xend/xend-socket
>[2007-05-19 15:30:30 xend.XendDomainInfo 2365] DEBUG (XendDomainInfo:940) XendDomainInfo.handleShutdownWatch
>[2007-05-19 17:44:25 xend 2330] INFO (SrvDaemon:283) Xend Daemon started
>[2007-05-19 17:44:25 xend 2330] INFO (SrvDaemon:287) Xend changeset: unavailable .
>[2007-05-19 17:44:26 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:212) XendDomainInfo.recreate({'paused': 0, 'cpu_time': 44023614129L, 'ssidref': 0, 'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'shutdown_reason': 0, 'dying': 0, 'dom': 0, 'mem_kb': 946748, 'maxmem_kb': -4, 'max_vcpu_id': 0, 'crashed': 0, 'running': 1, 'shutdown': 0, 'online_vcpus': 1, 'blocked': 0})
>[2007-05-19 17:44:26 xend.XendDomainInfo 2330] INFO (XendDomainInfo:224) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000.
>[2007-05-19 17:44:26 xend.XendDomainInfo 2330] WARNING (XendDomainInfo:246) No vm path in store for existing domain 0
>[2007-05-19 17:44:26 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:715) Storing VM details: {'shadow_memory': '0', 'uuid': '00000000-0000-0000-0000-000000000000', 'on_reboot': 'restart', 'on_poweroff': 'destroy', 'name': 'Domain-0', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '925', 'on_crash': 'restart', 'maxmem': '925'}
>[2007-05-19 17:44:26 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:750) Storing domain details: {'cpu/0/availability': 'online', 'memory/target': '947200', 'name': 'Domain-0', 'console/limit': '1048576', 'vm': '/vm/00000000-0000-0000-0000-000000000000', 'domid': '0'}
>[2007-05-19 17:44:26 xend 2330] DEBUG (XendDomain:153) number of vcpus to use is 0
>[2007-05-19 17:44:26 xend 2330] INFO (SrvServer:116) unix path=/var/lib/xend/xend-socket
>[2007-05-19 17:44:26 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:940) XendDomainInfo.handleShutdownWatch
>[2007-05-19 17:52:35 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:190) XendDomainInfo.create(['vm', ['name', 'xen'], ['memory', '450'], ['maxmem', '450'], ['vcpus', '1'], ['uuid', 'e978c0eb-454f-fcce-6f29-6be0744d6bb0'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/vmlinuz.LqqkXL'], ['ramdisk', '/var/lib/xen/initrd.img.k03qaS'], ['args', ' method=ftp://192.168.0.23/pub/  ']]], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/home/robert/xen.img'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:77:12:ef'], ['bridge', 'xenbr0'], ['script', '/etc/xen/scripts/vif-bridge']]], ['device', ['vkbd']], ['device', ['vfb', ['type', 'vnc'], ['vncunused', '1']]]])
>[2007-05-19 17:52:35 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:296) parseConfig: config is ['vm', ['name', 'xen'], ['memory', '450'], ['maxmem', '450'], ['vcpus', '1'], ['uuid', 'e978c0eb-454f-fcce-6f29-6be0744d6bb0'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/vmlinuz.LqqkXL'], ['ramdisk', '/var/lib/xen/initrd.img.k03qaS'], ['args', ' method=ftp://192.168.0.23/pub/  ']]], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/home/robert/xen.img'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:77:12:ef'], ['bridge', 'xenbr0'], ['script', '/etc/xen/scripts/vif-bridge']]], ['device', ['vkbd']], ['device', ['vfb', ['type', 'vnc'], ['vncunused', '1']]]]
>[2007-05-19 17:52:35 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:397) parseConfig: result is {'shadow_memory': None, 'start_time': None, 'uuid': 'e978c0eb-454f-fcce-6f29-6be0744d6bb0', 'on_crash': 'destroy', 'on_reboot': 'destroy', 'localtime': None, 'image': ['linux', ['kernel', '/var/lib/xen/vmlinuz.LqqkXL'], ['ramdisk', '/var/lib/xen/initrd.img.k03qaS'], ['args', ' method=ftp://192.168.0.23/pub/  ']], 'on_poweroff': 'destroy', 'bootloader_args': None, 'cpus': None, 'name': 'xen', 'backend': [], 'vcpus': 1, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory': 450, 'device': [('tap', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/home/robert/xen.img'], ['mode', 'w']]), ('vif', ['vif', ['mac', '00:16:3e:77:12:ef'], ['bridge', 'xenbr0'], ['script', '/etc/xen/scripts/vif-bridge']]), ('vkbd', ['vkbd']), ('vfb', ['vfb', ['type', 'vnc'], ['vncunused', '1']])], 'bootloader': None, 'cpu': None, 'maxmem': 450}
>[2007-05-19 17:52:35 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:1264) XendDomainInfo.construct: None
>[2007-05-19 17:52:35 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:1296) XendDomainInfo.initDomain: 1 1.0
>[2007-05-19 17:52:35 xend 2330] DEBUG (balloon:133) Balloon: 64480 KiB free; 0 to scrub; need 460800; retries: 20.
>[2007-05-19 17:52:35 xend 2330] DEBUG (balloon:148) Balloon: setting dom0 target to 537 MiB.
>[2007-05-19 17:52:35 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:1021) Setting memory target of domain Domain-0 (0) to 537 MiB.
>[2007-05-19 17:52:38 xend 2330] DEBUG (balloon:127) Balloon: 461340 KiB free; need 460800; done.
>[2007-05-19 17:52:38 xend 2330] INFO (image:136) buildDomain os=linux dom=1 vcpus=1
>[2007-05-19 17:52:38 xend 2330] DEBUG (image:199) dom            = 1
>[2007-05-19 17:52:38 xend 2330] DEBUG (image:200) image          = /var/lib/xen/vmlinuz.LqqkXL
>[2007-05-19 17:52:38 xend 2330] DEBUG (image:201) store_evtchn   = 1
>[2007-05-19 17:52:38 xend 2330] DEBUG (image:202) console_evtchn = 2
>[2007-05-19 17:52:38 xend 2330] DEBUG (image:203) cmdline        =   method=ftp://192.168.0.23/pub/  
>[2007-05-19 17:52:38 xend 2330] DEBUG (image:204) ramdisk        = /var/lib/xen/initrd.img.k03qaS
>[2007-05-19 17:52:38 xend 2330] DEBUG (image:205) vcpus          = 1
>[2007-05-19 17:52:38 xend 2330] DEBUG (image:206) features       = 
>[2007-05-19 17:52:38 xend 2330] DEBUG (blkif:24) exception looking up device number for xvda: [Errno 2] No such file or directory: '/dev/xvda'
>[2007-05-19 17:52:38 xend 2330] DEBUG (DevController:110) DevController: writing {'backend-id': '0', 'virtual-device': '51712', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/tap/1/51712'} to /local/domain/1/device/vbd/51712.
>[2007-05-19 17:52:38 xend 2330] DEBUG (DevController:112) DevController: writing {'domain': 'xen', 'frontend': '/local/domain/1/device/vbd/51712', 'dev': 'xvda', 'state': '1', 'params': 'aio:/home/robert/xen.img', 'mode': 'w', 'online': '1', 'frontend-id': '1', 'type': 'tap'} to /local/domain/0/backend/tap/1/51712.
>[2007-05-19 17:52:38 xend 2330] DEBUG (DevController:110) DevController: writing {'backend-id': '0', 'mac': '00:16:3e:77:12:ef', 'handle': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/1/0'} to /local/domain/1/device/vif/0.
>[2007-05-19 17:52:38 xend 2330] DEBUG (DevController:112) DevController: writing {'bridge': 'xenbr0', 'domain': 'xen', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/1/device/vif/0', 'mac': '00:16:3e:77:12:ef', 'online': '1', 'frontend-id': '1'} to /local/domain/0/backend/vif/1/0.
>[2007-05-19 17:52:38 xend 2330] DEBUG (DevController:110) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vkbd/1/0'} to /local/domain/1/device/vkbd/0.
>[2007-05-19 17:52:38 xend 2330] DEBUG (DevController:112) DevController: writing {'frontend-id': '1', 'domain': 'xen', 'frontend': '/local/domain/1/device/vkbd/0', 'state': '1', 'online': '1'} to /local/domain/0/backend/vkbd/1/0.
>[2007-05-19 17:52:38 xend 2330] DEBUG (DevController:110) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/1/0'} to /local/domain/1/device/vfb/0.
>[2007-05-19 17:52:38 xend 2330] DEBUG (DevController:112) DevController: writing {'vncunused': '1', 'domain': 'xen', 'frontend': '/local/domain/1/device/vfb/0', 'online': '1', 'frontend-id': '1', 'type': 'vnc', 'state': '1'} to /local/domain/0/backend/vfb/1/0.
>[2007-05-19 17:52:38 xend 2330] DEBUG (vfbif:92) No VNC passwd configured for vfb access
>[2007-05-19 17:52:38 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:715) Storing VM details: {'shadow_memory': '0', 'uuid': 'e978c0eb-454f-fcce-6f29-6be0744d6bb0', 'on_reboot': 'destroy', 'start_time': '1179589958.76', 'on_poweroff': 'destroy', 'name': 'xen', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '450', 'on_crash': 'destroy', 'image': "(linux (kernel /var/lib/xen/vmlinuz.LqqkXL) (ramdisk /var/lib/xen/initrd.img.k03qaS) (args ' method=ftp://192.168.0.23/pub/  '))", 'maxmem': '450'}
>[2007-05-19 17:52:38 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:750) Storing domain details: {'console/ring-ref': '66339', 'console/port': '2', 'name': 'xen', 'console/limit': '1048576', 'vm': '/vm/e978c0eb-454f-fcce-6f29-6be0744d6bb0', 'domid': '1', 'cpu/0/availability': 'online', 'memory/target': '460800', 'store/ring-ref': '256240', 'store/port': '1'}
>[2007-05-19 17:52:38 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:940) XendDomainInfo.handleShutdownWatch
>[2007-05-19 17:52:38 xend 2330] DEBUG (DevController:143) Waiting for devices vif.
>[2007-05-19 17:52:39 xend 2330] DEBUG (DevController:149) Waiting for 0.
>[2007-05-19 17:52:39 xend 2330] DEBUG (DevController:471) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:471) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:485) hotplugStatusCallback 1.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:143) Waiting for devices usb.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:143) Waiting for devices vbd.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:143) Waiting for devices irq.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:143) Waiting for devices vkbd.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:149) Waiting for 0.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:471) hotplugStatusCallback /local/domain/0/backend/vkbd/1/0/hotplug-status.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:485) hotplugStatusCallback 1.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:143) Waiting for devices vfb.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:149) Waiting for 0.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:471) hotplugStatusCallback /local/domain/0/backend/vfb/1/0/hotplug-status.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:485) hotplugStatusCallback 1.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:143) Waiting for devices pci.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:143) Waiting for devices ioports.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:143) Waiting for devices tap.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:149) Waiting for 51712.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:471) hotplugStatusCallback /local/domain/0/backend/tap/1/51712/hotplug-status.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:485) hotplugStatusCallback 1.
>[2007-05-19 17:52:40 xend 2330] DEBUG (DevController:143) Waiting for devices vtpm.
>[2007-05-19 17:52:40 xend 2330] INFO (XendDomain:370) Domain xen (1) unpaused.
>[2007-05-19 18:10:59 xend.XendDomainInfo 2330] INFO (XendDomainInfo:899) Domain has shutdown: name=xen id=1 reason=reboot.
>[2007-05-19 18:10:59 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:1463) XendDomainInfo.destroy: domid=1
>[2007-05-19 18:10:59 xend.XendDomainInfo 2330] DEBUG (XendDomainInfo:1471) XendDomainInfo.destroyDomain(1)
>[2007-05-19 18:11:34 xend 2330] ERROR (XendDomain:268) Restore failed
>Traceback (most recent call last):
>  File "/usr/lib/python2.4/site-packages/xen/xend/XendDomain.py", line 263, in domain_restore_fd
>    return XendCheckpoint.restore(self, fd)
>  File "/usr/lib/python2.4/site-packages/xen/xend/XendCheckpoint.py", line 118, in restore
>    raise XendError("not a valid guest state file: found '%s'" %
>XendError: not a valid guest state file: found 'ëHÐ¼°¸ØÀ'
>[2007-05-19 18:12:45 xend 2330] ERROR (XendDomain:268) Restore failed
>Traceback (most recent call last):
>  File "/usr/lib/python2.4/site-packages/xen/xend/XendDomain.py", line 263, in domain_restore_fd
>    return XendCheckpoint.restore(self, fd)
>  File "/usr/lib/python2.4/site-packages/xen/xend/XendCheckpoint.py", line 118, in restore
>    raise XendError("not a valid guest state file: found '%s'" %
>XendError: not a valid guest state file: found 'ëHÐ¼°¸ØÀ'
>[2007-05-19 18:12:55 xend 2329] INFO (SrvDaemon:190) Xend stopped due to signal 15.
>[2007-05-19 18:12:58 xend 5770] INFO (SrvDaemon:283) Xend Daemon started
>[2007-05-19 18:12:58 xend 5770] INFO (SrvDaemon:287) Xend changeset: unavailable .
>[2007-05-19 18:12:58 xend.XendDomainInfo 5770] DEBUG (XendDomainInfo:212) XendDomainInfo.recreate({'paused': 0, 'cpu_time': 360288991130L, 'ssidref': 0, 'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'shutdown_reason': 0, 'dying': 0, 'dom': 0, 'mem_kb': 550076, 'maxmem_kb': -4, 'max_vcpu_id': 0, 'crashed': 0, 'running': 1, 'shutdown': 0, 'online_vcpus': 1, 'blocked': 0})
>[2007-05-19 18:12:58 xend.XendDomainInfo 5770] INFO (XendDomainInfo:224) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000.
>[2007-05-19 18:12:58 xend 5770] DEBUG (XendDomain:153) number of vcpus to use is 0
>[2007-05-19 18:12:58 xend 5770] INFO (SrvServer:116) unix path=/var/lib/xend/xend-socket
>[2007-05-19 18:12:58 xend.XendDomainInfo 5770] DEBUG (XendDomainInfo:940) XendDomainInfo.handleShutdownWatch
>[2007-05-19 18:13:54 xend 5770] ERROR (XendDomain:268) Restore failed
>Traceback (most recent call last):
>  File "/usr/lib/python2.4/site-packages/xen/xend/XendDomain.py", line 263, in domain_restore_fd
>    return XendCheckpoint.restore(self, fd)
>  File "/usr/lib/python2.4/site-packages/xen/xend/XendCheckpoint.py", line 118, in restore
>    raise XendError("not a valid guest state file: found '%s'" %
>XendError: not a valid guest state file: found 'ëHÐ¼°¸ØÀ'
>[2007-05-19 18:14:56 xend 5770] ERROR (XendDomain:268) Restore failed
>Traceback (most recent call last):
>  File "/usr/lib/python2.4/site-packages/xen/xend/XendDomain.py", line 263, in domain_restore_fd
>    return XendCheckpoint.restore(self, fd)
>  File "/usr/lib/python2.4/site-packages/xen/xend/XendCheckpoint.py", line 118, in restore
>    raise XendError("not a valid guest state file: found '%s'" %
>XendError: not a valid guest state file: found 'ëHÐ¼°¸ØÀ'
>[2007-05-19 18:16:03 xend 5770] ERROR (XendDomain:268) Restore failed
>Traceback (most recent call last):
>  File "/usr/lib/python2.4/site-packages/xen/xend/XendDomain.py", line 263, in domain_restore_fd
>    return XendCheckpoint.restore(self, fd)
>  File "/usr/lib/python2.4/site-packages/xen/xend/XendCheckpoint.py", line 118, in restore
>    raise XendError("not a valid guest state file: found '%s'" %
>XendError: not a valid guest state file: found 'ëHÐ¼°¸ØÀ'

Actions: View

Attachments on bug 240661: 155048