SCSI0:1: Command WRITE(10) took 1.857 seconds (ok)
Verfasst: 10.09.2008, 23:36
Hallo, ich hänge an einen Problem, dass ich durch googeln und durchsuchen dieses Forums nicht lösen konnte
Ein Win2003 Gast produziert sändig diese Fehlermeldung und friert nach einigen Tagen ein.
Host: Debian Etch 4
kernel: 2.6.18-6
Vmware Server: 1.0.7
Des Gast reagiert nicht mehr und lässt sich auch nicht mit vmware-cmd ... stop beenden. Nach einen kill -9 [Pozess-ID des Gastes] bekomme ich beim Neustart die Fehlermeldung, dass sich der Gast nicht mehr mit dem vmx-Prozess verbinden kann. In der Prozessliste hängen nach dem Kill auch noch Defunct-Prozesse von dem VMware-Gast, die ich aber nicht killen kann. Bevor ich die Logs und vmx Poste eine Frage:
Ist es möglich, so einen toten Prozess zu beenden, ohne den Mutterprozess (vmware-cmd) zu stoppen? Das würde sicher auch schon weiterhelfen.
An anderer Stelle habe ich aus einem Posting aus 2004 gelesen, dass Hyperthreadding ein Problem sein kann. Gilt das auch heute noch? Kann ich von der Linux-Kommandozeile rausbekommen, ob Hyperthreading aktiv ist?
Es handelt sich bei dem Gast um einen Fileserver, der sich mit drei anderen Win2003-Gästen einen Host mit 8 Xeons und 16GB RAM teilt. VMware-tools sind installatiert. Das System arbeite auf einem RAID. iostat zeigt keine beängstigende Festplattenaktivität. Alles ist im Bereich von maximal einigen MB die Sekunde. Dennoch sieht es aus, als könnte der Gast seine Daten nicht schnell genug wegschreiben.
Hat da vielleicht jemand eine Idee?
root@vm-host:/mnt/vmware/Fileserver# cat Fileserver.vmx
#!/usr/bin/vmware
config.version = "8"
virtualHW.version = "4"
scsi0.present = "TRUE"
scsi0.virtualDev = "lsilogic"
memsize = "512"
scsi0:0.present = "TRUE"
scsi0:0.fileName = "Fileserver.vmdk"
scsi0:0.writeThrough = "TRUE"
ide1:0.present = "TRUE"
ide1:0.fileName = "/mnt/images/w2k3se-R2.disk2.iso"
ide1:0.deviceType = "cdrom-image"
floppy0.startConnected = "FALSE"
floppy0.fileName = "/dev/fd0"
Ethernet0.present = "TRUE"
displayName = "file-srv (Fileserver)"
guestOS = "winnetenterprise"
priority.grabbed = "normal"
priority.ungrabbed = "normal"
powerType.powerOff = "hard"
powerType.powerOn = "hard"
powerType.suspend = "hard"
powerType.reset = "hard"
scsi0:0.redo = ""
ide1:0.startConnected = "FALSE"
ethernet0.addressType = "generated"
uuid.location = "56 4d a5 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7"
uuid.bios = "56 4d a7 db 7d 61 3d ba-96 50 d7 84 41 74 51 e7"
ethernet0.generatedAddress = "00:0c:29:74:51:e7"
ethernet0.generatedAddressOffset = "0"
tools.syncTime = "FALSE"
nvram = "Fileserver.nvram"
scsi0:1.present = "TRUE"
scsi0:1.fileName = "FileServer Partition D.vmdk"
scsi0:1.redo = ""
autostart = "poweron"
autostop = "softpoweroff"
checkpoint.vmState = "Fileserver.vmss"
Logfile:
Sep 07 14:30:48: vmx| Log for VMware Server pid=4068 version=1.0.7 build=build-108231 option=Release
Sep 07 14:30:48: vmx| Command line: "/usr/lib/vmware/bin/vmware-vmx" "-C" "-@" """" "/mnt/vmware/Fileserver/Fileserver.v
mx"
Sep 07 14:30:48: vmx| vmxvmdb: Index name being generated from config file
Sep 07 14:30:48: vmx| VMXVmdbConnectServerd - Trying to discover serverd
Sep 07 14:30:48: vmx| MStat: Creating Stat system.cpuusage
Sep 07 14:30:48: vmx| MStat: Creating Stat system.ram
Sep 07 14:30:48: vmx| MStat: Creating Stat system.uptime
Sep 07 14:30:48: vmx| MStat: Creating Stat system.load
Sep 07 14:30:48: vmx| cpuids[0].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[1].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[2].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[3].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[4].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[5].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[6].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[7].id81.ecx = 0x1
Sep 07 14:30:48: vmx| pcpu #0 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #0 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x20800
Sep 07 14:30:48: vmx| pcpu #0 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #1 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #1 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x1020800
Sep 07 14:30:48: vmx| pcpu #1 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #2 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #2 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x6020800
Sep 07 14:30:48: vmx| pcpu #2 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #3 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #3 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x7020800
Sep 07 14:30:48: vmx| pcpu #3 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #4 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #4 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x10020800
Sep 07 14:30:48: vmx| pcpu #4 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #5 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #5 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x11020800
Sep 07 14:30:48: vmx| pcpu #5 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #6 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #6 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x16020800
Sep 07 14:30:48: vmx| pcpu #6 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #7 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #7 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x17020800
Sep 07 14:30:48: vmx| pcpu #7 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| CPUID id1.edx: 0xbfebfbff id1.ecx: 0x659d id81.edx: 0x20000800 id81.ecx: 0x1
Sep 07 14:30:48: vmx| CPUID id88.ecx: 0 id88.edx: 0
Sep 07 14:30:48: vmx| Setup symlink /var/run/vmware/%2Fmnt%2Fvmware%2FFileserver%2FFileserver%2Evmx -> /var/run/vmware/a
dmin/4068
Sep 07 14:30:48: vmx| ACL_InitCapabilities: here 1 (bug 63252)
Sep 07 14:30:48: vmx| changing directory to /mnt/vmware/Fileserver/.
Sep 07 14:30:48: vmx| Config file: /mnt/vmware/Fileserver/Fileserver.vmx
Sep 07 14:30:48: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:48: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| CnxAcceptConnection: Could not receive fd on 27: invalid control message
Sep 07 14:30:49: vmx| Failed to get IPC connection
Sep 07 14:30:49: vmx| Read from FIFO 32 -- connecting to serverd...
Sep 07 14:30:49: vmx| VMDB: Connected to serverd
Sep 07 14:30:49: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset
Sep 07 14:30:49: vmx| PowerOn
Sep 07 14:30:49: vmx| Host ACPI: SRAT @ 0xcff76000
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x00, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x01, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x06, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x07, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x10, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x11, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x16, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x17, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host: CPU #0 LAPIC ID 0x00, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #1 LAPIC ID 0x01, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #2 LAPIC ID 0x06, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #3 LAPIC ID 0x07, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #4 LAPIC ID 0x10, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #5 LAPIC ID 0x11, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #6 LAPIC ID 0x16, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #7 LAPIC ID 0x17, node 0x00
Sep 07 14:30:49: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| HOST sysname Linux, nodename s002vm01, release 2.6.18-6-amd64, version #1 SMP Mon Jun 16 22:30:01
UTC 2008, machine x86_64, SMP, hz=250
Sep 07 14:30:49: vmx| DICT --- USER PREFERENCES
Sep 07 14:30:49: vmx| DICT pref.grabOnKeyPress = FALSE
Sep 07 14:30:49: vmx| DICT pref.autoFitFullScreen = fitHostToGuest
Sep 07 14:30:49: vmx| DICT pref.view.navBar.type = favorites
Sep 07 14:30:49: vmx| DICT pref.mruDest0.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.mruDest0.destString = 192.168.2.3
Sep 07 14:30:49: vmx| DICT pref.mruDest0.user = admin
Sep 07 14:30:49: vmx| DICT pref.mruDest1.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.mruDest1.destString = s002vm03
Sep 07 14:30:49: vmx| DICT pref.mruDest1.user = admin
Sep 07 14:30:49: vmx| DICT pref.mruDest2.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest2.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest2.user =
Sep 07 14:30:49: vmx| DICT pref.mruDest3.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest3.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest3.user =
Sep 07 14:30:49: vmx| DICT pref.mruDest4.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest4.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest4.user =
Sep 07 14:30:49: vmx| DICT pref.mruDest5.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest5.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest5.user =
Sep 07 14:30:49: vmx| DICT pref.mruDest6.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest6.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest6.user =
Sep 07 14:30:49: vmx| DICT pref.mruDest7.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest7.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest7.user =
Sep 07 14:30:49: vmx| DICT webUpdate.checkPeriod = weekly
Sep 07 14:30:49: vmx| DICT webUpdate.checkLast = 1220794210
Sep 07 14:30:49: vmx| DICT webUpdate.lastCheck.status = done_none
Sep 07 14:30:49: vmx| DICT pref.console.openedObj0.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj0.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj0.path = /vm/#9f638f20d08a1290/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj0.file = /mnt/vmware/DController/DController.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj0.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj.maxNum = 6
Sep 07 14:30:49: vmx| DICT pref.console.openedObj1.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj1.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj1.path = /vm/#47f27aabed7d88e6/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj1.file = /mnt/vmware/s002hy07/s002hy07.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj1.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj2.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj2.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj2.path = /vm/#9268f6a5cccd0b40/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj2.file = /mnt/vmware/S002FS02/S002FS02.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj2.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj3.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj3.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj3.path = /vm/#59ad2050f329391e/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj3.file = /mnt/vmware/citrix_webserver/citrix_webserver.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj3.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj4.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj4.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj4.path = /vm/#d58734eea9603e24/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj4.file = /mnt/vmware/Adminstation/Adminstation.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj4.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj5.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj5.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj5.path = /vm/#632b892ab9692c60/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj5.file = /mnt/vmware/s002hy08/s002hy08.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj5.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.view.navBar = TRUE
Sep 07 14:30:49: vmx| DICT pref.kvm = FALSE
Sep 07 14:30:49: vmx| DICT pref.cnxType = local
Sep 07 14:30:49: vmx| DICT pref.placement.left = 224
Sep 07 14:30:49: vmx| DICT pref.placement.top = 163
Sep 07 14:30:49: vmx| DICT pref.placement.right = 1024
Sep 07 14:30:49: vmx| DICT pref.placement.bottom = 763
Sep 07 14:30:49: vmx| DICT --- USER DEFAULTS
Sep 07 14:30:49: vmx| DICT --- HOST DEFAULTS
Sep 07 14:30:49: vmx| DICT serverd.init.fullpath = /usr/lib/vmware/serverd/init.pl
Sep 07 14:30:49: vmx| DICT authd.client.port = 902
Sep 07 14:30:49: vmx| DICT control.fullpath = /usr/bin/vmware-cmd
Sep 07 14:30:49: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd
Sep 07 14:30:49: vmx| DICT loop.fullpath = /usr/bin/vmware-loop
Sep 07 14:30:49: vmx| DICT libdir = /usr/lib/vmware
Sep 07 14:30:49: vmx| DICT vmware.fullpath = /usr/bin/vmware
Sep 07 14:30:49: vmx| DICT vmdir = /mnt/vmware
Sep 07 14:30:49: vmx| DICT dhcpd.fullpath = /usr/bin/vmnet-dhcpd
Sep 07 14:30:49: vmx| DICT serverd.fullpath = /usr/sbin/vmware-serverd
Sep 07 14:30:49: vmx| DICT datastore.name = local
Sep 07 14:30:49: vmx| DICT datastore.localpath = /mnt/vmware/
Sep 07 14:30:49: vmx| DICT --- SITE DEFAULTS
Sep 07 14:30:49: vmx| DICT tag.help = introduction.htm
Sep 07 14:30:49: vmx| DICT tag.configurationEditor = config_editor_newvm.htm
Sep 07 14:30:49: vmx| DICT tag.ideConfig = devices_virtualdrive.htm
Sep 07 14:30:49: vmx| DICT tag.floppyConfig = devices_floppy.htm
Sep 07 14:30:49: vmx| DICT tag.mouseConfig = devices_mouse.htm
Sep 07 14:30:49: vmx| DICT tag.netConfig = devices_netadapter.htm
Sep 07 14:30:49: vmx| DICT tag.parallelConfig = devices_parallel.htm
Sep 07 14:30:49: vmx| DICT tag.serialConfig = devices_serial.htm
Sep 07 14:30:49: vmx| DICT tag.soundConfig = devices_sound.htm
Sep 07 14:30:49: vmx| DICT tag.memConfig = configvm_memory.htm
Sep 07 14:30:49: vmx| DICT tag.miscConfig = configvm.htm
Sep 07 14:30:49: vmx| DICT tag.usbConfig = devices_usb.htm
Sep 07 14:30:49: vmx| DICT tag.displayConfig = configvm_display-problems.htm
Sep 07 14:30:49: vmx| DICT tag.tools = vmtools.htm
Sep 07 14:30:49: vmx| DICT --- COMMAND LINE
Sep 07 14:30:49: vmx| DICT gui.managementUI = TRUE
Sep 07 14:30:49: vmx| DICT --- CONFIGURATION
Sep 07 14:30:49: vmx| DICT config.version = 8
Sep 07 14:30:49: vmx| DICT virtualHW.version = 4
Sep 07 14:30:49: vmx| DICT scsi0.present = TRUE
Sep 07 14:30:49: vmx| DICT scsi0.virtualDev = lsilogic
Sep 07 14:30:49: vmx| DICT memsize = 512
Sep 07 14:30:49: vmx| DICT scsi0:0.present = TRUE
Sep 07 14:30:49: vmx| DICT scsi0:0.fileName = Fileserver.vmdk
Sep 07 14:30:49: vmx| DICT scsi0:0.writeThrough = TRUE
Sep 07 14:30:49: vmx| DICT ide1:0.present = TRUE
Sep 07 14:30:49: vmx| DICT ide1:0.fileName = /mnt/images/w2k3se-R2.disk2.iso
Sep 07 14:30:49: vmx| DICT ide1:0.deviceType = cdrom-image
Sep 07 14:30:49: vmx| DICT floppy0.startConnected = FALSE
Sep 07 14:30:49: vmx| DICT floppy0.fileName = /dev/fd0
Sep 07 14:30:49: vmx| DICT Ethernet0.present = TRUE
Sep 07 14:30:49: vmx| DICT displayName = S002FS01 (Fileserver)
Sep 07 14:30:49: vmx| DICT guestOS = winnetenterprise
Sep 07 14:30:49: vmx| DICT priority.grabbed = normal
Sep 07 14:30:49: vmx| DICT priority.ungrabbed = normal
Sep 07 14:30:49: vmx| DICT powerType.powerOff = hard
Sep 07 14:30:49: vmx| DICT powerType.powerOn = hard
Sep 07 14:30:49: vmx| DICT powerType.suspend = hard
Sep 07 14:30:49: vmx| DICT powerType.reset = hard
Sep 07 14:30:49: vmx| DICT scsi0:0.redo =
Sep 07 14:30:49: vmx| DICT ide1:0.startConnected = FALSE
Sep 07 14:30:49: vmx| DICT ethernet0.addressType = generated
Sep 07 14:30:49: vmx| DICT uuid.location = 56 4d a7 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7
Sep 07 14:30:49: vmx| DICT uuid.bios = 56 4d a7 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7
Sep 07 14:30:49: vmx| DICT ethernet0.generatedAddress = 00:0c:29:74:51:e7
Sep 07 14:30:49: vmx| DICT ethernet0.generatedAddressOffset = 0
Sep 07 14:30:49: vmx| DICT tools.syncTime = FALSE
Sep 07 14:30:49: vmx| DICT nvram = Fileserver.nvram
Sep 07 14:30:49: vmx| DICT scsi0:1.present = TRUE
Sep 07 14:30:49: vmx| DICT scsi0:1.fileName = FileServer Partition D.vmdk
Sep 07 14:30:49: vmx| DICT scsi0:1.redo =
Sep 07 14:30:49: vmx| DICT autostart = poweron
Sep 07 14:30:49: vmx| DICT autostop = softpoweroff
Sep 07 14:30:49: vmx| DICT checkpoint.vmState = Fileserver.vmss
Sep 07 14:30:49: vmx| DICT --- USER DEFAULTS
Sep 07 14:30:49: vmx| DICT --- HOST DEFAULTS
Sep 07 14:30:49: vmx| DICT serverd.init.fullpath = /usr/lib/vmware/serverd/init.pl
Sep 07 14:30:49: vmx| DICT authd.client.port = 902
Sep 07 14:30:49: vmx| DICT control.fullpath = /usr/bin/vmware-cmd
Sep 07 14:30:49: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd
Sep 07 14:30:49: vmx| DICT loop.fullpath = /usr/bin/vmware-loop
Sep 07 14:30:49: vmx| DICT libdir = /usr/lib/vmware
Sep 07 14:30:49: vmx| DICT vmware.fullpath = /usr/bin/vmware
Sep 07 14:30:49: vmx| DICT vmdir = /mnt/vmware
Sep 07 14:30:49: vmx| DICT dhcpd.fullpath = /usr/bin/vmnet-dhcpd
Sep 07 14:30:49: vmx| DICT serverd.fullpath = /usr/sbin/vmware-serverd
Sep 07 14:30:49: vmx| DICT datastore.name = local
Sep 07 14:30:49: vmx| DICT datastore.localpath = /mnt/vmware/
Sep 07 14:30:49: vmx| DICT --- SITE DEFAULTS
Sep 07 14:30:49: vmx| DICT tag.help = introduction.htm
Sep 07 14:30:49: vmx| DICT tag.configurationEditor = config_editor_newvm.htm
Sep 07 14:30:49: vmx| DICT tag.ideConfig = devices_virtualdrive.htm
Sep 07 14:30:49: vmx| DICT tag.floppyConfig = devices_floppy.htm
Sep 07 14:30:49: vmx| DICT tag.mouseConfig = devices_mouse.htm
Sep 07 14:30:49: vmx| DICT tag.netConfig = devices_netadapter.htm
Sep 07 14:30:49: vmx| DICT tag.parallelConfig = devices_parallel.htm
Sep 07 14:30:49: vmx| DICT tag.serialConfig = devices_serial.htm
Sep 07 14:30:49: vmx| DICT tag.soundConfig = devices_sound.htm
Sep 07 14:30:49: vmx| DICT tag.memConfig = configvm_memory.htm
Sep 07 14:30:49: vmx| DICT tag.miscConfig = configvm.htm
Sep 07 14:30:49: vmx| DICT tag.usbConfig = devices_usb.htm
Sep 07 14:30:49: vmx| DICT tag.displayConfig = configvm_display-problems.htm
Sep 07 14:30:49: vmx| DICT tag.tools = vmtools.htm
Sep 07 14:30:49: vmx| DICT --- GLOBAL SETTINGS
Sep 07 14:30:49: vmx| Msg_Hint: msg.hostlinux.othervms (not shown)
Sep 07 14:30:49: vmx| WSSCAN: reserved mem (in MB) min=32 max=15968 recommended=15968
Sep 07 14:30:49: vmx| hostMem=16064 maxAllowedAll=-1 maxAllowedVM=3600
Sep 07 14:30:49: vmx| totOverhead=16
Sep 07 14:30:49: vmx| WSSCAN: used rec mem (in MB) 15968
Sep 07 14:30:49: vmx| WSSCAN: Overhead 137457 paged 6047 nonpaged 4096 maxFBSize
Sep 07 14:30:49: vmx| WSSCAN 4 510473 4087808 -1 4087808 -1 50 304718
Sep 07 14:30:49: vmx| LICENSE using: '/etc/vmware/license.vs.1.0-00'
Sep 07 14:30:49: vmx| STATDECLGROUP stats Root "" null
Sep 07 14:30:49: vmx| Host CPUID features: version 0xf48 id1.edx 0xbfebfbff id1.ecx 0x659d id81.edx 0x20000800 id81.ecx
0x1
Sep 07 14:30:49: vmx| CPU.cpuFeatures = 0x39b1ff30
Sep 07 14:30:49: vmx| CPUID after masking: version 0xf48 id1.edx 0xfebbbff id1.ecx 0x15 id81.edx 0x800 id81.ecx 0x0 id88
.ecx 0x0
Sep 07 14:30:49: vmx| CPU.cpuFeatures = 0x18b1fe00
Sep 07 14:30:49: vmx| APIC: Local APIC at 0xfee00000
Sep 07 14:30:49: vmx| KHZEstimate 3169412
Sep 07 14:30:49: vmx| MHZEstimate 3169
Sep 07 14:30:49: vmx| NumVCPUs 1
Sep 07 14:30:49: vmx| UUID: location-UUID is 56 4d a7 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7
Sep 07 14:30:49: vmx| MM: Using partialmap, 131072 pages AC 0 CE 1 TM 0 DOHU 0
Sep 07 14:30:49: vmx| UUID: canonical path is /mnt/vmware/Fileserver/Fileserver.vmx
Sep 07 14:30:49: vmx| UUID: location-UUID is 56 4d a7 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7
Sep 07 14:30:49: vmx| MM: using fileName /mnt/vmware/Fileserver/564da7da-7d61-3dba-9650-d784417451e7.vmem for paging
Sep 07 14:30:49: vmx| Msg_Reset:
Sep 07 14:30:49: vmx| ----------------------------------------
Sep 07 14:30:49: vmx| Opened paging file /mnt/vmware/Fileserver/564da7da-7d61-3dba-9650-d784417451e7.vmem
Sep 07 14:30:49: vmx| Mapped mainmem as pageable
Sep 07 14:30:49: vmx| MStat: Creating Stat vm.cpuusage
Sep 07 14:30:49: vmx| MStat: Creating Stat vm.ram
Sep 07 14:30:49: vmx| MStat: Creating Stat vm.uptime
Sep 07 14:30:49: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| DISK: OPEN scsi0:0 '/mnt/vmware/Fileserver/Fileserver.vmdk' persistent R[(null)]
Sep 07 14:30:49: vmx| AIOGNRC: Starting 10 I/O threads.
Sep 07 14:30:49: vmx| DISKLIB-DSCPTR: Opened [0]: "Fileserver.vmdk" (0x2a)
Sep 07 14:30:49: vmx| DISKLIB-LINK : Opened '/mnt/vmware/Fileserver/Fileserver.vmdk' (0x2a): monolithicSparse, 20971520
sectors / 10240 Mb.
Sep 07 14:30:49: vmx| DISKLIB-LIB : Opened "/mnt/vmware/Fileserver/Fileserver.vmdk" (flags 0x2a).
Sep 07 14:30:49: vmx| DISK: OPEN '/mnt/vmware/Fileserver/Fileserver.vmdk' Geo (1305/255/63) BIOS Geo (1305/255/63) freeS
pace=35084Mb
Sep 07 14:30:49: vmx| DISK: OPEN scsi0:1 '/mnt/vmware/Fileserver/FileServer Partition D.vmdk' persistent R[(null)]
Sep 07 14:30:49: vmx| DISKLIB-DSCPTR: Opened [0]: "FileServer Partition D-flat.vmdk" 0 (0xa)
Sep 07 14:30:49: vmx| DISKLIB-LINK : Opened '/mnt/vmware/Fileserver/FileServer Partition D.vmdk' (0xa): monolithicFlat,
629145600 sectors / 307200 Mb.
Sep 07 14:30:49: vmx| DISKLIB-LIB : Opened "/mnt/vmware/Fileserver/FileServer Partition D.vmdk" (flags 0xa).
Sep 07 14:30:49: vmx| DISK: OPEN '/mnt/vmware/Fileserver/FileServer Partition D.vmdk' Geo (39162/255/63) BIOS Geo (39162
/255/63) freeSpace=35084Mb
Sep 07 14:30:49: vmx| TimeTracker host to guest rate conversion 1562429379446 @ 3169412000Hz -> 1562429379446 @ 31694120
00Hz
Sep 07 14:30:49: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0
Sep 07 14:30:49: vmx| MStat: Creating Stat ide1:0.bytesread
Sep 07 14:30:49: vmx| MStat: Creating Stat ide1:0.byteswritten
Sep 07 14:30:49: vmx| MStat: Creating Stat scsi0:0.bytesread
Sep 07 14:30:49: vmx| MStat: Creating Stat scsi0:0.byteswritten
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:0 : capacity=20971520
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:0 : geometry=1305/255/63
Sep 07 14:30:49: vmx| MStat: Creating Stat scsi0:1.bytesread
Sep 07 14:30:49: vmx| MStat: Creating Stat scsi0:1.byteswritten
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:1 : capacity=629145600
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:1 : geometry=39162/255/63
Sep 07 14:30:49: vmx| SCSI0: UNTAGGED commands will be converted to ORDER tags.
Sep 07 14:30:49: vmx| MStat: Creating Stat vm.heartbeat
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 07 14:30:49: vmx| TOOLS INSTALL initializing state to IDLE on power on.
Sep 07 14:30:49: vmx| VMMon_GetkHzEstimate: Calculated 3169226 kHz
Sep 07 14:30:49: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks
Sep 07 14:30:49: vmx| MStat: Creating Stat ethernet0.bytesread
Sep 07 14:30:49: vmx| MStat: Creating Stat ethernet0.byteswritten
Sep 07 14:30:49: vmx| Ethernet0 MAC Address: 00:0c:29:74:51:e7
Sep 07 14:30:49: vmx| VMXNET: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks, dontClusterSize is 128
Sep 07 14:30:49: vmx| E1000: checksum cycles/kB: C=936 asm=2904
Sep 07 14:30:49: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1
Sep 07 14:30:49: vmx| VMX setting maximum IPC write buffers to 0 packets, 0 bytes
Sep 07 14:30:49: mks| Async MKS thread is alive
Sep 07 14:30:49: vcpu-0| APIC: version = 0x14, max LVT = 5
Sep 07 14:30:49: vcpu-0| APIC: LDR = 0x2000000, DFR = 0xfffffff
Sep 07 14:30:49: vmx| Accepted new connection at 179 for thread servercontrol (0x84d1360)
Sep 07 14:30:49: vmx| VUINewControlConnection: before slow ACL gunk (bug 63252).
Sep 07 14:30:49: vmx| ACL_InitCapabilities: here 2 (bug 63252)
Sep 07 14:30:49: vmx| VUINewControlConnection: after slow ACL gunk (bug 63252).
Sep 07 14:30:49: vmx| VUI: A new VMControl client connected.
Sep 07 14:30:49: vmx| IPC version negotiation version: VMX returning 2.1 to servercontrol
Sep 07 14:30:49: vmx| IPC vmcontrol-temp version: VMX returning 11.4 to servercontrol that tried 11.4
Sep 07 14:30:49: vcpu-0| PShare: enabled 1, scanRate 32, checkRate 16
Sep 07 14:30:49: vcpu-0| guestCpuFeatures = 0x18b1fe00
Sep 07 14:30:49: vcpu-0| Init modules.
Sep 07 14:30:49: vcpu-0| CPU reset: hard
Sep 07 14:30:49: vmx| VNET: Notification enabled for Ethernet0
Sep 07 14:30:49: vcpu-0| sz=3108832
Sep 07 14:30:49: vcpu-0| vmm32 initialized: Releasebuild-108231. cflags: 0x02000002.01803000.00000054
Sep 07 14:30:50: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0x0) and 0xec000000(0x0)
Sep 07 14:30:50: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:30:50: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:30:50: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:30:50: vcpu-0| SVGA: Registering IOSpace at 0x1060 (0x0)
Sep 07 14:30:50: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:30:51: vcpu-0| DISKUTIL: scsi0:0 : geometry=1305/255/63
Sep 07 14:30:51: vcpu-0| DISKUTIL: scsi0:1 : geometry=39162/255/63
Sep 07 14:30:52: vcpu-0| BIOS-UUID is 56 4d a7 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7
Sep 07 14:30:53: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 07 14:30:53: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 07 14:30:53: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 07 14:30:53: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 07 14:30:53: vcpu-0| Unknown int 10h func 0x2000
Sep 07 14:31:01: mks| Connecting to window system.
Sep 07 14:31:01: mks| XINFO X fd is 41
Sep 07 14:31:01: mks| XINFO depth 24 bpp 32 class 4
Sep 07 14:31:01: mks| XINFO WARNING: XF86MISC version 0.9
Sep 07 14:31:01: mks| VT redirected kernel output to /dev/tty1
Sep 07 14:31:01: mks| rasterops MMXEXT accelerations enabled
Sep 07 14:31:01: mks| XINFO unsupported XF86VidMode version: 2.2
Sep 07 14:31:01: mks| XINFO XFree86 VidMode 0: 1024x768 flags: 0x0
Sep 07 14:31:01: mks| XINFO XFree86 VidMode 1: 800x600 flags: 0x0
Sep 07 14:31:01: mks| XINFO XFree86 VidMode 2: 640x480 flags: 0x0
Sep 07 14:31:01: mks| KHBKL: Unable to parse keystring at: ''
Sep 07 14:31:01: mks| Ignoring update request in VGA_Expose (mode change pending).
Sep 07 14:31:02: vcpu-0| SVGA: Unregistering IOSpace at 0x1060 (0x1060)
Sep 07 14:31:02: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:31:02: vcpu-0| SVGA: Registering IOSpace at 0x1060 (0x0)
Sep 07 14:31:02: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:31:02: mks| Ignoring update request in VGA_Expose (mode change pending).
Sep 07 14:31:02: mks| Ignoring update request in VGA_Expose (mode change pending).
Sep 07 14:31:02: vcpu-0| CDROM: Mode Sense for Unsupported Page 0x1B
Sep 07 14:31:03: vcpu-0| SCSI-DEV0:0: Unsupported command READ BUFFER issued. --ok
Sep 07 14:31:03: vcpu-0| SCSI-DEV0:0: Unsupported command READ BUFFER issued. --ok
Sep 07 14:31:03: vcpu-0| SCSI DEVICE (scsi0:0): INQUIRY request with EVDP set
Sep 07 14:31:03: vcpu-0| SCSI-DEV0:1: Unsupported command READ BUFFER issued. --ok
Sep 07 14:31:03: vcpu-0| SCSI-DEV0:1: Unsupported command READ BUFFER issued. --ok
Sep 07 14:31:03: vcpu-0| SCSI DEVICE (scsi0:1): INQUIRY request with EVDP set
Sep 07 14:31:03: vcpu-0| SCSI DEVICE (scsi0:0): MODE SENSE(6) for unsupported page 0x1c
Sep 07 14:31:03: vcpu-0| SCSI DEVICE (scsi0:1): MODE SENSE(6) for unsupported page 0x1c
Sep 07 14:31:09: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Sep 07 14:31:09: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Sep 07 14:31:09: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Sep 07 14:31:09: vcpu-0| VNET: Notification enabled for Ethernet0
Sep 07 14:31:09: vcpu-0| The guest OS tried to update the CPU's microcode.
Sep 07 14:31:09: vcpu-0| Since a newer microcode version exists, please consider updating the microcode on your host.
Sep 07 14:31:09: vcpu-0| SVGA: Unregistering IOSpace at 0x1060 (0x1060)
Sep 07 14:31:09: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:31:09: vcpu-0| SVGA: Registering IOSpace at 0x1060 (0x0)
Sep 07 14:31:09: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:31:09: vcpu-0| Guest OS = 0x5016
Sep 07 14:31:20: mks| SVGA: Using extended FIFO: Caps 0x00000007, Flags 0x00000000
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 0
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 30
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 31
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 32
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 33
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 35
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 36
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 38
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 45
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 46
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 47
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 259
Sep 07 14:31:48: vcpu-0| MKS Backdoor get pointer: first time, notify tools are running
Sep 07 14:31:48: vcpu-0| GuestRpc: Channel 0, registration number 1, guest application toolbox.
Sep 07 14:31:48: vcpu-0| TOOLS Reducing idleLoopSpinUS to 500us
Sep 07 14:31:48: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 07 14:31:48: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 07 14:31:48: vcpu-0| Guest: toolbox: Version: build-44356
Sep 07 14:31:48: vcpu-0| TOOLS setting the tools version to '6532'
Sep 07 14:31:48: vcpu-0| TOOLS unified loop capability requested by 'toolbox'; now sending options via TCLO
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.498 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.626 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.626 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.642 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.640 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command READ(10) took 1.427 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command READ(10) took 1.417 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.698 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.698 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command READ(10) took 1.204 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command READ(10) took 1.450 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.770 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.455 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.790 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.742 seconds (ok)
... Mit dieser Meldung quillt das Logfiel voll. Einige ... deuten im Folgenden die Wiederholung der letzten Meldung an
Sep 07 14:32:03: vmx| SCSI0:0: Command WRITE(10) took 1.060 seconds (ok)
Sep 07 14:32:03: vmx| SCSI0:0: Command WRITE(10) took 1.081 seconds (ok)
Sep 07 14:32:03: vmx| SCSI0:0: Command WRITE(10) took 1.070 seconds (ok)
Sep 07 14:33:18: mks| MKS lock got type None
Sep 07 14:33:18: vcpu-0| VMMouse: CMD Read ID
Sep 07 14:33:18: vcpu-0| MKS switching absolute mouse on
Sep 07 14:33:21: mks| MKS lost grab
Sep 07 14:36:39: mks| MKS lost grab
Sep 07 14:40:28: mks| Detaching from window system.
Sep 07 14:40:28: mks| MKS remote display status changed, enabling remoteoptimizations
Sep 08 02:44:00: vcpu-0| DISKLIB-LIB :numIOs = 50000 numMergedIOs = 6780 numSplitIOs = 1217
Sep 08 08:28:43: vmx| DISKLIB-LIB :numIOs = 50000 numMergedIOs = 0 numSplitIOs = 0
Sep 08 14:06:00: vmx| DISKLIB-LIB :numIOs = 100000 numMergedIOs = 12494 numSplitIOs = 2270
Sep 08 14:06:05: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO
Sep 08 14:06:05: vcpu-0| GuestRpc: Channel 1, registration number 1, guest application toolbox-dnd.
Sep 08 14:06:05: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 08 14:06:05: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 08 14:10:51: vmx| SCSI0:1: Command READ(10) took 1.877 seconds (ok)
Sep 08 14:11:26: vmx| SCSI0:0: Command WRITE(10) took 1.474 seconds (ok)
Sep 08 14:30:42: vmx| LICENSE using: '/etc/vmware/license.vs.1.0-00'
Sep 08 16:31:36: vcpu-0| GuestRpc: Channel 1 reinitialized.
Sep 08 22:04:10: vmx| SCSI0:0: Command WRITE(10) took 1.099 seconds (ok)
Sep 08 22:04:10: vmx| SCSI0:0: Command WRITE(10) took 1.117 seconds (ok)
Sep 08 22:04:14: vmx| SCSI0:0: Command WRITE(10) took 1.069 seconds (ok)
Sep 08 22:04:30: vmx| SCSI0:0: Command WRITE(10) took 1.158 seconds (ok)
Sep 08 22:04:32: vmx| SCSI0:0: Command WRITE(10) took 1.357 seconds (ok)
Sep 08 22:04:32: vmx| SCSI0:0: Command WRITE(10) took 1.307 seconds (ok)
......
Sep 08 23:30:39: vmx| SCSI0:0: Command WRITE(10) took 1.059 seconds (ok)
Sep 08 23:30:41: vmx| SCSI0:0: Command WRITE(10) took 1.003 seconds (ok)
Sep 08 23:30:43: vmx| SCSI0:0: Command WRITE(10) took 1.075 seconds (ok)
Sep 08 23:30:43: vmx| SCSI0:0: Command WRITE(10) took 1.157 seconds (ok)
Sep 08 23:30:43: vmx| SCSI0:0: Command WRITE(10) took 1.010 seconds (ok)
Sep 09 02:25:35: vmx| DISKLIB-LIB :numIOs = 150000 numMergedIOs = 20916 numSplitIOs = 3711
Sep 09 02:26:10: vcpu-0| DISKLIB-LIB :numIOs = 100000 numMergedIOs = 0 numSplitIOs = 0
Sep 09 09:05:01: vmx| SCSI0:1: Command WRITE(10) took 1.263 seconds (ok)
Sep 09 10:06:59: vmx| DISKLIB-LIB :numIOs = 150000 numMergedIOs = 0 numSplitIOs = 0
Sep 09 12:31:00: vmx| DISKLIB-LIB :numIOs = 200000 numMergedIOs = 26047 numSplitIOs = 4519
Sep 09 14:30:42: vmx| LICENSE using: '/etc/vmware/license.vs.1.0-00'
Sep 10 01:03:32: vmx| SCSI0:0: Command WRITE(10) took 1.046 seconds (ok)
Sep 10 01:03:33: vmx| SCSI0:0: Command WRITE(10) took 1.019 seconds (ok)
........
Sep 10 01:03:33: vmx| SCSI0:0: Command WRITE(10) took 1.316 seconds (ok)
Sep 10 01:03:33: vmx| SCSI0:0: Command WRITE(10) took 1.321 seconds (ok)
Sep 10 04:06:09: vcpu-0| DISKLIB-LIB :numIOs = 250000 numMergedIOs = 34394 numSplitIOs = 5241
Sep 10 10:51:29: vcpu-0| DISKLIB-LIB :numIOs = 200000 numMergedIOs = 0 numSplitIOs = 0
Sep 10 11:11:51: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO
Sep 10 11:11:51: vcpu-0| GuestRpc: Channel 1, registration number 1, guest application toolbox-dnd.
Sep 10 11:11:51: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 10 11:11:51: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 10 11:22:45: vmx| SCSI0:1: Command WRITE(10) took 1.452 seconds (ok)
Sep 10 11:22:45: vmx| SCSI0:1: Command WRITE(10) took 1.438 seconds (ok)
.....
Sep 10 11:23:27: vmx| SCSI0:0: Command WRITE(10) took 2.314 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.183 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:0: Command READ(10) took 1.426 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.168 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.155 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command READ(10) took 1.877 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.168 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.174 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.130 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.141 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.139 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.135 seconds (ok)
Sep 10 11:23:31: vmx| SCSI0:1: Command READ(10) took 1.116 seconds (ok)
Sep 10 11:23:31: vmx| SCSI0:1: Command WRITE(10) took 2.106 seconds (ok)
Sep 10 11:23:32: vmx| SCSI0:1: Command WRITE(10) took 2.244 seconds (ok)
...
Sep 10 11:25:02: vmx| SCSI0:0: Command WRITE(10) took 1.536 seconds (ok)
Sep 10 11:25:02: vmx| SCSI0:0: Command READ(10) took 1.286 seconds (ok)
Sep 10 11:25:02: vmx| SCSI0:0: Command READ(10) took 1.293 seconds (ok)
Sep 10 11:25:02: vmx| SCSI0:1: Command WRITE(10) took 2.229 seconds (ok)
Sep 10 11:25:03: vmx| SCSI0:1: Command WRITE(10) took 2.334 seconds (ok)
...
ep 10 11:28:19: vmx| SCSI0:1: Command READ(10) took 1.106 seconds (ok)
Sep 10 11:28:19: vmx| SCSI0:1: Command WRITE(10) took 1.000 seconds (ok)
Sep 10 11:28:20: vmx| DISKLIB-LIB :numIOs = 250000 numMergedIOs = 0 numSplitIOs = 0
Sep 10 11:28:24: vmx| SCSI0:1: Command WRITE(10) took 1.053 seconds (ok)
Sep 10 11:28:25: vmx| SCSI0:0: Command WRITE(10) took 2.340 seconds (ok)
...
Sep 10 11:32:03: vmx| SCSI0:1: Command WRITE(10) took 2.721 seconds (ok)
Sep 10 11:32:03: vmx| SCSI0:1: Command WRITE(10) took 2.818 seconds (ok)
Sep 10 11:39:16: vmx| DISKLIB-LIB :numIOs = 300000 numMergedIOs = 39595 numSplitIOs = 6452
Sep 10 11:44:07: vmx| SCSI0:1: Command WRITE(10) took 2.694 seconds (ok)
Sep 10 11:44:09: vmx| SCSI0:1: Command WRITE(10) took 4.413 seconds (ok)
...
Sep 10 11:51:44: vmx| SCSI0:1: Command WRITE(10) took 1.325 seconds (ok)
Sep 10 11:51:45: vmx| SCSI0:1: Command WRITE(10) took 1.341 seconds (ok)
Sep 10 11:51:45: vmx| SCSI0:1: Command WRITE(10) took 1.330 seconds (ok)
Sep 10 11:51:45: vmx| SCSI0:1: Command WRITE(10) took 1.330 seconds (ok)
Sep 10 11:56:40: vmx| DISKLIB-LIB :numIOs = 350000 numMergedIOs = 0 numSplitIOs = 0
Sep 10 11:56:57: vmx| SCSI0:1: Command READ(10) took 1.115 seconds (ok)
Sep 10 11:56:57: vmx| SCSI0:1: Command READ(10) took 1.115 seconds (ok)
Sep 10 11:56:57: vmx| SCSI0:1: Command READ(10) took 1.143 seconds (ok)
Sep 10 11:57:18: vmx| SCSI0:0: Command WRITE(10) took 1.025 seconds (ok)
Sep 10 12:20:49: vcpu-0| GuestRpc: Channel 1 reinitialized.
Hier scheint der Gast gecrasht zu sein. Hat jemand eine Idee?
Ein Win2003 Gast produziert sändig diese Fehlermeldung und friert nach einigen Tagen ein.
Host: Debian Etch 4
kernel: 2.6.18-6
Vmware Server: 1.0.7
Des Gast reagiert nicht mehr und lässt sich auch nicht mit vmware-cmd ... stop beenden. Nach einen kill -9 [Pozess-ID des Gastes] bekomme ich beim Neustart die Fehlermeldung, dass sich der Gast nicht mehr mit dem vmx-Prozess verbinden kann. In der Prozessliste hängen nach dem Kill auch noch Defunct-Prozesse von dem VMware-Gast, die ich aber nicht killen kann. Bevor ich die Logs und vmx Poste eine Frage:
Ist es möglich, so einen toten Prozess zu beenden, ohne den Mutterprozess (vmware-cmd) zu stoppen? Das würde sicher auch schon weiterhelfen.
An anderer Stelle habe ich aus einem Posting aus 2004 gelesen, dass Hyperthreadding ein Problem sein kann. Gilt das auch heute noch? Kann ich von der Linux-Kommandozeile rausbekommen, ob Hyperthreading aktiv ist?
Es handelt sich bei dem Gast um einen Fileserver, der sich mit drei anderen Win2003-Gästen einen Host mit 8 Xeons und 16GB RAM teilt. VMware-tools sind installatiert. Das System arbeite auf einem RAID. iostat zeigt keine beängstigende Festplattenaktivität. Alles ist im Bereich von maximal einigen MB die Sekunde. Dennoch sieht es aus, als könnte der Gast seine Daten nicht schnell genug wegschreiben.
Hat da vielleicht jemand eine Idee?
root@vm-host:/mnt/vmware/Fileserver# cat Fileserver.vmx
#!/usr/bin/vmware
config.version = "8"
virtualHW.version = "4"
scsi0.present = "TRUE"
scsi0.virtualDev = "lsilogic"
memsize = "512"
scsi0:0.present = "TRUE"
scsi0:0.fileName = "Fileserver.vmdk"
scsi0:0.writeThrough = "TRUE"
ide1:0.present = "TRUE"
ide1:0.fileName = "/mnt/images/w2k3se-R2.disk2.iso"
ide1:0.deviceType = "cdrom-image"
floppy0.startConnected = "FALSE"
floppy0.fileName = "/dev/fd0"
Ethernet0.present = "TRUE"
displayName = "file-srv (Fileserver)"
guestOS = "winnetenterprise"
priority.grabbed = "normal"
priority.ungrabbed = "normal"
powerType.powerOff = "hard"
powerType.powerOn = "hard"
powerType.suspend = "hard"
powerType.reset = "hard"
scsi0:0.redo = ""
ide1:0.startConnected = "FALSE"
ethernet0.addressType = "generated"
uuid.location = "56 4d a5 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7"
uuid.bios = "56 4d a7 db 7d 61 3d ba-96 50 d7 84 41 74 51 e7"
ethernet0.generatedAddress = "00:0c:29:74:51:e7"
ethernet0.generatedAddressOffset = "0"
tools.syncTime = "FALSE"
nvram = "Fileserver.nvram"
scsi0:1.present = "TRUE"
scsi0:1.fileName = "FileServer Partition D.vmdk"
scsi0:1.redo = ""
autostart = "poweron"
autostop = "softpoweroff"
checkpoint.vmState = "Fileserver.vmss"
Logfile:
Sep 07 14:30:48: vmx| Log for VMware Server pid=4068 version=1.0.7 build=build-108231 option=Release
Sep 07 14:30:48: vmx| Command line: "/usr/lib/vmware/bin/vmware-vmx" "-C" "-@" """" "/mnt/vmware/Fileserver/Fileserver.v
mx"
Sep 07 14:30:48: vmx| vmxvmdb: Index name being generated from config file
Sep 07 14:30:48: vmx| VMXVmdbConnectServerd - Trying to discover serverd
Sep 07 14:30:48: vmx| MStat: Creating Stat system.cpuusage
Sep 07 14:30:48: vmx| MStat: Creating Stat system.ram
Sep 07 14:30:48: vmx| MStat: Creating Stat system.uptime
Sep 07 14:30:48: vmx| MStat: Creating Stat system.load
Sep 07 14:30:48: vmx| cpuids[0].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[1].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[2].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[3].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[4].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[5].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[6].id81.ecx = 0x1
Sep 07 14:30:48: vmx| cpuids[7].id81.ecx = 0x1
Sep 07 14:30:48: vmx| pcpu #0 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #0 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x20800
Sep 07 14:30:48: vmx| pcpu #0 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #1 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #1 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x1020800
Sep 07 14:30:48: vmx| pcpu #1 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #2 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #2 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x6020800
Sep 07 14:30:48: vmx| pcpu #2 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #3 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #3 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x7020800
Sep 07 14:30:48: vmx| pcpu #3 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #4 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #4 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x10020800
Sep 07 14:30:48: vmx| pcpu #4 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #5 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #5 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x11020800
Sep 07 14:30:48: vmx| pcpu #5 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #6 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #6 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x16020800
Sep 07 14:30:48: vmx| pcpu #6 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| pcpu #7 CPUID numEntries=5 GenuntelineI
Sep 07 14:30:48: vmx| pcpu #7 CPUID version=0xf49 id1.edx=0xbfebfbff id1.ecx=0x659d id1.ebx=0x17020800
Sep 07 14:30:48: vmx| pcpu #7 CPUID id80.eax=80000008 id81.edx=0x20000800 id81.ecx=0x1
Sep 07 14:30:48: vmx| CPUID id1.edx: 0xbfebfbff id1.ecx: 0x659d id81.edx: 0x20000800 id81.ecx: 0x1
Sep 07 14:30:48: vmx| CPUID id88.ecx: 0 id88.edx: 0
Sep 07 14:30:48: vmx| Setup symlink /var/run/vmware/%2Fmnt%2Fvmware%2FFileserver%2FFileserver%2Evmx -> /var/run/vmware/a
dmin/4068
Sep 07 14:30:48: vmx| ACL_InitCapabilities: here 1 (bug 63252)
Sep 07 14:30:48: vmx| changing directory to /mnt/vmware/Fileserver/.
Sep 07 14:30:48: vmx| Config file: /mnt/vmware/Fileserver/Fileserver.vmx
Sep 07 14:30:48: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:48: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| CnxAcceptConnection: Could not receive fd on 27: invalid control message
Sep 07 14:30:49: vmx| Failed to get IPC connection
Sep 07 14:30:49: vmx| Read from FIFO 32 -- connecting to serverd...
Sep 07 14:30:49: vmx| VMDB: Connected to serverd
Sep 07 14:30:49: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset
Sep 07 14:30:49: vmx| PowerOn
Sep 07 14:30:49: vmx| Host ACPI: SRAT @ 0xcff76000
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x00, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x01, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x06, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x07, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x10, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x11, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x16, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host ACPI: SRAT: LAPIC ID 0x17, proximity domain low 0x00
Sep 07 14:30:49: vmx| Host: CPU #0 LAPIC ID 0x00, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #1 LAPIC ID 0x01, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #2 LAPIC ID 0x06, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #3 LAPIC ID 0x07, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #4 LAPIC ID 0x10, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #5 LAPIC ID 0x11, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #6 LAPIC ID 0x16, node 0x00
Sep 07 14:30:49: vmx| Host: CPU #7 LAPIC ID 0x17, node 0x00
Sep 07 14:30:49: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| HOST sysname Linux, nodename s002vm01, release 2.6.18-6-amd64, version #1 SMP Mon Jun 16 22:30:01
UTC 2008, machine x86_64, SMP, hz=250
Sep 07 14:30:49: vmx| DICT --- USER PREFERENCES
Sep 07 14:30:49: vmx| DICT pref.grabOnKeyPress = FALSE
Sep 07 14:30:49: vmx| DICT pref.autoFitFullScreen = fitHostToGuest
Sep 07 14:30:49: vmx| DICT pref.view.navBar.type = favorites
Sep 07 14:30:49: vmx| DICT pref.mruDest0.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.mruDest0.destString = 192.168.2.3
Sep 07 14:30:49: vmx| DICT pref.mruDest0.user = admin
Sep 07 14:30:49: vmx| DICT pref.mruDest1.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.mruDest1.destString = s002vm03
Sep 07 14:30:49: vmx| DICT pref.mruDest1.user = admin
Sep 07 14:30:49: vmx| DICT pref.mruDest2.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest2.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest2.user =
Sep 07 14:30:49: vmx| DICT pref.mruDest3.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest3.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest3.user =
Sep 07 14:30:49: vmx| DICT pref.mruDest4.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest4.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest4.user =
Sep 07 14:30:49: vmx| DICT pref.mruDest5.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest5.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest5.user =
Sep 07 14:30:49: vmx| DICT pref.mruDest6.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest6.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest6.user =
Sep 07 14:30:49: vmx| DICT pref.mruDest7.present = FALSE
Sep 07 14:30:49: vmx| DICT pref.mruDest7.destString =
Sep 07 14:30:49: vmx| DICT pref.mruDest7.user =
Sep 07 14:30:49: vmx| DICT webUpdate.checkPeriod = weekly
Sep 07 14:30:49: vmx| DICT webUpdate.checkLast = 1220794210
Sep 07 14:30:49: vmx| DICT webUpdate.lastCheck.status = done_none
Sep 07 14:30:49: vmx| DICT pref.console.openedObj0.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj0.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj0.path = /vm/#9f638f20d08a1290/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj0.file = /mnt/vmware/DController/DController.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj0.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj.maxNum = 6
Sep 07 14:30:49: vmx| DICT pref.console.openedObj1.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj1.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj1.path = /vm/#47f27aabed7d88e6/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj1.file = /mnt/vmware/s002hy07/s002hy07.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj1.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj2.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj2.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj2.path = /vm/#9268f6a5cccd0b40/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj2.file = /mnt/vmware/S002FS02/S002FS02.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj2.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj3.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj3.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj3.path = /vm/#59ad2050f329391e/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj3.file = /mnt/vmware/citrix_webserver/citrix_webserver.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj3.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj4.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj4.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj4.path = /vm/#d58734eea9603e24/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj4.file = /mnt/vmware/Adminstation/Adminstation.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj4.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj5.present = TRUE
Sep 07 14:30:49: vmx| DICT pref.console.openedObj5.type = vm
Sep 07 14:30:49: vmx| DICT pref.console.openedObj5.path = /vm/#632b892ab9692c60/
Sep 07 14:30:49: vmx| DICT pref.console.openedObj5.file = /mnt/vmware/s002hy08/s002hy08.vmx
Sep 07 14:30:49: vmx| DICT pref.console.openedObj5.dest = /host2/#85931facbf8ad3b5/
Sep 07 14:30:49: vmx| DICT pref.view.navBar = TRUE
Sep 07 14:30:49: vmx| DICT pref.kvm = FALSE
Sep 07 14:30:49: vmx| DICT pref.cnxType = local
Sep 07 14:30:49: vmx| DICT pref.placement.left = 224
Sep 07 14:30:49: vmx| DICT pref.placement.top = 163
Sep 07 14:30:49: vmx| DICT pref.placement.right = 1024
Sep 07 14:30:49: vmx| DICT pref.placement.bottom = 763
Sep 07 14:30:49: vmx| DICT --- USER DEFAULTS
Sep 07 14:30:49: vmx| DICT --- HOST DEFAULTS
Sep 07 14:30:49: vmx| DICT serverd.init.fullpath = /usr/lib/vmware/serverd/init.pl
Sep 07 14:30:49: vmx| DICT authd.client.port = 902
Sep 07 14:30:49: vmx| DICT control.fullpath = /usr/bin/vmware-cmd
Sep 07 14:30:49: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd
Sep 07 14:30:49: vmx| DICT loop.fullpath = /usr/bin/vmware-loop
Sep 07 14:30:49: vmx| DICT libdir = /usr/lib/vmware
Sep 07 14:30:49: vmx| DICT vmware.fullpath = /usr/bin/vmware
Sep 07 14:30:49: vmx| DICT vmdir = /mnt/vmware
Sep 07 14:30:49: vmx| DICT dhcpd.fullpath = /usr/bin/vmnet-dhcpd
Sep 07 14:30:49: vmx| DICT serverd.fullpath = /usr/sbin/vmware-serverd
Sep 07 14:30:49: vmx| DICT datastore.name = local
Sep 07 14:30:49: vmx| DICT datastore.localpath = /mnt/vmware/
Sep 07 14:30:49: vmx| DICT --- SITE DEFAULTS
Sep 07 14:30:49: vmx| DICT tag.help = introduction.htm
Sep 07 14:30:49: vmx| DICT tag.configurationEditor = config_editor_newvm.htm
Sep 07 14:30:49: vmx| DICT tag.ideConfig = devices_virtualdrive.htm
Sep 07 14:30:49: vmx| DICT tag.floppyConfig = devices_floppy.htm
Sep 07 14:30:49: vmx| DICT tag.mouseConfig = devices_mouse.htm
Sep 07 14:30:49: vmx| DICT tag.netConfig = devices_netadapter.htm
Sep 07 14:30:49: vmx| DICT tag.parallelConfig = devices_parallel.htm
Sep 07 14:30:49: vmx| DICT tag.serialConfig = devices_serial.htm
Sep 07 14:30:49: vmx| DICT tag.soundConfig = devices_sound.htm
Sep 07 14:30:49: vmx| DICT tag.memConfig = configvm_memory.htm
Sep 07 14:30:49: vmx| DICT tag.miscConfig = configvm.htm
Sep 07 14:30:49: vmx| DICT tag.usbConfig = devices_usb.htm
Sep 07 14:30:49: vmx| DICT tag.displayConfig = configvm_display-problems.htm
Sep 07 14:30:49: vmx| DICT tag.tools = vmtools.htm
Sep 07 14:30:49: vmx| DICT --- COMMAND LINE
Sep 07 14:30:49: vmx| DICT gui.managementUI = TRUE
Sep 07 14:30:49: vmx| DICT --- CONFIGURATION
Sep 07 14:30:49: vmx| DICT config.version = 8
Sep 07 14:30:49: vmx| DICT virtualHW.version = 4
Sep 07 14:30:49: vmx| DICT scsi0.present = TRUE
Sep 07 14:30:49: vmx| DICT scsi0.virtualDev = lsilogic
Sep 07 14:30:49: vmx| DICT memsize = 512
Sep 07 14:30:49: vmx| DICT scsi0:0.present = TRUE
Sep 07 14:30:49: vmx| DICT scsi0:0.fileName = Fileserver.vmdk
Sep 07 14:30:49: vmx| DICT scsi0:0.writeThrough = TRUE
Sep 07 14:30:49: vmx| DICT ide1:0.present = TRUE
Sep 07 14:30:49: vmx| DICT ide1:0.fileName = /mnt/images/w2k3se-R2.disk2.iso
Sep 07 14:30:49: vmx| DICT ide1:0.deviceType = cdrom-image
Sep 07 14:30:49: vmx| DICT floppy0.startConnected = FALSE
Sep 07 14:30:49: vmx| DICT floppy0.fileName = /dev/fd0
Sep 07 14:30:49: vmx| DICT Ethernet0.present = TRUE
Sep 07 14:30:49: vmx| DICT displayName = S002FS01 (Fileserver)
Sep 07 14:30:49: vmx| DICT guestOS = winnetenterprise
Sep 07 14:30:49: vmx| DICT priority.grabbed = normal
Sep 07 14:30:49: vmx| DICT priority.ungrabbed = normal
Sep 07 14:30:49: vmx| DICT powerType.powerOff = hard
Sep 07 14:30:49: vmx| DICT powerType.powerOn = hard
Sep 07 14:30:49: vmx| DICT powerType.suspend = hard
Sep 07 14:30:49: vmx| DICT powerType.reset = hard
Sep 07 14:30:49: vmx| DICT scsi0:0.redo =
Sep 07 14:30:49: vmx| DICT ide1:0.startConnected = FALSE
Sep 07 14:30:49: vmx| DICT ethernet0.addressType = generated
Sep 07 14:30:49: vmx| DICT uuid.location = 56 4d a7 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7
Sep 07 14:30:49: vmx| DICT uuid.bios = 56 4d a7 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7
Sep 07 14:30:49: vmx| DICT ethernet0.generatedAddress = 00:0c:29:74:51:e7
Sep 07 14:30:49: vmx| DICT ethernet0.generatedAddressOffset = 0
Sep 07 14:30:49: vmx| DICT tools.syncTime = FALSE
Sep 07 14:30:49: vmx| DICT nvram = Fileserver.nvram
Sep 07 14:30:49: vmx| DICT scsi0:1.present = TRUE
Sep 07 14:30:49: vmx| DICT scsi0:1.fileName = FileServer Partition D.vmdk
Sep 07 14:30:49: vmx| DICT scsi0:1.redo =
Sep 07 14:30:49: vmx| DICT autostart = poweron
Sep 07 14:30:49: vmx| DICT autostop = softpoweroff
Sep 07 14:30:49: vmx| DICT checkpoint.vmState = Fileserver.vmss
Sep 07 14:30:49: vmx| DICT --- USER DEFAULTS
Sep 07 14:30:49: vmx| DICT --- HOST DEFAULTS
Sep 07 14:30:49: vmx| DICT serverd.init.fullpath = /usr/lib/vmware/serverd/init.pl
Sep 07 14:30:49: vmx| DICT authd.client.port = 902
Sep 07 14:30:49: vmx| DICT control.fullpath = /usr/bin/vmware-cmd
Sep 07 14:30:49: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd
Sep 07 14:30:49: vmx| DICT loop.fullpath = /usr/bin/vmware-loop
Sep 07 14:30:49: vmx| DICT libdir = /usr/lib/vmware
Sep 07 14:30:49: vmx| DICT vmware.fullpath = /usr/bin/vmware
Sep 07 14:30:49: vmx| DICT vmdir = /mnt/vmware
Sep 07 14:30:49: vmx| DICT dhcpd.fullpath = /usr/bin/vmnet-dhcpd
Sep 07 14:30:49: vmx| DICT serverd.fullpath = /usr/sbin/vmware-serverd
Sep 07 14:30:49: vmx| DICT datastore.name = local
Sep 07 14:30:49: vmx| DICT datastore.localpath = /mnt/vmware/
Sep 07 14:30:49: vmx| DICT --- SITE DEFAULTS
Sep 07 14:30:49: vmx| DICT tag.help = introduction.htm
Sep 07 14:30:49: vmx| DICT tag.configurationEditor = config_editor_newvm.htm
Sep 07 14:30:49: vmx| DICT tag.ideConfig = devices_virtualdrive.htm
Sep 07 14:30:49: vmx| DICT tag.floppyConfig = devices_floppy.htm
Sep 07 14:30:49: vmx| DICT tag.mouseConfig = devices_mouse.htm
Sep 07 14:30:49: vmx| DICT tag.netConfig = devices_netadapter.htm
Sep 07 14:30:49: vmx| DICT tag.parallelConfig = devices_parallel.htm
Sep 07 14:30:49: vmx| DICT tag.serialConfig = devices_serial.htm
Sep 07 14:30:49: vmx| DICT tag.soundConfig = devices_sound.htm
Sep 07 14:30:49: vmx| DICT tag.memConfig = configvm_memory.htm
Sep 07 14:30:49: vmx| DICT tag.miscConfig = configvm.htm
Sep 07 14:30:49: vmx| DICT tag.usbConfig = devices_usb.htm
Sep 07 14:30:49: vmx| DICT tag.displayConfig = configvm_display-problems.htm
Sep 07 14:30:49: vmx| DICT tag.tools = vmtools.htm
Sep 07 14:30:49: vmx| DICT --- GLOBAL SETTINGS
Sep 07 14:30:49: vmx| Msg_Hint: msg.hostlinux.othervms (not shown)
Sep 07 14:30:49: vmx| WSSCAN: reserved mem (in MB) min=32 max=15968 recommended=15968
Sep 07 14:30:49: vmx| hostMem=16064 maxAllowedAll=-1 maxAllowedVM=3600
Sep 07 14:30:49: vmx| totOverhead=16
Sep 07 14:30:49: vmx| WSSCAN: used rec mem (in MB) 15968
Sep 07 14:30:49: vmx| WSSCAN: Overhead 137457 paged 6047 nonpaged 4096 maxFBSize
Sep 07 14:30:49: vmx| WSSCAN 4 510473 4087808 -1 4087808 -1 50 304718
Sep 07 14:30:49: vmx| LICENSE using: '/etc/vmware/license.vs.1.0-00'
Sep 07 14:30:49: vmx| STATDECLGROUP stats Root "" null
Sep 07 14:30:49: vmx| Host CPUID features: version 0xf48 id1.edx 0xbfebfbff id1.ecx 0x659d id81.edx 0x20000800 id81.ecx
0x1
Sep 07 14:30:49: vmx| CPU.cpuFeatures = 0x39b1ff30
Sep 07 14:30:49: vmx| CPUID after masking: version 0xf48 id1.edx 0xfebbbff id1.ecx 0x15 id81.edx 0x800 id81.ecx 0x0 id88
.ecx 0x0
Sep 07 14:30:49: vmx| CPU.cpuFeatures = 0x18b1fe00
Sep 07 14:30:49: vmx| APIC: Local APIC at 0xfee00000
Sep 07 14:30:49: vmx| KHZEstimate 3169412
Sep 07 14:30:49: vmx| MHZEstimate 3169
Sep 07 14:30:49: vmx| NumVCPUs 1
Sep 07 14:30:49: vmx| UUID: location-UUID is 56 4d a7 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7
Sep 07 14:30:49: vmx| MM: Using partialmap, 131072 pages AC 0 CE 1 TM 0 DOHU 0
Sep 07 14:30:49: vmx| UUID: canonical path is /mnt/vmware/Fileserver/Fileserver.vmx
Sep 07 14:30:49: vmx| UUID: location-UUID is 56 4d a7 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7
Sep 07 14:30:49: vmx| MM: using fileName /mnt/vmware/Fileserver/564da7da-7d61-3dba-9650-d784417451e7.vmem for paging
Sep 07 14:30:49: vmx| Msg_Reset:
Sep 07 14:30:49: vmx| ----------------------------------------
Sep 07 14:30:49: vmx| Opened paging file /mnt/vmware/Fileserver/564da7da-7d61-3dba-9650-d784417451e7.vmem
Sep 07 14:30:49: vmx| Mapped mainmem as pageable
Sep 07 14:30:49: vmx| MStat: Creating Stat vm.cpuusage
Sep 07 14:30:49: vmx| MStat: Creating Stat vm.ram
Sep 07 14:30:49: vmx| MStat: Creating Stat vm.uptime
Sep 07 14:30:49: vmx| Unable to find Fileserver.vmss. Setting vmState to NULL.
Sep 07 14:30:49: vmx| DISK: OPEN scsi0:0 '/mnt/vmware/Fileserver/Fileserver.vmdk' persistent R[(null)]
Sep 07 14:30:49: vmx| AIOGNRC: Starting 10 I/O threads.
Sep 07 14:30:49: vmx| DISKLIB-DSCPTR: Opened [0]: "Fileserver.vmdk" (0x2a)
Sep 07 14:30:49: vmx| DISKLIB-LINK : Opened '/mnt/vmware/Fileserver/Fileserver.vmdk' (0x2a): monolithicSparse, 20971520
sectors / 10240 Mb.
Sep 07 14:30:49: vmx| DISKLIB-LIB : Opened "/mnt/vmware/Fileserver/Fileserver.vmdk" (flags 0x2a).
Sep 07 14:30:49: vmx| DISK: OPEN '/mnt/vmware/Fileserver/Fileserver.vmdk' Geo (1305/255/63) BIOS Geo (1305/255/63) freeS
pace=35084Mb
Sep 07 14:30:49: vmx| DISK: OPEN scsi0:1 '/mnt/vmware/Fileserver/FileServer Partition D.vmdk' persistent R[(null)]
Sep 07 14:30:49: vmx| DISKLIB-DSCPTR: Opened [0]: "FileServer Partition D-flat.vmdk" 0 (0xa)
Sep 07 14:30:49: vmx| DISKLIB-LINK : Opened '/mnt/vmware/Fileserver/FileServer Partition D.vmdk' (0xa): monolithicFlat,
629145600 sectors / 307200 Mb.
Sep 07 14:30:49: vmx| DISKLIB-LIB : Opened "/mnt/vmware/Fileserver/FileServer Partition D.vmdk" (flags 0xa).
Sep 07 14:30:49: vmx| DISK: OPEN '/mnt/vmware/Fileserver/FileServer Partition D.vmdk' Geo (39162/255/63) BIOS Geo (39162
/255/63) freeSpace=35084Mb
Sep 07 14:30:49: vmx| TimeTracker host to guest rate conversion 1562429379446 @ 3169412000Hz -> 1562429379446 @ 31694120
00Hz
Sep 07 14:30:49: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0
Sep 07 14:30:49: vmx| MStat: Creating Stat ide1:0.bytesread
Sep 07 14:30:49: vmx| MStat: Creating Stat ide1:0.byteswritten
Sep 07 14:30:49: vmx| MStat: Creating Stat scsi0:0.bytesread
Sep 07 14:30:49: vmx| MStat: Creating Stat scsi0:0.byteswritten
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:0 : capacity=20971520
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:0 : geometry=1305/255/63
Sep 07 14:30:49: vmx| MStat: Creating Stat scsi0:1.bytesread
Sep 07 14:30:49: vmx| MStat: Creating Stat scsi0:1.byteswritten
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:1 : capacity=629145600
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:1 : geometry=39162/255/63
Sep 07 14:30:49: vmx| SCSI0: UNTAGGED commands will be converted to ORDER tags.
Sep 07 14:30:49: vmx| MStat: Creating Stat vm.heartbeat
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 07 14:30:49: vmx| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 07 14:30:49: vmx| TOOLS INSTALL initializing state to IDLE on power on.
Sep 07 14:30:49: vmx| VMMon_GetkHzEstimate: Calculated 3169226 kHz
Sep 07 14:30:49: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks
Sep 07 14:30:49: vmx| MStat: Creating Stat ethernet0.bytesread
Sep 07 14:30:49: vmx| MStat: Creating Stat ethernet0.byteswritten
Sep 07 14:30:49: vmx| Ethernet0 MAC Address: 00:0c:29:74:51:e7
Sep 07 14:30:49: vmx| VMXNET: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks, dontClusterSize is 128
Sep 07 14:30:49: vmx| E1000: checksum cycles/kB: C=936 asm=2904
Sep 07 14:30:49: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1
Sep 07 14:30:49: vmx| VMX setting maximum IPC write buffers to 0 packets, 0 bytes
Sep 07 14:30:49: mks| Async MKS thread is alive
Sep 07 14:30:49: vcpu-0| APIC: version = 0x14, max LVT = 5
Sep 07 14:30:49: vcpu-0| APIC: LDR = 0x2000000, DFR = 0xfffffff
Sep 07 14:30:49: vmx| Accepted new connection at 179 for thread servercontrol (0x84d1360)
Sep 07 14:30:49: vmx| VUINewControlConnection: before slow ACL gunk (bug 63252).
Sep 07 14:30:49: vmx| ACL_InitCapabilities: here 2 (bug 63252)
Sep 07 14:30:49: vmx| VUINewControlConnection: after slow ACL gunk (bug 63252).
Sep 07 14:30:49: vmx| VUI: A new VMControl client connected.
Sep 07 14:30:49: vmx| IPC version negotiation version: VMX returning 2.1 to servercontrol
Sep 07 14:30:49: vmx| IPC vmcontrol-temp version: VMX returning 11.4 to servercontrol that tried 11.4
Sep 07 14:30:49: vcpu-0| PShare: enabled 1, scanRate 32, checkRate 16
Sep 07 14:30:49: vcpu-0| guestCpuFeatures = 0x18b1fe00
Sep 07 14:30:49: vcpu-0| Init modules.
Sep 07 14:30:49: vcpu-0| CPU reset: hard
Sep 07 14:30:49: vmx| VNET: Notification enabled for Ethernet0
Sep 07 14:30:49: vcpu-0| sz=3108832
Sep 07 14:30:49: vcpu-0| vmm32 initialized: Releasebuild-108231. cflags: 0x02000002.01803000.00000054
Sep 07 14:30:50: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0x0) and 0xec000000(0x0)
Sep 07 14:30:50: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:30:50: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:30:50: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:30:50: vcpu-0| SVGA: Registering IOSpace at 0x1060 (0x0)
Sep 07 14:30:50: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:30:51: vcpu-0| DISKUTIL: scsi0:0 : geometry=1305/255/63
Sep 07 14:30:51: vcpu-0| DISKUTIL: scsi0:1 : geometry=39162/255/63
Sep 07 14:30:52: vcpu-0| BIOS-UUID is 56 4d a7 da 7d 61 3d ba-96 50 d7 84 41 74 51 e7
Sep 07 14:30:53: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 07 14:30:53: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 07 14:30:53: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 07 14:30:53: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 07 14:30:53: vcpu-0| Unknown int 10h func 0x2000
Sep 07 14:31:01: mks| Connecting to window system.
Sep 07 14:31:01: mks| XINFO X fd is 41
Sep 07 14:31:01: mks| XINFO depth 24 bpp 32 class 4
Sep 07 14:31:01: mks| XINFO WARNING: XF86MISC version 0.9
Sep 07 14:31:01: mks| VT redirected kernel output to /dev/tty1
Sep 07 14:31:01: mks| rasterops MMXEXT accelerations enabled
Sep 07 14:31:01: mks| XINFO unsupported XF86VidMode version: 2.2
Sep 07 14:31:01: mks| XINFO XFree86 VidMode 0: 1024x768 flags: 0x0
Sep 07 14:31:01: mks| XINFO XFree86 VidMode 1: 800x600 flags: 0x0
Sep 07 14:31:01: mks| XINFO XFree86 VidMode 2: 640x480 flags: 0x0
Sep 07 14:31:01: mks| KHBKL: Unable to parse keystring at: ''
Sep 07 14:31:01: mks| Ignoring update request in VGA_Expose (mode change pending).
Sep 07 14:31:02: vcpu-0| SVGA: Unregistering IOSpace at 0x1060 (0x1060)
Sep 07 14:31:02: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:31:02: vcpu-0| SVGA: Registering IOSpace at 0x1060 (0x0)
Sep 07 14:31:02: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:31:02: mks| Ignoring update request in VGA_Expose (mode change pending).
Sep 07 14:31:02: mks| Ignoring update request in VGA_Expose (mode change pending).
Sep 07 14:31:02: vcpu-0| CDROM: Mode Sense for Unsupported Page 0x1B
Sep 07 14:31:03: vcpu-0| SCSI-DEV0:0: Unsupported command READ BUFFER issued. --ok
Sep 07 14:31:03: vcpu-0| SCSI-DEV0:0: Unsupported command READ BUFFER issued. --ok
Sep 07 14:31:03: vcpu-0| SCSI DEVICE (scsi0:0): INQUIRY request with EVDP set
Sep 07 14:31:03: vcpu-0| SCSI-DEV0:1: Unsupported command READ BUFFER issued. --ok
Sep 07 14:31:03: vcpu-0| SCSI-DEV0:1: Unsupported command READ BUFFER issued. --ok
Sep 07 14:31:03: vcpu-0| SCSI DEVICE (scsi0:1): INQUIRY request with EVDP set
Sep 07 14:31:03: vcpu-0| SCSI DEVICE (scsi0:0): MODE SENSE(6) for unsupported page 0x1c
Sep 07 14:31:03: vcpu-0| SCSI DEVICE (scsi0:1): MODE SENSE(6) for unsupported page 0x1c
Sep 07 14:31:09: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Sep 07 14:31:09: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Sep 07 14:31:09: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Sep 07 14:31:09: vcpu-0| VNET: Notification enabled for Ethernet0
Sep 07 14:31:09: vcpu-0| The guest OS tried to update the CPU's microcode.
Sep 07 14:31:09: vcpu-0| Since a newer microcode version exists, please consider updating the microcode on your host.
Sep 07 14:31:09: vcpu-0| SVGA: Unregistering IOSpace at 0x1060 (0x1060)
Sep 07 14:31:09: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:31:09: vcpu-0| SVGA: Registering IOSpace at 0x1060 (0x0)
Sep 07 14:31:09: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Sep 07 14:31:09: vcpu-0| Guest OS = 0x5016
Sep 07 14:31:20: mks| SVGA: Using extended FIFO: Caps 0x00000007, Flags 0x00000000
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 0
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 30
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 31
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 32
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 33
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 35
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 36
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 38
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 45
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 46
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 47
Sep 07 14:31:31: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 2 start feature 259
Sep 07 14:31:48: vcpu-0| MKS Backdoor get pointer: first time, notify tools are running
Sep 07 14:31:48: vcpu-0| GuestRpc: Channel 0, registration number 1, guest application toolbox.
Sep 07 14:31:48: vcpu-0| TOOLS Reducing idleLoopSpinUS to 500us
Sep 07 14:31:48: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 07 14:31:48: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 07 14:31:48: vcpu-0| Guest: toolbox: Version: build-44356
Sep 07 14:31:48: vcpu-0| TOOLS setting the tools version to '6532'
Sep 07 14:31:48: vcpu-0| TOOLS unified loop capability requested by 'toolbox'; now sending options via TCLO
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.498 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.626 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.626 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.642 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.640 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command READ(10) took 1.427 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command READ(10) took 1.417 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.698 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.698 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command READ(10) took 1.204 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command READ(10) took 1.450 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.770 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.455 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.790 seconds (ok)
Sep 07 14:31:55: vmx| SCSI0:0: Command WRITE(10) took 1.742 seconds (ok)
... Mit dieser Meldung quillt das Logfiel voll. Einige ... deuten im Folgenden die Wiederholung der letzten Meldung an
Sep 07 14:32:03: vmx| SCSI0:0: Command WRITE(10) took 1.060 seconds (ok)
Sep 07 14:32:03: vmx| SCSI0:0: Command WRITE(10) took 1.081 seconds (ok)
Sep 07 14:32:03: vmx| SCSI0:0: Command WRITE(10) took 1.070 seconds (ok)
Sep 07 14:33:18: mks| MKS lock got type None
Sep 07 14:33:18: vcpu-0| VMMouse: CMD Read ID
Sep 07 14:33:18: vcpu-0| MKS switching absolute mouse on
Sep 07 14:33:21: mks| MKS lost grab
Sep 07 14:36:39: mks| MKS lost grab
Sep 07 14:40:28: mks| Detaching from window system.
Sep 07 14:40:28: mks| MKS remote display status changed, enabling remoteoptimizations
Sep 08 02:44:00: vcpu-0| DISKLIB-LIB :numIOs = 50000 numMergedIOs = 6780 numSplitIOs = 1217
Sep 08 08:28:43: vmx| DISKLIB-LIB :numIOs = 50000 numMergedIOs = 0 numSplitIOs = 0
Sep 08 14:06:00: vmx| DISKLIB-LIB :numIOs = 100000 numMergedIOs = 12494 numSplitIOs = 2270
Sep 08 14:06:05: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO
Sep 08 14:06:05: vcpu-0| GuestRpc: Channel 1, registration number 1, guest application toolbox-dnd.
Sep 08 14:06:05: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 08 14:06:05: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 08 14:10:51: vmx| SCSI0:1: Command READ(10) took 1.877 seconds (ok)
Sep 08 14:11:26: vmx| SCSI0:0: Command WRITE(10) took 1.474 seconds (ok)
Sep 08 14:30:42: vmx| LICENSE using: '/etc/vmware/license.vs.1.0-00'
Sep 08 16:31:36: vcpu-0| GuestRpc: Channel 1 reinitialized.
Sep 08 22:04:10: vmx| SCSI0:0: Command WRITE(10) took 1.099 seconds (ok)
Sep 08 22:04:10: vmx| SCSI0:0: Command WRITE(10) took 1.117 seconds (ok)
Sep 08 22:04:14: vmx| SCSI0:0: Command WRITE(10) took 1.069 seconds (ok)
Sep 08 22:04:30: vmx| SCSI0:0: Command WRITE(10) took 1.158 seconds (ok)
Sep 08 22:04:32: vmx| SCSI0:0: Command WRITE(10) took 1.357 seconds (ok)
Sep 08 22:04:32: vmx| SCSI0:0: Command WRITE(10) took 1.307 seconds (ok)
......
Sep 08 23:30:39: vmx| SCSI0:0: Command WRITE(10) took 1.059 seconds (ok)
Sep 08 23:30:41: vmx| SCSI0:0: Command WRITE(10) took 1.003 seconds (ok)
Sep 08 23:30:43: vmx| SCSI0:0: Command WRITE(10) took 1.075 seconds (ok)
Sep 08 23:30:43: vmx| SCSI0:0: Command WRITE(10) took 1.157 seconds (ok)
Sep 08 23:30:43: vmx| SCSI0:0: Command WRITE(10) took 1.010 seconds (ok)
Sep 09 02:25:35: vmx| DISKLIB-LIB :numIOs = 150000 numMergedIOs = 20916 numSplitIOs = 3711
Sep 09 02:26:10: vcpu-0| DISKLIB-LIB :numIOs = 100000 numMergedIOs = 0 numSplitIOs = 0
Sep 09 09:05:01: vmx| SCSI0:1: Command WRITE(10) took 1.263 seconds (ok)
Sep 09 10:06:59: vmx| DISKLIB-LIB :numIOs = 150000 numMergedIOs = 0 numSplitIOs = 0
Sep 09 12:31:00: vmx| DISKLIB-LIB :numIOs = 200000 numMergedIOs = 26047 numSplitIOs = 4519
Sep 09 14:30:42: vmx| LICENSE using: '/etc/vmware/license.vs.1.0-00'
Sep 10 01:03:32: vmx| SCSI0:0: Command WRITE(10) took 1.046 seconds (ok)
Sep 10 01:03:33: vmx| SCSI0:0: Command WRITE(10) took 1.019 seconds (ok)
........
Sep 10 01:03:33: vmx| SCSI0:0: Command WRITE(10) took 1.316 seconds (ok)
Sep 10 01:03:33: vmx| SCSI0:0: Command WRITE(10) took 1.321 seconds (ok)
Sep 10 04:06:09: vcpu-0| DISKLIB-LIB :numIOs = 250000 numMergedIOs = 34394 numSplitIOs = 5241
Sep 10 10:51:29: vcpu-0| DISKLIB-LIB :numIOs = 200000 numMergedIOs = 0 numSplitIOs = 0
Sep 10 11:11:51: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO
Sep 10 11:11:51: vcpu-0| GuestRpc: Channel 1, registration number 1, guest application toolbox-dnd.
Sep 10 11:11:51: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6532
Sep 10 11:11:51: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532
Sep 10 11:22:45: vmx| SCSI0:1: Command WRITE(10) took 1.452 seconds (ok)
Sep 10 11:22:45: vmx| SCSI0:1: Command WRITE(10) took 1.438 seconds (ok)
.....
Sep 10 11:23:27: vmx| SCSI0:0: Command WRITE(10) took 2.314 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.183 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:0: Command READ(10) took 1.426 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.168 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.155 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command READ(10) took 1.877 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.168 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.174 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.130 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.141 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.139 seconds (ok)
Sep 10 11:23:27: vmx| SCSI0:1: Command WRITE(10) took 3.135 seconds (ok)
Sep 10 11:23:31: vmx| SCSI0:1: Command READ(10) took 1.116 seconds (ok)
Sep 10 11:23:31: vmx| SCSI0:1: Command WRITE(10) took 2.106 seconds (ok)
Sep 10 11:23:32: vmx| SCSI0:1: Command WRITE(10) took 2.244 seconds (ok)
...
Sep 10 11:25:02: vmx| SCSI0:0: Command WRITE(10) took 1.536 seconds (ok)
Sep 10 11:25:02: vmx| SCSI0:0: Command READ(10) took 1.286 seconds (ok)
Sep 10 11:25:02: vmx| SCSI0:0: Command READ(10) took 1.293 seconds (ok)
Sep 10 11:25:02: vmx| SCSI0:1: Command WRITE(10) took 2.229 seconds (ok)
Sep 10 11:25:03: vmx| SCSI0:1: Command WRITE(10) took 2.334 seconds (ok)
...
ep 10 11:28:19: vmx| SCSI0:1: Command READ(10) took 1.106 seconds (ok)
Sep 10 11:28:19: vmx| SCSI0:1: Command WRITE(10) took 1.000 seconds (ok)
Sep 10 11:28:20: vmx| DISKLIB-LIB :numIOs = 250000 numMergedIOs = 0 numSplitIOs = 0
Sep 10 11:28:24: vmx| SCSI0:1: Command WRITE(10) took 1.053 seconds (ok)
Sep 10 11:28:25: vmx| SCSI0:0: Command WRITE(10) took 2.340 seconds (ok)
...
Sep 10 11:32:03: vmx| SCSI0:1: Command WRITE(10) took 2.721 seconds (ok)
Sep 10 11:32:03: vmx| SCSI0:1: Command WRITE(10) took 2.818 seconds (ok)
Sep 10 11:39:16: vmx| DISKLIB-LIB :numIOs = 300000 numMergedIOs = 39595 numSplitIOs = 6452
Sep 10 11:44:07: vmx| SCSI0:1: Command WRITE(10) took 2.694 seconds (ok)
Sep 10 11:44:09: vmx| SCSI0:1: Command WRITE(10) took 4.413 seconds (ok)
...
Sep 10 11:51:44: vmx| SCSI0:1: Command WRITE(10) took 1.325 seconds (ok)
Sep 10 11:51:45: vmx| SCSI0:1: Command WRITE(10) took 1.341 seconds (ok)
Sep 10 11:51:45: vmx| SCSI0:1: Command WRITE(10) took 1.330 seconds (ok)
Sep 10 11:51:45: vmx| SCSI0:1: Command WRITE(10) took 1.330 seconds (ok)
Sep 10 11:56:40: vmx| DISKLIB-LIB :numIOs = 350000 numMergedIOs = 0 numSplitIOs = 0
Sep 10 11:56:57: vmx| SCSI0:1: Command READ(10) took 1.115 seconds (ok)
Sep 10 11:56:57: vmx| SCSI0:1: Command READ(10) took 1.115 seconds (ok)
Sep 10 11:56:57: vmx| SCSI0:1: Command READ(10) took 1.143 seconds (ok)
Sep 10 11:57:18: vmx| SCSI0:0: Command WRITE(10) took 1.025 seconds (ok)
Sep 10 12:20:49: vcpu-0| GuestRpc: Channel 1 reinitialized.
Hier scheint der Gast gecrasht zu sein. Hat jemand eine Idee?