Hi,
I hope you don't mind me contacting you like this, but I got your emails
from an inconclusive discussion about the following subject. I am
experimenting with virtualization in RHEL 5 Beta 2, and everything
appears to go well until I try to connect to the console of the new
virtual machine. The error includes the message "connection refused".
There is no qemu-dm process running and /var/log/xen/qemu-dm.3598.log
contains the following line:
xc_get_pfn_list returned error 22
I have attached xend.log for today in case it helps.
Incidentally, I seem to have found that there is no way to recover the
new virtual machine after I have installed Fedora unless I save it just
before the final reboot. I then do virsh restore xen-machine1.saved. I
am hoping this is the right thing to do.
Many thanks,
Ciaran Byrne
[2007-02-21 10:07:45 xend 2758] INFO (SrvDaemon:283) Xend Daemon started
[2007-02-21 10:07:45 xend 2758] INFO (SrvDaemon:287) Xend changeset: unavailable .
[2007-02-21 10:07:45 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:212)
XendDomainInfo.recreate({'paused': 0, 'cpu_time': 21785358755L,
'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':
1443380, 'maxmem_kb': -4, 'max_vcpu_id': 1, 'crashed': 0,
'running': 1, 'shutdown': 0, 'online_vcpus': 2, 'blocked':
0})
[2007-02-21 10:07:45 xend.XendDomainInfo 2758] INFO (XendDomainInfo:224) Recreating domain
0, UUID 00000000-0000-0000-0000-000000000000.
[2007-02-21 10:07:45 xend.XendDomainInfo 2758] WARNING (XendDomainInfo:246) No vm path in
store for existing domain 0
[2007-02-21 10:07:45 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:712) 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': '2',
'vcpu_avail': '3', 'memory': '1410', 'on_crash':
'restart', 'maxmem': '1410'}
[2007-02-21 10:07:45 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:747) Storing domain
details: {'cpu/0/availability': 'online', 'memory/target':
'1443840', 'cpu/1/availability': 'online', 'name':
'Domain-0', 'console/limit': '1048576', 'vm':
'/vm/00000000-0000-0000-0000-000000000000', 'domid': '0'}
[2007-02-21 10:07:45 xend 2758] DEBUG (XendDomain:153) number of vcpus to use is 0
[2007-02-21 10:07:45 xend 2758] INFO (SrvServer:116) unix path=/var/lib/xend/xend-socket
[2007-02-21 10:07:45 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:933)
XendDomainInfo.handleShutdownWatch
[2007-02-21 10:23:10 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:265)
XendDomainInfo.restore(['domain', ['domid', '1'], ['uuid',
'f4842de3-c5c9-9d03-7d13-9d0b90fda55c'], ['vcpus', '1'],
['vcpu_avail', '1'], ['cpu_weight', '1.0'],
['memory', '500'], ['shadow_memory', '0'],
['maxmem', '500'], ['features'], ['name',
'xen-machine1'], ['on_poweroff', 'destroy'], ['on_reboot',
'destroy'], ['on_crash', 'destroy'], ['image',
['linux', ['kernel', '/var/lib/xen/vmlinuz.17k0rg'],
['ramdisk', '/var/lib/xen/initrd.img.rF_ALq'], ['args', '
method=http://download.fedora.redhat.com/pub/fedora/linux/core/6/i386/os/ '],
['vnc', '1'], ['vncunused', '1']]], ['device',
['vif', ['backend', '0'], ['script',
'vif-bridge'], ['bridge', 'xenbr0'], ['mac',
'00:16:3e:0c:7d:61']]], ['device', ['vbd', ['backend',
'0'], ['dev', 'xvda:disk'], ['uname',
'phy:/dev/sda4'], ['mode', 'w']]], ['state',
'-b----'], ['shutdown_reason', 'poweroff'], ['cpu_time',
'727.940923805'], ['online_vcpus', '1'], ['up_time',
'10187.961659'], ['start_time', '1171453340.59'],
['store_mfn', '280846'], ['console_mfn', '280845']])
[2007-02-21 10:23:10 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:296) parseConfig:
config is ['domain', ['domid', '1'], ['uuid',
'f4842de3-c5c9-9d03-7d13-9d0b90fda55c'], ['vcpus', '1'],
['vcpu_avail', '1'], ['cpu_weight', '1.0'],
['memory', '500'], ['shadow_memory', '0'],
['maxmem', '500'], ['features'], ['name',
'xen-machine1'], ['on_poweroff', 'destroy'], ['on_reboot',
'destroy'], ['on_crash', 'destroy'], ['image',
['linux', ['kernel', '/var/lib/xen/vmlinuz.17k0rg'],
['ramdisk', '/var/lib/xen/initrd.img.rF_ALq'], ['args', '
method=http://download.fedora.redhat.com/pub/fedora/linux/core/6/i386/os/ '],
['vnc', '1'], ['vncunused', '1']]], ['device',
['vif', ['backend', '0'], ['script',
'vif-bridge'], ['bridge', 'xenbr0'], ['mac',
'00:16:3e:0c:7d:61']]], ['device', ['vbd', ['backend',
'0'], ['dev', 'xvda:disk'], ['uname',
'phy:/dev/sda4'], ['mode', 'w']]], ['state',
'-b----'], ['shutdown_reason', 'poweroff'], ['cpu_time',
'727.940923805'], ['online_vcpus', '1'], ['up_time',
'10187.961659'], ['start_time', '1171453340.59'],
['store_mfn', '280846'], ['console_mfn', '280845']]
[2007-02-21 10:23:10 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:395) parseConfig:
result is {'shadow_memory': 0, 'uuid':
'f4842de3-c5c9-9d03-7d13-9d0b90fda55c', 'on_crash': 'destroy',
'on_reboot': 'destroy', 'localtime': None, 'image':
['linux', ['kernel', '/var/lib/xen/vmlinuz.17k0rg'],
['ramdisk', '/var/lib/xen/initrd.img.rF_ALq'], ['args', '
method=http://download.fedora.redhat.com/pub/fedora/linux/core/6/i386/os/ '],
['vnc', '1'], ['vncunused', '1']], 'on_poweroff':
'destroy', 'bootloader_args': None, 'cpus': None, 'name':
'xen-machine1', 'backend': [], 'vcpus': 1, 'cpu_weight':
1.0, 'features': None, 'vcpu_avail': 1, 'memory': 500,
'device': [('vif', ['vif', ['backend', '0'],
['script', 'vif-bridge'], ['bridge', 'xenbr0'],
['mac', '00:16:3e:0c:7d:61']]), ('vbd', ['vbd',
['backend', '0'], ['dev', 'xvda:disk'], ['uname',
'phy:/dev/sda4'], ['mode', 'w']])], 'bootloader': None,
'cpu': None, 'maxmem': 500}
[2007-02-21 10:23:10 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:1253)
XendDomainInfo.construct: None
[2007-02-21 10:23:10 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:712) Storing VM
details: {'shadow_memory': '0', 'uuid':
'f4842de3-c5c9-9d03-7d13-9d0b90fda55c', 'on_reboot': 'destroy',
'image': "(linux (kernel /var/lib/xen/vmlinuz.17k0rg) (ramdisk
/var/lib/xen/initrd.img.rF_ALq) (args '
method=http://download.fedora.redhat.com/pub/fedora/linux/core/6/i386/os/ ') (vnc 1)
(vncunused 1))", 'on_poweroff': 'destroy', 'name':
'xen-machine1', 'xend/restart_count': '0', 'vcpus':
'1', 'vcpu_avail': '1', 'memory': '500',
'on_crash': 'destroy', 'maxmem': '500'}
[2007-02-21 10:23:10 xend 2758] DEBUG (DevController:110) DevController: writing
{'backend-id': '0', 'mac': '00:16:3e:0c:7d:61',
'handle': '0', 'state': '1', 'backend':
'/local/domain/0/backend/vif/1/0'} to /local/domain/1/device/vif/0.
[2007-02-21 10:23:10 xend 2758] DEBUG (DevController:112) DevController: writing
{'bridge': 'xenbr0', 'domain': 'xen-machine1',
'handle': '0', 'script': '/etc/xen/scripts/vif-bridge',
'state': '1', 'frontend': '/local/domain/1/device/vif/0',
'mac': '00:16:3e:0c:7d:61', 'online': '1',
'frontend-id': '1'} to /local/domain/0/backend/vif/1/0.
[2007-02-21 10:23:11 xend 2758] DEBUG (blkif:24) exception looking up device number for
xvda: [Errno 2] No such file or directory: '/dev/xvda'
[2007-02-21 10:23:11 xend 2758] DEBUG (DevController:110) DevController: writing
{'backend-id': '0', 'virtual-device': '51712',
'device-type': 'disk', 'state': '1', 'backend':
'/local/domain/0/backend/vbd/1/51712'} to /local/domain/1/device/vbd/51712.
[2007-02-21 10:23:11 xend 2758] DEBUG (DevController:112) DevController: writing
{'domain': 'xen-machine1', 'frontend':
'/local/domain/1/device/vbd/51712', 'dev': 'xvda',
'state': '1', 'params': '/dev/sda4', 'mode':
'w', 'online': '1', 'frontend-id': '1',
'type': 'phy'} to /local/domain/0/backend/vbd/1/51712.
[2007-02-21 10:23:11 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:747) Storing domain
details: {'console/port': '2', 'name': 'xen-machine1',
'console/limit': '1048576', 'vm':
'/vm/f4842de3-c5c9-9d03-7d13-9d0b90fda55c', 'domid': '1',
'cpu/0/availability': 'online', 'memory/target': '512000',
'store/port': '1'}
[2007-02-21 10:23:11 xend 2758] DEBUG (balloon:133) Balloon: 97588 KiB free; 0 to scrub;
need 520192; retries: 20.
[2007-02-21 10:23:11 xend 2758] DEBUG (balloon:148) Balloon: setting dom0 target to 996
MiB.
[2007-02-21 10:23:11 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:1014) Setting memory
target of domain Domain-0 (0) to 996 MiB.
[2007-02-21 10:23:12 xend 2758] DEBUG (balloon:127) Balloon: 521064 KiB free; need 520192;
done.
[2007-02-21 10:23:12 xend 2758] DEBUG (XendCheckpoint:155) [xc_restore]:
/usr/lib/xen/bin/xc_restore 21 1 130048 1 2
[2007-02-21 10:23:12 xend 2758] ERROR (XendCheckpoint:236) xc_linux_restore start: max_pfn
= 1fc00
[2007-02-21 10:23:12 xend 2758] ERROR (XendCheckpoint:236) Increased domain reservation by
7f000 KB
[2007-02-21 10:23:12 xend 2758] ERROR (XendCheckpoint:236) Reloading memory pages: 0%
[2007-02-21 10:23:28 xend 2758] ERROR (XendCheckpoint:236) Received all pages (0 races)
[2007-02-21 10:23:28 xend 2758] ERROR (XendCheckpoint:236) 100%
[2007-02-21 10:23:28 xend 2758] ERROR (XendCheckpoint:236) Memory reloaded.
[2007-02-21 10:23:28 xend 2758] ERROR (XendCheckpoint:236) Decreased reservation by 2082
pages
[2007-02-21 10:23:28 xend 2758] ERROR (XendCheckpoint:236) Domain ready to be built.
[2007-02-21 10:23:28 xend 2758] ERROR (XendCheckpoint:236) Restore exit with rc=0
[2007-02-21 10:23:28 xend 2758] DEBUG (XendCheckpoint:209) store-mfn 65550
[2007-02-21 10:23:28 xend 2758] DEBUG (XendCheckpoint:209) console-mfn 65549
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:143) Waiting for devices vif.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:149) Waiting for 0.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:464) hotplugStatusCallback
/local/domain/0/backend/vif/1/0/hotplug-status.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:478) hotplugStatusCallback 1.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:143) Waiting for devices usb.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:143) Waiting for devices vbd.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:149) Waiting for 51712.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:464) hotplugStatusCallback
/local/domain/0/backend/vbd/1/51712/hotplug-status.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:478) hotplugStatusCallback 1.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:143) Waiting for devices irq.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:143) Waiting for devices pci.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:143) Waiting for devices ioports.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:143) Waiting for devices tap.
[2007-02-21 10:23:28 xend 2758] DEBUG (DevController:143) Waiting for devices vtpm.
[2007-02-21 10:23:28 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:672)
XendDomainInfo.completeRestore
[2007-02-21 10:23:28 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:747) Storing domain
details: {'console/ring-ref': '65549', 'console/port':
'2', 'name': 'xen-machine1', 'console/limit':
'1048576', 'vm': '/vm/f4842de3-c5c9-9d03-7d13-9d0b90fda55c',
'domid': '1', 'cpu/0/availability': 'online',
'memory/target': '512000', 'store/ring-ref': '65550',
'store/port': '1'}
[2007-02-21 10:23:28 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:682)
XendDomainInfo.completeRestore done
[2007-02-21 10:23:28 xend.XendDomainInfo 2758] DEBUG (XendDomainInfo:933)
XendDomainInfo.handleShutdownWatch