Attachment 930365 Details for Bug 1133458 – xend.log

xend.log

xend.log (text/plain), 14.86 KB, created by Lingfei Kong on 2014-08-25 08:27:16 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Lingfei Kong

Created: 2014-08-25 08:27:16 UTC

Size: 14.86 KB

patch

obsolete

>[2014-07-25 00:11:06 xend 27541] INFO (SrvDaemon:283) Xend Daemon started
>[2014-07-25 00:11:06 xend 27541] INFO (SrvDaemon:287) Xend changeset: unavailable.
>[2014-07-25 00:11:06 xend 27541] DEBUG (tcp:98) Listening on :8002
>[2014-07-25 00:11:06 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:283) XendDomainInfo.recreate({'paused': 0, 'cpu_time': 1544751479855L, 'ssidref': 0, 'hvm': 0, 'shutdown_reason': 0, 'dying': 0, 'mem_kb': 30690304L, 'domid': 0, 'max_vcpu_id': 23, 'crashed': 0, 'running': 1, 'maxmem_kb': 17179869180L, 'shutdown': 0, 'online_vcpus': 24, 'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'blocked': 0})
>[2014-07-25 00:11:06 xend.XendDomainInfo 27541] INFO (XendDomainInfo:295) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000.
>[2014-07-25 00:11:06 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:1439) Setting maximum memory value of domain Domain-0 (0) to 32724 MiB.
>[2014-07-25 00:11:06 xend 27541] DEBUG (XendDomain:166) number of vcpus to use is 0
>[2014-07-25 00:11:06 xend 27541] INFO (SrvServer:116) unix path=/var/lib/xend/xend-socket
>[2014-07-25 00:11:06 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:1335) XendDomainInfo.handleShutdownWatch
>[2014-07-25 00:11:10 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:261) XendDomainInfo.create(['vm', ['name', 'hvm-win2012-64-1'], ['memory', 1024], ['maxmem', 1024], ['on_poweroff', 'destroy'], ['on_reboot', 'restart'], ['on_crash', 'preserve'], ['vcpus', 4], ['image', ['hvm', ['kernel', '/usr/lib/xen/boot/hvmloader'], ['device_model', '/usr/lib64/xen/bin/qemu-dm'], ['pae', 1], ['vcpus', 4], ['boot', 'd'], ['timer_mode', 1], ['serial', 'pty'], ['vnc', 1], ['vncunused', 1], ['vnclisten', '0.0.0.0'], ['display', 'localhost:12.0'], ['xauthority', '/root/.Xauthority'], ['acpi', 1], ['apic', 1], ['usbdevice', 'tablet'], ['keymap', 'en-us'], ['hpet', 1]]], ['device', ['vbd', ['uname', 'file:/var/lib/xen/images/hvm-win2012-64-1.img'], ['dev', 'hda'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'file:/root/en_windows_server_2012_r2_x64_dvd_2707946.iso'], ['dev', 'hdc:cdrom'], ['mode', 'r']]], ['device', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3e:63:3c:8b'], ['script', 'vif-bridge']]]])
>[2014-07-25 00:11:10 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:383) parseConfig: config is ['vm', ['name', 'hvm-win2012-64-1'], ['memory', 1024], ['maxmem', 1024], ['on_poweroff', 'destroy'], ['on_reboot', 'restart'], ['on_crash', 'preserve'], ['vcpus', 4], ['image', ['hvm', ['kernel', '/usr/lib/xen/boot/hvmloader'], ['device_model', '/usr/lib64/xen/bin/qemu-dm'], ['pae', 1], ['vcpus', 4], ['boot', 'd'], ['timer_mode', 1], ['serial', 'pty'], ['vnc', 1], ['vncunused', 1], ['vnclisten', '0.0.0.0'], ['display', 'localhost:12.0'], ['xauthority', '/root/.Xauthority'], ['acpi', 1], ['apic', 1], ['usbdevice', 'tablet'], ['keymap', 'en-us'], ['hpet', 1]]], ['device', ['vbd', ['uname', 'file:/var/lib/xen/images/hvm-win2012-64-1.img'], ['dev', 'hda'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'file:/root/en_windows_server_2012_r2_x64_dvd_2707946.iso'], ['dev', 'hdc:cdrom'], ['mode', 'r']]], ['device', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3e:63:3c:8b'], ['script', 'vif-bridge']]]]
>[2014-07-25 00:11:10 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:507) parseConfig: result is {'features': None, 'hpet': None, 'image': ['hvm', ['kernel', '/usr/lib/xen/boot/hvmloader'], ['device_model', '/usr/lib64/xen/bin/qemu-dm'], ['pae', 1], ['vcpus', 4], ['boot', 'd'], ['timer_mode', 1], ['serial', 'pty'], ['vnc', 1], ['vncunused', 1], ['vnclisten', '0.0.0.0'], ['display', 'localhost:12.0'], ['xauthority', '/root/.Xauthority'], ['acpi', 1], ['apic', 1], ['usbdevice', 'tablet'], ['keymap', 'en-us'], ['hpet', 1]], 'cpus': None, 'vcpu_avail': None, 'backend': [], 'uuid': None, 'on_reboot': 'restart', 'cpu_weight': None, 'memory': 1024, 'cpu_cap': None, 'localtime': None, 'timer_mode': None, 'start_time': None, 'on_poweroff': 'destroy', 'on_crash': 'preserve', 'device': [('vbd', ['vbd', ['uname', 'file:/var/lib/xen/images/hvm-win2012-64-1.img'], ['dev', 'hda'], ['mode', 'w']]), ('vbd', ['vbd', ['uname', 'file:/root/en_windows_server_2012_r2_x64_dvd_2707946.iso'], ['dev', 'hdc:cdrom'], ['mode', 'r']]), ('vif', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3e:63:3c:8b'], ['script', 'vif-bridge']])], 'bootloader': None, 'maxmem': 1024, 'shadow_memory': None, 'name': 'hvm-win2012-64-1', 'bootloader_args': None, 'vcpus': 4, 'cpu': None}
>[2014-07-25 00:11:10 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:1975) XendDomainInfo.construct: None
>[2014-07-25 00:11:10 xend 27541] DEBUG (balloon:146) Balloon: 2139816 KiB free; need 4096; done.
>[2014-07-25 00:11:10 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:2150) XendDomainInfo.initDomain: 26 256
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: boot, val: d
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: fda, val: None
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: fdb, val: None
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: soundhw, val: None
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: localtime, val: None
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: serial, val: pty
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: std-vga, val: None
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: isa, val: None
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: vcpus, val: 4
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: acpi, val: 1
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: usb, val: None
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: usbdevice, val: tablet
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:347) args: k, val: en-us
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:407) No VNC passwd configured for vfb access
>[2014-07-25 00:11:10 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:2191) _initDomain:shadow_memory=0x0, maxmem=0x400, memory=0x400.
>[2014-07-25 00:11:10 xend 27541] DEBUG (balloon:146) Balloon: 2138768 KiB free; need 1069056; done.
>[2014-07-25 00:11:10 xend 27541] INFO (image:139) buildDomain os=None dom=26 vcpus=4
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:275) domid          = 26
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:276) image          = /usr/lib/xen/boot/hvmloader
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:277) store_evtchn   = 5
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:278) memsize        = 1024
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:279) vcpus          = 4
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:280) pae            = 1
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:281) acpi           = 1
>[2014-07-25 00:11:10 xend 27541] DEBUG (image:282) apic           = 1
>[2014-07-25 00:11:11 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:677) Checking for duplicate for uname: /var/lib/xen/images/hvm-win2012-64-1.img [file:/var/lib/xen/images/hvm-win2012-64-1.img], dev: hda, mode: w
>[2014-07-25 00:11:11 xend 27541] DEBUG (blkif:27) exception looking up device number for hda: [Errno 2] No such file or directory: '/dev/hda'
>[2014-07-25 00:11:11 xend 27541] DEBUG (blkif:27) exception looking up device number for hdc: [Errno 2] No such file or directory: '/dev/hdc'
>[2014-07-25 00:11:11 xend 27541] DEBUG (blkif:27) exception looking up device number for hda: [Errno 2] No such file or directory: '/dev/hda'
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:133) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/26/768'} to /local/domain/26/device/vbd/768.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:135) DevController: writing {'domain': 'hvm-win2012-64-1', 'frontend': '/local/domain/26/device/vbd/768', 'dev': 'hda', 'state': '1', 'params': '/var/lib/xen/images/hvm-win2012-64-1.img', 'mode': 'w', 'online': '1', 'frontend-id': '26', 'type': 'file'} to /local/domain/0/backend/vbd/26/768.
>[2014-07-25 00:11:11 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:677) Checking for duplicate for uname: /root/en_windows_server_2012_r2_x64_dvd_2707946.iso [file:/root/en_windows_server_2012_r2_x64_dvd_2707946.iso], dev: hdc, mode: r
>[2014-07-25 00:11:11 xend 27541] DEBUG (blkif:27) exception looking up device number for hdc: [Errno 2] No such file or directory: '/dev/hdc'
>[2014-07-25 00:11:11 xend 27541] DEBUG (blkif:27) exception looking up device number for hda: [Errno 2] No such file or directory: '/dev/hda'
>[2014-07-25 00:11:11 xend 27541] DEBUG (blkif:27) exception looking up device number for hdc: [Errno 2] No such file or directory: '/dev/hdc'
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:133) DevController: writing {'backend-id': '0', 'virtual-device': '5632', 'device-type': 'cdrom', 'state': '1', 'backend': '/local/domain/0/backend/vbd/26/5632'} to /local/domain/26/device/vbd/5632.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:135) DevController: writing {'domain': 'hvm-win2012-64-1', 'frontend': '/local/domain/26/device/vbd/5632', 'dev': 'hdc', 'state': '1', 'params': '/root/en_windows_server_2012_r2_x64_dvd_2707946.iso', 'mode': 'r', 'online': '1', 'frontend-id': '26', 'type': 'file'} to /local/domain/0/backend/vbd/26/5632.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:133) DevController: writing {'backend-id': '0', 'mac': '00:16:3e:63:3c:8b', 'handle': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/26/0'} to /local/domain/26/device/vif/0.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:135) DevController: writing {'bridge': 'xenbr0', 'domain': 'hvm-win2012-64-1', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/26/device/vif/0', 'mac': '00:16:3e:63:3c:8b', 'online': '1', 'frontend-id': '26'} to /local/domain/0/backend/vif/26/0.
>[2014-07-25 00:11:11 xend 27541] INFO (image:453) spawning device models: /usr/lib64/xen/bin/qemu-dm ['/usr/lib64/xen/bin/qemu-dm', '-d', '26', '-m', '1024', '-boot', 'd', '-serial', 'pty', '-vcpus', '4', '-acpi', '-usbdevice', 'tablet', '-k', 'en-us', '-domain-name', 'hvm-win2012-64-1', '-net', 'nic,vlan=1,macaddr=00:16:3e:63:3c:8b,model=rtl8139', '-net', 'tap,vlan=1,bridge=xenbr0', '-vnc', '0.0.0.0:26', '-vncunused']
>[2014-07-25 00:11:11 xend 27541] INFO (image:456) device model pid: 27594
>[2014-07-25 00:11:11 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:1072) Storing VM details: {'shadow_memory': '12', 'uuid': '4e19e6bb-70a0-58d0-a086-92293408d65b', 'on_reboot': 'restart', 'start_time': '1406218271.06', 'on_poweroff': 'destroy', 'name': 'hvm-win2012-64-1', 'xend/restart_count': '0', 'vcpus': '4', 'vcpu_avail': '15', 'memory': '1024', 'on_crash': 'preserve', 'image': '(hvm (kernel /usr/lib/xen/boot/hvmloader) (device_model /usr/lib64/xen/bin/qemu-dm) (pae 1) (vcpus 4) (boot d) (timer_mode 1) (serial pty) (vnc 1) (vncunused 1) (vnclisten 0.0.0.0) (display localhost:12.0) (xauthority /root/.Xauthority) (acpi 1) (apic 1) (usbdevice tablet) (keymap en-us) (hpet 1))', 'maxmem': '1024'}
>[2014-07-25 00:11:11 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:1107) Storing domain details: {'console/port': '6', 'cpu/3/availability': 'online', 'name': 'hvm-win2012-64-1', 'console/limit': '1048576', 'cpu/2/availability': 'online', 'vm': '/vm/4e19e6bb-70a0-58d0-a086-92293408d65b', 'domid': '26', 'cpu/0/availability': 'online', 'memory/target': '1048576', 'store/ring-ref': '262142', 'cpu/1/availability': 'online', 'store/port': '5'}
>[2014-07-25 00:11:11 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:1335) XendDomainInfo.handleShutdownWatch
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:179) Waiting for devices vif.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:184) Waiting for 0.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:571) hotplugStatusCallback /local/domain/0/backend/vif/26/0/hotplug-status.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:571) hotplugStatusCallback /local/domain/0/backend/vif/26/0/hotplug-status.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:585) hotplugStatusCallback 1.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:94) Lock released
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:179) Waiting for devices usb.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:179) Waiting for devices vbd.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:184) Waiting for 768.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:571) hotplugStatusCallback /local/domain/0/backend/vbd/26/768/hotplug-status.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:571) hotplugStatusCallback /local/domain/0/backend/vbd/26/768/hotplug-status.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:585) hotplugStatusCallback 1.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:94) Lock released
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:184) Waiting for 5632.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:571) hotplugStatusCallback /local/domain/0/backend/vbd/26/5632/hotplug-status.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:571) hotplugStatusCallback /local/domain/0/backend/vbd/26/5632/hotplug-status.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:585) hotplugStatusCallback 1.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:94) Lock released
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:179) Waiting for devices irq.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:179) Waiting for devices vkbd.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:179) Waiting for devices vfb.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:179) Waiting for devices pci.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:179) Waiting for devices ioports.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:179) Waiting for devices tap.
>[2014-07-25 00:11:11 xend 27541] DEBUG (DevController:179) Waiting for devices vtpm.
>[2014-07-25 00:11:11 xend 27541] INFO (XendDomain:388) Domain hvm-win2012-64-1 (26) unpaused.
>[2014-07-25 00:11:12 xend.XendDomainInfo 27541] INFO (XendDomainInfo:1292) Domain has shutdown: name=hvm-win2012-64-1 id=26 reason=reboot.
>[2014-07-25 00:11:12 xend.XendDomainInfo 27541] ERROR (XendDomainInfo:2864) VM hvm-win2012-64-1 restarting too fast (Elapsed time: 1.111902 seconds). Refusing to restart to avoid loops.
>[2014-07-25 00:11:12 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:2386) XendDomainInfo.destroy: domid=26
>[2014-07-25 00:11:12 xend.XendDomainInfo 27541] INFO (XendDomainInfo:2527) Dev 768 still active, looping...
>[2014-07-25 00:11:12 xend.XendDomainInfo 27541] INFO (XendDomainInfo:2527) Dev 5632 still active, looping...
>[2014-07-25 00:11:12 xend.XendDomainInfo 27541] INFO (XendDomainInfo:2527) Dev 768 still active, looping...
>[2014-07-25 00:11:12 xend.XendDomainInfo 27541] INFO (XendDomainInfo:2527) Dev 5632 still active, looping...
>[2014-07-25 00:11:12 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:2311) UUID Created: True
>[2014-07-25 00:11:12 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:2312) Devices to release: [], domid = 26
>[2014-07-25 00:11:12 xend.XendDomainInfo 27541] DEBUG (XendDomainInfo:2324) Releasing PVFB backend devices ...

Actions: View

Attachments on bug 1133458: 930363 | 930364 | 930365 | 930366 | 930367 | 930368 | 930369 | 930370