[2005-12-21 01:30:11 xend] INFO (SrvDaemon:268) Xend Daemon started
[2005-12-21 01:30:11 xend] INFO (SrvDaemon:272) Xend changeset: Sun Dec  4 17:24:24 2005 +0100 8240:64cd054aa143.
[2005-12-21 01:30:11 xend.XendDomainInfo] DEBUG (XendDomainInfo:198) XendDomainInfo.recreate({'paused': 0, 'cpu_time': 16092068622L, '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': 471788, 'maxmem_kb': -4, 'max_vcpu_id': 1, 'crashed': 0, 'running': 1, 'shutdown': 0, 'online_vcpus': 1, 'blocked': 0})
[2005-12-21 01:30:11 xend.XendDomainInfo] INFO (XendDomainInfo:210) Recreating domain 0, UUID 00000000-00000000-00000000-00000000.
[2005-12-21 01:30:11 xend.XendDomainInfo] WARNING (XendDomainInfo:232) No vm path in store for existing domain 0
[2005-12-21 01:30:11 xend.XendDomainInfo] DEBUG (XendDomainInfo:655) Storing VM details: {'ssidref': '0', 'uuid': '00000000-00000000-00000000-00000000', 'on_reboot': 'restart', 'on_poweroff': 'destroy', 'name': 'Domain-0', 'vcpus': '2', 'vcpu_avail': '3', 'memory': '461', 'on_crash': 'restart', 'maxmem': '461'}
[2005-12-21 01:30:11 xend.XendDomainInfo] DEBUG (XendDomainInfo:680) Storing domain details: {'cpu/0/availability': 'online', 'memory/target': '472064', 'cpu/1/availability': 'online', 'name': 'Domain-0', 'console/limit': '1048576', 'vm': '/vm/00000000-00000000-00000000-00000000', 'domid': '0'}
[2005-12-21 01:30:11 xend] DEBUG (XendDomain:151) number of vcpus to use is 0
[2005-12-21 01:30:11 xend] INFO (SrvServer:112) unix path=/var/lib/xend/xend-socket
[2005-12-21 01:30:16 xend.XendDomainInfo] DEBUG (XendDomainInfo:176) XendDomainInfo.create(['vm', ['name', 'vm01'], ['memory', '96'], ['image', ['linux', ['kernel', '/boot/vmlinuz-2.6.12-xenU'], ['ip', ':1.2.3.4::::eth0:dhcp'], ['root', '/dev/hda1 ro'], ['vcpus', '1'], ['vcpus', '1'], ['boot', 'c'], ['nics', '1']]], ['device', ['vbd', ['uname', 'file:/opt/xen/domains/vm01/diskimage'], ['dev', 'hda1'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'file:/opt/xen/domains/vm01/swapimage'], ['dev', 'hda2'], ['mode', 'w']]], ['device', ['vif']]])
[2005-12-21 01:30:16 xend.XendDomainInfo] DEBUG (XendDomainInfo:281) parseConfig: config is ['vm', ['name', 'vm01'], ['memory', '96'], ['image', ['linux', ['kernel', '/boot/vmlinuz-2.6.12-xenU'], ['ip', ':1.2.3.4::::eth0:dhcp'], ['root', '/dev/hda1 ro'], ['vcpus', '1'], ['vcpus', '1'], ['boot', 'c'], ['nics', '1']]], ['device', ['vbd', ['uname', 'file:/opt/xen/domains/vm01/diskimage'], ['dev', 'hda1'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'file:/opt/xen/domains/vm01/swapimage'], ['dev', 'hda2'], ['mode', 'w']]], ['device', ['vif']]]
[2005-12-21 01:30:16 xend.XendDomainInfo] DEBUG (XendDomainInfo:373) parseConfig: result is {'ssidref': None, 'uuid': None, 'on_crash': None, 'on_reboot': None, 'image': ['linux', ['kernel', '/boot/vmlinuz-2.6.12-xenU'], ['ip', ':1.2.3.4::::eth0:dhcp'], ['root', '/dev/hda1 ro'], ['vcpus', '1'], ['vcpus', '1'], ['boot', 'c'], ['nics', '1']], 'on_poweroff': None, 'cpus': None, 'name': 'vm01', 'backend': [], 'vcpus': 1, 'cpu_weight': None, 'vcpu_avail': None, 'memory': 96, 'device': [('vbd', ['vbd', ['uname', 'file:/opt/xen/domains/vm01/diskimage'], ['dev', 'hda1'], ['mode', 'w']]), ('vbd', ['vbd', ['uname', 'file:/opt/xen/domains/vm01/swapimage'], ['dev', 'hda2'], ['mode', 'w']]), ('vif', ['vif'])], 'bootloader': None, 'cpu': None, 'maxmem': None}
[2005-12-21 01:30:16 xend.XendDomainInfo] DEBUG (XendDomainInfo:1123) XendDomainInfo.construct: None 0
[2005-12-21 01:30:16 xend.XendDomainInfo] DEBUG (XendDomainInfo:1155) XendDomainInfo.initDomain: 1 1.0
[2005-12-21 01:30:16 xend] DEBUG (balloon:47) Balloon: free 31; need 97.
[2005-12-21 01:30:16 xend.XendDomainInfo] DEBUG (XendDomainInfo:903) Setting memory target of domain Domain-0 (0) to 395 MiB.
[2005-12-21 01:30:16 xend] INFO (image:132) buildDomain os=linux dom=1 vcpus=1
[2005-12-21 01:30:16 xend] DEBUG (image:170) dom            = 1
[2005-12-21 01:30:16 xend] DEBUG (image:171) image          = /boot/vmlinuz-2.6.12-xenU
[2005-12-21 01:30:16 xend] DEBUG (image:172) store_evtchn   = 1
[2005-12-21 01:30:16 xend] DEBUG (image:173) console_evtchn = 2
[2005-12-21 01:30:16 xend] DEBUG (image:174) cmdline        =  ip=:1.2.3.4::::eth0:dhcp root=/dev/hda1 ro
[2005-12-21 01:30:16 xend] DEBUG (image:175) ramdisk        = 
[2005-12-21 01:30:16 xend] DEBUG (image:176) vcpus          = 1
[2005-12-21 01:30:17 xend] DEBUG (DevController:104) DevController: writing {'virtual-device': '769', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vbd/1/769'} to /local/domain/1/device/vbd/769.
[2005-12-21 01:30:17 xend] DEBUG (DevController:106) DevController: writing {'domain': 'vm01', 'frontend': '/local/domain/1/device/vbd/769', 'dev': 'hda1', 'state': '1', 'params': '/opt/xen/domains/vm01/diskimage', 'mode': 'w', 'frontend-id': '1', 'type': 'file'} to /local/domain/0/backend/vbd/1/769.
[2005-12-21 01:30:17 xend] DEBUG (DevController:104) DevController: writing {'virtual-device': '770', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vbd/1/770'} to /local/domain/1/device/vbd/770.
[2005-12-21 01:30:17 xend] DEBUG (DevController:106) DevController: writing {'domain': 'vm01', 'frontend': '/local/domain/1/device/vbd/770', 'dev': 'hda2', 'state': '1', 'params': '/opt/xen/domains/vm01/swapimage', 'mode': 'w', 'frontend-id': '1', 'type': 'file'} to /local/domain/0/backend/vbd/1/770.
[2005-12-21 01:30:17 xend] DEBUG (DevController:104) DevController: writing {'backend-id': '0', 'mac': '00:16:3e:50:88:e8', 'handle': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/1/0'} to /local/domain/1/device/vif/0.
[2005-12-21 01:30:17 xend] DEBUG (DevController:106) DevController: writing {'mac': '00:16:3e:50:88:e8', 'state': '1', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'frontend-id': '1', 'domain': 'vm01', 'frontend': '/local/domain/1/device/vif/0'} to /local/domain/0/backend/vif/1/0.
[2005-12-21 01:30:17 xend.XendDomainInfo] DEBUG (XendDomainInfo:655) Storing VM details: {'ssidref': '0', 'uuid': '19d0e670-31a9234a-73c39835-0a58f6b7', 'on_reboot': 'restart', 'image': "(linux (kernel /boot/vmlinuz-2.6.12-xenU) (ip :1.2.3.4::::eth0:dhcp) (root '/dev/hda1 ro') (vcpus 1) (vcpus 1) (boot c) (nics 1))", 'on_poweroff': 'destroy', 'name': 'vm01', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '96', 'on_crash': 'restart', 'start_time': '1135157417.12', 'maxmem': '96'}
[2005-12-21 01:30:17 xend.XendDomainInfo] DEBUG (XendDomainInfo:680) Storing domain details: {'console/ring-ref': '73417', 'console/port': '2', 'name': 'vm01', 'console/limit': '1048576', 'vm': '/vm/19d0e670-31a9234a-73c39835-0a58f6b7', 'domid': '1', 'cpu/0/availability': 'online', 'memory/target': '98304', 'store/ring-ref': '73418', 'store/port': '1'}
[2005-12-21 01:30:17 xend] DEBUG (DevController:133) Waiting for devices vif.
[2005-12-21 01:30:17 xend] DEBUG (DevController:139) Waiting for 0.
[2005-12-21 01:30:17 xend] DEBUG (DevController:409) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
[2005-12-21 01:30:27 xend] ERROR (SrvBase:87) Request wait_for_devices failed.
Traceback (most recent call last):
  File "/usr/lib/python/xen/web/SrvBase.py", line 85, in perform
    return op_method(op, req)
  File "/usr/lib/python/xen/xend/server/SrvDomain.py", line 72, in op_wait_for_devices
    return self.dom.waitForDevices()
  File "/usr/lib/python/xen/xend/XendDomainInfo.py", line 1343, in waitForDevices
    self.waitForDevices_(c)
  File "/usr/lib/python/xen/xend/XendDomainInfo.py", line 971, in waitForDevices_
    return self.getDeviceController(deviceClass).waitForDevices()
  File "/usr/lib/python/xen/xend/server/DevController.py", line 135, in waitForDevices
    return map(self.waitForDevice, self.deviceIDs())
  File "/usr/lib/python/xen/xend/server/DevController.py", line 145, in waitForDevice
    raise VmError("Device %s (%s) could not be connected. "
VmError: Device 0 (vif) could not be connected. Hotplug scripts not working.