Festplatte langsam

[sunghost]

Hallo,
ich habe den Server2 auf Debian Lenny laufen. Als VM läuft WinXP. Wenn ich z.B. eine Datei entpacke oder auch nur Dateien kopiere, dann macht er dies mit einer Geschwindigkeit von ca. 1-2,5 MB. Verbaut ist eine schnelle 7200/Umin 16MB Cache Festplatte, die durchaus mehr leisten kann. Hat jemand einen Tipp was evtl. angepasst werden kann, bzw. woran es liegen könnte?

DAnke

[Dayworker]

Poste mal den Inhalt deines aktuellen vmware.log, dann sehen wir weiter.

[sunghost]

hostd.log:

[2009-07-01 11:45:52.528 'PropertyProvider' 3061529488 verbose] RecordOp ASSIGN: config.hardware.device[7000].busNumb er, 64
[2009-07-01 11:45:52.528 'PropertyProvider' 3061529488 verbose] RecordOp ASSIGN: summary, 64
[2009-07-01 11:46:18.247 'PropertyProvider' 3062320016 verbose] RecordOp ASSIGN: guest.disk, 64
[2009-07-01 11:46:50.407 'PropertyProvider' 3060997008 verbose] RecordOp ASSIGN: guest.disk, 64
[2009-07-01 11:47:23.498 'PropertyProvider' 3074127536 verbose] RecordOp ASSIGN: guest.disk, 64
[2009-07-01 11:47:56.830 'PropertyProvider' 3062586256 verbose] RecordOp ASSIGN: guest.disk, 64
[2009-07-01 11:48:21.280 'PropertyProvider' 3062320016 verbose] RecordOp ASSIGN: guest.disk, 32
[2009-07-01 11:48:31.044 'PropertyProvider' 3064441744 verbose] RecordOp ASSIGN: guest.disk, 64
[2009-07-01 11:49:04.796 'PropertyProvider' 3065236368 verbose] RecordOp ASSIGN: guest.disk, 64
[2009-07-01 11:49:38.788 'PropertyProvider' 3060997008 verbose] RecordOp ASSIGN: guest.disk, 64
[2009-07-01 11:50:11.868 'PropertyProvider' 3062852496 verbose] RecordOp ASSIGN: guest.disk, 64
[2009-07-01 11:50:44.225 'PropertyProvider' 3061529488 verbose] RecordOp ASSIGN: guest.disk, 64
[2009-07-01 11:51:17.610 'PropertyProvider' 3063913360 verbose] RecordOp ASSIGN: guest.disk, 64

log der entspr. VM

Jul 01 09:38:45.305: vcpu-0| TOOLS Setting autoupgrade-checked TRUE.
Jul 01 09:38:45.305: vcpu-0| Guest: toolbox: Version: build-156745
Jul 01 09:38:45.306: vcpu-0| TOOLS unified loop capability requested by 'toolbox'; now sending options via TCLO
Jul 01 09:39:35.011: vcpu-0| VIDE: (0x1f0) OUTB Cmd 0xb0, Unknown ATA Command on drive 0
Jul 01 09:45:38.988: vmx| ide0:0: Command WRITE(10) took 1.026 seconds (ok)
Jul 01 09:45:44.148: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO
Jul 01 09:45:44.160: vcpu-0| GuestRpc: Channel 1, guest application toolbox-dnd.
Jul 01 09:46:16.567: vmx| ide0:0: Command WRITE(10) took 1.060 seconds (ok)
Jul 01 09:57:08.281: vmx| DISKLIB-LIB : numIOs = 1750000 numMergedIOs = 601098 numSplitIOs = 6675
Jul 01 09:57:34.332: vmx| ide0:0: Command WRITE(10) took 1.359 seconds (ok)
Jul 01 10:05:06.882: vmx| ide0:0: Command WRITE(10) took 1.198 seconds (ok)
Jul 01 10:11:12.117: vmx| ide0:0: Command WRITE(10) took 1.088 seconds (ok)
Jul 01 10:11:17.173: vmx| ide0:0: Command WRITE(10) took 1.366 seconds (ok)
Jul 01 10:12:46.058: vmx| ide0:0: Command WRITE(10) took 1.057 seconds (ok)
Jul 01 10:13:53.740: vmx| DISKLIB-LIB : numIOs = 1800000 numMergedIOs = 615212 numSplitIOs = 6768
Jul 01 10:20:40.614: vmx| ide0:0: Command WRITE(10) took 1.098 seconds (ok)
Jul 01 10:21:15.613: vmx| ide0:0: Command WRITE(10) took 1.485 seconds (ok)
Jul 01 10:22:08.654: vcpu-0| ide0:0: Command READ(10) took 1.176 seconds (ok)
Jul 01 10:22:41.409: vmx| GuestRpcSendTimedOut: message to toolbox timed out.
Jul 01 10:22:41.438: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Jul 01 10:35:48.201: vmx| DISKLIB-LIB : numIOs = 1850000 numMergedIOs = 626920 numSplitIOs = 6906
Jul 01 11:00:10.512: vmx| ide0:0: Command WRITE(10) took 1.103 seconds (ok)
Jul 01 11:02:41.954: vmx| ide0:0: Command WRITE(10) took 1.291 seconds (ok)
Jul 01 11:03:26.562: vmx| GuestRpcSendTimedOut: message to toolbox timed out.
Jul 01 11:03:26.578: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Jul 01 11:04:41.565: vmx| GuestRpcSendTimedOut: message to toolbox timed out.
Jul 01 11:06:48.382: vmx| ide0:0: Command WRITE(10) took 1.000 seconds (ok)
Jul 01 11:10:54.148: vmx| ide0:0: Command WRITE(10) took 1.323 seconds (ok)
Jul 01 11:11:00.254: vmx| ide0:0: Command WRITE(10) took 1.174 seconds (ok)
Jul 01 11:11:48.078: vcpu-0| DISKLIB-LIB : numIOs = 1900000 numMergedIOs = 649823 numSplitIOs = 7073
Jul 01 11:12:30.045: vmx| ide0:0: Command WRITE(10) took 1.425 seconds (ok)
Jul 01 11:13:29.730: vmx| ide0:0: Command WRITE(10) took 1.592 seconds (ok)
Jul 01 11:14:56.557: vmx| GuestRpcSendTimedOut: message to toolbox timed out.
Jul 01 11:15:20.498: vmx| ide0:0: Command WRITE(10) took 1.005 seconds (ok)
Jul 01 11:15:25.088: vmx| ide0:0: Command WRITE(10) took 1.029 seconds (ok)
Jul 01 11:16:56.557: vmx| GuestRpcSendTimedOut: message to toolbox timed out.
Jul 01 11:16:56.558: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Jul 01 11:18:05.352: vmx| ide0:0: Command WRITE(10) took 1.426 seconds (ok)
Jul 01 11:27:35.824: vmx| ide0:0: Command WRITE(10) took 2.036 seconds (ok)
Jul 01 11:27:45.035: vmx| ide0:0: Command WRITE(10) took 1.039 seconds (ok)
Jul 01 11:27:56.978: vmx| ide0:0: Command WRITE(10) took 1.003 seconds (ok)
Jul 01 11:28:03.454: vmx| ide0:0: Command WRITE(10) took 1.122 seconds (ok)
Jul 01 11:28:16.683: vmx| ide0:0: Command WRITE(10) took 1.151 seconds (ok)
Jul 01 11:29:11.990: vmx| ide0:0: Command WRITE(10) took 1.228 seconds (ok)
Jul 01 11:29:24.126: vmx| ide0:0: Command WRITE(10) took 1.078 seconds (ok)
Jul 01 11:29:40.839: vmx| DISKLIB-LIB : numIOs = 1950000 numMergedIOs = 670832 numSplitIOs = 7184
Jul 01 11:30:24.636: vmx| ide0:0: Command WRITE(10) took 1.366 seconds (ok)
Jul 01 11:30:34.149: vmx| ide0:0: Command WRITE(10) took 1.544 seconds (ok)
Jul 01 11:31:03.771: vmx| ide0:0: Command WRITE(10) took 1.882 seconds (ok)
Jul 01 11:31:48.191: vmx| ide0:0: Command WRITE(10) took 1.125 seconds (ok)
Jul 01 11:32:07.110: vmx| ide0:0: Command WRITE(10) took 1.236 seconds (ok)
Jul 01 11:33:27.703: vmx| ide0:0: Command WRITE(10) took 1.187 seconds (ok)
Jul 01 11:33:32.334: vmx| ide0:0: Command WRITE(10) took 1.156 seconds (ok)
Jul 01 11:37:18.270: vmx| ide0:0: Command WRITE(10) took 1.023 seconds (ok)
Jul 01 11:37:22.667: vmx| ide0:0: Command WRITE(10) took 1.198 seconds (ok)
Jul 01 11:37:37.420: vmx| ide0:0: Command WRITE(10) took 1.221 seconds (ok)
Jul 01 11:38:28.232: vmx| ide0:0: Command WRITE(10) took 1.012 seconds (ok)
Jul 01 11:38:33.119: vmx| ide0:0: Command WRITE(10) took 1.775 seconds (ok)
Jul 01 11:38:48.841: vmx| ide0:0: Command WRITE(10) took 1.280 seconds (ok)
Jul 01 11:41:55.972: vmx| ide0:0: Command WRITE(10) took 1.199 seconds (ok)
Jul 01 11:42:02.736: vmx| ide0:0: Command WRITE(10) took 1.020 seconds (ok)
Jul 01 11:42:06.211: vmx| ide0:0: Command WRITE(10) took 1.443 seconds (ok)
Jul 01 11:43:11.086: vmx| ide0:0: Command WRITE(10) took 1.240 seconds (ok)
Jul 01 11:43:12.314: vmx| ide0:0: Command WRITE(10) took 1.228 seconds (ok)
Jul 01 11:43:21.622: vmx| ide0:0: Command WRITE(10) took 1.087 seconds (ok)
Jul 01 11:43:30.851: vmx| ide0:0: Command WRITE(10) took 1.245 seconds (ok)
Jul 01 11:44:10.734: vmx| ide0:0: Command WRITE(10) took 1.106 seconds (ok)
Jul 01 11:44:14.361: vmx| ide0:0: Command WRITE(10) took 1.245 seconds (ok)
Jul 01 11:44:16.010: vmx| ide0:0: Command WRITE(10) took 1.648 seconds (ok)
Jul 01 11:44:46.274: vmx| ide0:0: Command WRITE(10) took 1.462 seconds (ok)
Jul 01 11:44:48.496: vmx| ide0:0: Command WRITE(10) took 2.221 seconds (ok)
Jul 01 11:44:52.185: vmx| ide0:0: Command WRITE(10) took 1.552 seconds (ok)
Jul 01 11:44:58.760: vmx| ide0:0: Command WRITE(10) took 1.412 seconds (ok)
Jul 01 11:45:09.590: vmx| ide0:0: Command WRITE(10) took 1.113 seconds (ok)
Jul 01 11:45:15.345: vmx| ide0:0: Command WRITE(10) took 1.333 seconds (ok)
Jul 01 11:45:38.930: vmx| ide0:0: Command WRITE(10) took 1.192 seconds (ok)
Jul 01 11:45:41.665: vmx| GuestRpcSendTimedOut: message to toolbox timed out.
Jul 01 11:45:41.666: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Jul 01 11:45:44.524: vmx| ide0:0: Command WRITE(10) took 1.410 seconds (ok)
Jul 01 11:47:25.861: vmx| ide0:0: Command WRITE(10) took 1.115 seconds (ok)
Jul 01 11:47:39.591: vmx| ide0:0: Command WRITE(10) took 1.089 seconds (ok)
Jul 01 11:48:00.378: vmx| ide0:0: Command WRITE(10) took 1.145 seconds (ok)
Jul 01 11:48:17.492: vmx| DISKLIB-LIB : numIOs = 2000000 numMergedIOs = 694510 numSplitIOs = 7455
Jul 01 11:49:47.090: vmx| ide0:0: Command WRITE(10) took 1.002 seconds (ok)
Jul 01 11:51:37.999: vmx| ide0:0: Command WRITE(10) took 1.304 seconds (ok)
Jul 01 11:51:42.709: vmx| ide0:0: Command WRITE(10) took 1.031 seconds (ok)
Jul 01 11:52:49.665: vmx| ide0:0: Command WRITE(10) took 1.401 seconds (ok)
Jul 01 11:53:03.643: vmx| ide0:0: Command WRITE(10) took 1.176 seconds (ok)

sind nur Ausschnitte, da die Logs groß sind.

[Dayworker]

Das "hostd.log" hilft hier nichts und ein gekürztes vmware.log ist völlig nutzlos... Verlinke das Log doch einfach auf einen Freehoster, wenn es mehre 100KB hat.
Ansonsten fahre die VM runter, mach nach einem erneuten VM-Start ein paar IO-Zugriffe darin, 10min dürften ausreichen und poste dann den Inhalt. Dann sollte das Log auch nicht so groß sein.

[continuum]

leider ist das log nicht komplett ...
bitte lade es als ganzes bei ifile.it hoch

[Dayworker]

@sunghost
Deine unvollständigen Logs kannst du genauso gut wieder löschen, sie sind leider für niemanden hilfreich. Denn die Schreibverzögerungen hattest du ja schon beschrieben, aber die Ursache dafür steht weiter oben im Log. Deshalb geht ohne vollständiges Log gar nichts und im Raten sind wir schlecht.

[sunghost]

PNs sind raus - reicht das, oder braucht ihr noch mehr Infos?

[sunghost]

Hallo,
und was sagen die Fachleute? Oder braucht ihr noch mehr Logs?

[Dayworker]

Per PN, da seh ich so selten rein.

[quote="sunghost"]Jun 29 00:14:07.022: vmx| Log for VMware Server pid=3159 version=2.0.1 build=build-156745 option=Release
Jun 29 00:14:07.022: vmx| Hostname=masrv01
Jun 29 00:14:07.022: vmx| IP=127.0.0.1 (lo)
Jun 29 00:14:07.022: vmx| IP=192.168.0.44 (eth0)
Jun 29 00:14:07.022: vmx| IP=192.168.92.1 (vmnet1)
Jun 29 00:14:07.022: vmx| IP=192.168.176.1 (vmnet8)
Jun 29 00:14:07.022: vmx| Command line: "/usr/lib/vmware/bin/vmware-vmx" "-#" "product=2;name=VMware Server;version=2.0.1;buildnumber=156745;
licensename=VMware GSX Server for Linux;licenseversion=3.0 build-156745;" "-@" "pipe=/tmp/vmhsdaemon-0/vmxcdcc91a8391b2ba3;readyEvent=49"
"/var/lib/vmware/Virtual Machines/Workstation01/Workstation01.vmx"
Jun 29 00:14:07.023: vmx| Ready event: 49
Jun 29 00:14:07.107: vmx| UI Connecting to pipe '/tmp/vmhsdaemon-0/vmxcdcc91a8391b2ba3' with user '(null)'
Jun 29 00:14:07.112: vmx| Removing stale symlink /var/run/vmware/2529ecd68aa7a3c46d278277eeea2e79
Jun 29 00:14:07.112: vmx| Setup symlink /var/run/vmware/2529ecd68aa7a3c46d278277eeea2e79 -> /var/run/vmware/root_0/1246227247023165_3159
Jun 29 00:14:07.125: vmx| Sig_Init already initialized
Jun 29 00:14:07.134: vmx| Transitioned vmx/execState/val to poweredOff
Jun 29 00:14:07.144: vmx| MStat: Creating Stat system.cpuusage
Jun 29 00:14:07.144: vmx| MStat: Creating Stat system.ram
Jun 29 00:14:07.144: vmx| MStat: Creating Stat system.uptime
Jun 29 00:14:07.144: vmx| MStat: Creating Stat system.load
Jun 29 00:14:07.160: vmx| VMMon_GetkHzEstimate: Calculated 1591627 kHz
Jun 29 00:14:07.161: vmx| CPU kHz estimates: vmmon 1591627, cpuinfo 1600076, cpufreq 0. Using 1591627 kHz
Jun 29 00:14:07.161: vmx| CPU # 0 TSC = 83275333056
Jun 29 00:14:07.161: vmx| TSC delta 0
Jun 29 00:14:07.177: vmx| PTSC: RefClockToTSC 1000000Hz -> 1591627000Hz
Jun 29 00:14:07.177: vmx| PTSC: RefClockToTSC ((x * 3337883746) >> 21)
Jun 29 00:14:07.177: vmx| PTSC: using TSC
Jun 29 00:14:07.177: vmx| CPUID[0] vendor: GenuntelineI
Jun 29 00:14:07.177: vmx| CPUID[0] name: Intel(R) Celeron(R) M CPU 420 @ 1.60GHz
Jun 29 00:14:07.177: vmx| CPUID[0] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Jun 29 00:14:07.177: vmx| CPUID[0] level 00000001, 0: 0x000006e8 0x00010800 0x0000c109 0xafe9fbff
Jun 29 00:14:07.177: vmx| CPUID[0] level 00000002, 0: 0x02b3b001 0x000000f0 0x00000000 0x2c043078
Jun 29 00:14:07.177: vmx| CPUID[0] level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| CPUID[0] level 00000004, 0: 0x00000121 0x01c0003f 0x0000003f 0x00000001
Jun 29 00:14:07.177: vmx| CPUID[0] level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00001110
Jun 29 00:14:07.177: vmx| CPUID[0] level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Jun 29 00:14:07.177: vmx| CPUID[0] level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| CPUID[0] level 00000008, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| CPUID[0] level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| CPUID[0] level 0000000a, 0: 0x07280201 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| CPUID[0] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| CPUID[0] level 80000001, 0: 0x00000000 0x00000000 0x00000000 0x00100000
Jun 29 00:14:07.177: vmx| CPUID[0] level 80000002, 0: 0x65746e49 0x2952286c 0x6c654320 0x6e6f7265
Jun 29 00:14:07.177: vmx| CPUID[0] level 80000003, 0: 0x20295228 0x5043204d 0x20202055 0x20202020
Jun 29 00:14:07.177: vmx| CPUID[0] level 80000004, 0: 0x30323420 0x20402020 0x30362e31 0x007a4847
Jun 29 00:14:07.177: vmx| CPUID[0] level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| CPUID[0] level 80000006, 0: 0x00000000 0x00000000 0x04004040 0x00000000
Jun 29 00:14:07.177: vmx| CPUID[0] level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| CPUID[0] level 80000008, 0: 0x00002020 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| hostCPUID vendor: GenuntelineI
Jun 29 00:14:07.177: vmx| hostCPUID name: Intel(R) Celeron(R) M CPU 420 @ 1.60GHz
Jun 29 00:14:07.177: vmx| hostCPUID level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Jun 29 00:14:07.177: vmx| hostCPUID level 00000001, 0: 0x000006e8 0x00010800 0x0000c109 0xafe9fbff
Jun 29 00:14:07.177: vmx| hostCPUID level 00000002, 0: 0x02b3b001 0x000000f0 0x00000000 0x2c043078
Jun 29 00:14:07.177: vmx| hostCPUID level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| hostCPUID level 00000004, 0: 0x00000121 0x01c0003f 0x0000003f 0x00000001
Jun 29 00:14:07.177: vmx| hostCPUID level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00001110
Jun 29 00:14:07.177: vmx| hostCPUID level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Jun 29 00:14:07.177: vmx| hostCPUID level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| hostCPUID level 00000008, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| hostCPUID level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| hostCPUID level 0000000a, 0: 0x07280201 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| hostCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.177: vmx| hostCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000000 0x00100000
Jun 29 00:14:07.178: vmx| hostCPUID level 80000002, 0: 0x65746e49 0x2952286c 0x6c654320 0x6e6f7265
Jun 29 00:14:07.178: vmx| hostCPUID level 80000003, 0: 0x20295228 0x5043204d 0x20202055 0x20202020
Jun 29 00:14:07.178: vmx| hostCPUID level 80000004, 0: 0x30323420 0x20402020 0x30362e31 0x007a4847
Jun 29 00:14:07.178: vmx| hostCPUID level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.178: vmx| hostCPUID level 80000006, 0: 0x00000000 0x00000000 0x04004040 0x00000000
Jun 29 00:14:07.178: vmx| hostCPUID level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.178: vmx| hostCPUID level 80000008, 0: 0x00002020 0x00000000 0x00000000 0x00000000
Jun 29 00:14:07.178: vmx| CPUID Maximum Physical Address Bits supported across all CPUs: 32
Jun 29 00:14:07.178: vmx| Licensecheck: Invalid license file.
Jun 29 00:14:07.207: vmx| Host ACPI: can't find SRAT
Jun 29 00:14:07.207: vmx| Host: SRAT tables not found in memory
Jun 29 00:14:07.214: vmx| ACL_InitCapabilities: current IPC thread
Jun 29 00:14:07.214: vmx| ACL_InitCapabilities: done
Jun 29 00:14:07.214: vmx| changing directory to /var/lib/vmware/Virtual Machines/Workstation01/.
Jun 29 00:14:07.214: vmx| Config file: /var/lib/vmware/Virtual Machines/Workstation01/Workstation01.vmx
Jun 29 00:14:07.305: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Jun 29 00:14:07.500: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset, soft.
Jun 29 00:14:07.510: vmx| TOOLS delaying state change request to state 3
Jun 29 00:14:07.514: vmx| PowerOn
Jun 29 00:14:07.514: vmx| VMX_PowerOn: VMX build 156745, UI build 156745
Jun 29 00:14:07.514: vmx| Host ACPI: can't find SRAT
Jun 29 00:14:07.514: vmx| Host: SRAT tables not found in memory
Jun 29 00:14:07.524: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Jun 29 00:14:07.531: vmx| HOST sysname Linux, nodename masrv01, release 2.6.26-2-686, version #1 SMP Thu May 28 15:39:35 UTC 2009, machine i686, hz=250
Jun 29 00:14:07.531: vmx| DICT --- USER PREFERENCES
Jun 29 00:14:07.531: vmx| DICT --- USER DEFAULTS
Jun 29 00:14:07.531: vmx| DICT --- HOST DEFAULTS
Jun 29 00:14:07.531: vmx| DICT guest.commands.enabledonhost = TRUE
Jun 29 00:14:07.531: vmx| DICT vmnet1.hostonlyaddress = 192.168.92.1
Jun 29 00:14:07.531: vmx| DICT authd.client.port = 902
Jun 29 00:14:07.531: vmx| DICT authd.proxy.nfc = vmware-hostd:ha-nfc
Jun 29 00:14:07.531: vmx| DICT loop.fullpath = /usr/bin/vmware-loop
Jun 29 00:14:07.531: vmx| DICT guest.commands.anonguestpasswordonhost =
Jun 29 00:14:07.531: vmx| DICT product.buildnumber = 156745
Jun 29 00:14:07.531: vmx| DICT vmnet1.hostonlynetmask = 255.255.255.0
Jun 29 00:14:07.531: vmx| DICT authd.proxy.vim = vmware-hostd:hostd-vmdb
Jun 29 00:14:07.531: vmx| DICT bindir = /usr/bin
Jun 29 00:14:07.531: vmx| DICT dhcpd.fullpath = /usr/bin/vmnet-dhcpd
Jun 29 00:14:07.531: vmx| DICT guest.commands.anonguestusernameonhost =
Jun 29 00:14:07.531: vmx| DICT control.fullpath = /usr/bin/vmware-cmd
Jun 29 00:14:07.531: vmx| DICT product.version = 2.0.1
Jun 29 00:14:07.531: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd
Jun 29 00:14:07.531: vmx| DICT guest.commands.allowanonrootguestcommandsonhost = FALSE
Jun 29 00:14:07.531: vmx| DICT vmware.fullpath = /usr/bin/vmware
Jun 29 00:14:07.531: vmx| DICT libdir = /usr/lib/vmware
Jun 29 00:14:07.531: vmx| DICT guest.commands.allowanonguestcommandsonhost = FALSE
Jun 29 00:14:07.531: vmx| DICT vmdir = /var/lib/vmware/Virtual Machines
Jun 29 00:14:07.531: vmx| DICT authd.soapserver = TRUE
Jun 29 00:14:07.532: vmx| DICT product.name = VMware Server
Jun 29 00:14:07.532: vmx| DICT vix.libdir = /usr/lib/vmware-vix/lib
Jun 29 00:14:07.532: vmx| DICT --- SITE DEFAULTS
Jun 29 00:14:07.532: vmx| DICT tag.help = introduction.htm
Jun 29 00:14:07.532: vmx| DICT tag.configurationEditor = config_editor_newvm.htm
Jun 29 00:14:07.532: vmx| DICT tag.ideConfig = devices_virtualdrive.htm
Jun 29 00:14:07.532: vmx| DICT tag.floppyConfig = devices_floppy.htm
Jun 29 00:14:07.532: vmx| DICT tag.mouseConfig = devices_mouse.htm
Jun 29 00:14:07.532: vmx| DICT tag.netConfig = devices_netadapter.htm
Jun 29 00:14:07.532: vmx| DICT tag.parallelConfig = devices_parallel.htm
Jun 29 00:14:07.532: vmx| DICT tag.serialConfig = devices_serial.htm
Jun 29 00:14:07.532: vmx| DICT tag.soundConfig = devices_sound.htm
Jun 29 00:14:07.532: vmx| DICT tag.memConfig = configvm_memory.htm
Jun 29 00:14:07.532: vmx| DICT tag.miscConfig = configvm.htm
Jun 29 00:14:07.532: vmx| DICT tag.usbConfig = devices_usb.htm
Jun 29 00:14:07.532: vmx| DICT tag.displayConfig = configvm_display-problems.htm
Jun 29 00:14:07.542: vmx| DICT tag.tools = vmtools.htm
Jun 29 00:14:07.542: vmx| DICT --- COMMAND LINE
Jun 29 00:14:07.542: vmx| DICT --- CONFIGURATION
Jun 29 00:14:07.542: vmx| DICT config.version = 8
Jun 29 00:14:07.542: vmx| DICT virtualHW.version = 7
Jun 29 00:14:07.542: vmx| DICT floppy0.present = FALSE
Jun 29 00:14:07.542: vmx| DICT mks.enable3d = TRUE
Jun 29 00:14:07.542: vmx| DICT pciBridge0.present = TRUE
Jun 29 00:14:07.542: vmx| DICT pciBridge4.present = TRUE
Jun 29 00:14:07.542: vmx| DICT pciBridge4.virtualDev = pcieRootPort
Jun 29 00:14:07.542: vmx| DICT pciBridge4.functions = 8
Jun 29 00:14:07.542: vmx| DICT pciBridge5.present = TRUE
Jun 29 00:14:07.542: vmx| DICT pciBridge5.virtualDev = pcieRootPort
Jun 29 00:14:07.542: vmx| DICT pciBridge5.functions = 8
Jun 29 00:14:07.542: vmx| DICT pciBridge6.present = TRUE
Jun 29 00:14:07.542: vmx| DICT pciBridge6.virtualDev = pcieRootPort
Jun 29 00:14:07.542: vmx| DICT pciBridge6.functions = 8
Jun 29 00:14:07.542: vmx| DICT pciBridge7.present = TRUE
Jun 29 00:14:07.542: vmx| DICT pciBridge7.virtualDev = pcieRootPort
Jun 29 00:14:07.542: vmx| DICT pciBridge7.functions = 8
Jun 29 00:14:07.542: vmx| DICT vmci0.present = TRUE
Jun 29 00:14:07.542: vmx| DICT nvram = Workstation01.nvram
Jun 29 00:14:07.542: vmx| DICT virtualHW.productCompatibility = hosted
Jun 29 00:14:07.542: vmx| DICT ft.secondary0.enabled = TRUE
Jun 29 00:14:07.542: vmx| DICT tools.upgrade.policy = useGlobal
Jun 29 00:14:07.542: vmx| DICT powerType.powerOff = soft
Jun 29 00:14:07.542: vmx| DICT powerType.powerOn = hard
Jun 29 00:14:07.542: vmx| DICT powerType.suspend = hard
Jun 29 00:14:07.542: vmx| DICT powerType.reset = soft
Jun 29 00:14:07.542: vmx| DICT displayName = Workstation01
Jun 29 00:14:07.542: vmx| DICT extendedConfigFile = Workstation01.vmxf
Jun 29 00:14:07.542: vmx| DICT memsize = 256
Jun 29 00:14:07.542: vmx| DICT ide0:0.present = TRUE
Jun 29 00:14:07.542: vmx| DICT ide0:0.fileName = Workstation01.vmdk
Jun 29 00:14:07.542: vmx| DICT ide0:0.writeThrough = TRUE
Jun 29 00:14:07.542: vmx| DICT ide1:0.present = TRUE
Jun 29 00:14:07.542: vmx| DICT ide1:0.fileName = /home/shares/iso/winxp.iso
Jun 29 00:14:07.542: vmx| DICT ide1:0.deviceType = cdrom-image
Jun 29 00:14:07.542: vmx| DICT ide1:0.allowGuestConnectionControl = FALSE
Jun 29 00:14:07.542: vmx| DICT ethernet0.present = TRUE
Jun 29 00:14:07.542: vmx| DICT ethernet0.allowGuestConnectionControl = FALSE
Jun 29 00:14:07.542: vmx| DICT ethernet0.features = 1
Jun 29 00:14:07.542: vmx| DICT ethernet0.wakeOnPcktRcv = FALSE
Jun 29 00:14:07.542: vmx| DICT ethernet0.networkName = Bridged
Jun 29 00:14:07.542: vmx| DICT ethernet0.addressType = generated
Jun 29 00:14:07.542: vmx| DICT usb.present = TRUE
Jun 29 00:14:07.542: vmx| DICT ehci.present = TRUE
Jun 29 00:14:07.542: vmx| DICT guestOS = winxppro
Jun 29 00:14:07.542: vmx| DICT uuid.location = 56 4d 69 1d b8 48 ea 4f-fd 8e 1c 0d e2 1f 3b a9
Jun 29 00:14:07.542: vmx| DICT uuid.bios = 56 4d 69 1d b8 48 ea 4f-fd 8e 1c 0d e2 1f 3b a9
Jun 29 00:14:07.542: vmx| DICT vc.uuid = 52 5a 26 37 5c 9e 6b d9-71 25 18 d3 6a c2 25 ab
Jun 29 00:14:07.542: vmx| DICT ethernet0.generatedAddress = 00:0c:29:1f:3b:a9
Jun 29 00:14:07.542: vmx| DICT ide0:0.redo =
Jun 29 00:14:07.542: vmx| DICT vmotion.checkpointFBSize = 16777216
Jun 29 00:14:07.542: vmx| DICT pciBridge0.pciSlotNumber = 17
Jun 29 00:14:07.542: vmx| DICT pciBridge4.pciSlotNumber = 21
Jun 29 00:14:07.543: vmx| DICT pciBridge5.pciSlotNumber = 22
Jun 29 00:14:07.543: vmx| DICT pciBridge6.pciSlotNumber = 23
Jun 29 00:14:07.543: vmx| DICT pciBridge7.pciSlotNumber = 24
Jun 29 00:14:07.543: vmx| DICT usb.pciSlotNumber = 32
Jun 29 00:14:07.543: vmx| DICT ethernet0.pciSlotNumber = 33
Jun 29 00:14:07.543: vmx| DICT ehci.pciSlotNumber = 34
Jun 29 00:14:07.543: vmx| DICT vmci0.pciSlotNumber = 35
Jun 29 00:14:07.543: vmx| DICT ethernet0.generatedAddressOffset = 0
Jun 29 00:14:07.543: vmx| DICT vmci0.id = -501269591
Jun 29 00:14:07.543: vmx| DICT usb:0.present = TRUE
Jun 29 00:14:07.543: vmx| DICT usb:1.present = TRUE
Jun 29 00:14:07.543: vmx| DICT tools.remindInstall = FALSE
Jun 29 00:14:07.543: vmx| DICT usb:1.deviceType = hub
Jun 29 00:14:07.543: vmx| DICT usb:0.deviceType = mouse
Jun 29 00:14:07.543: vmx| DICT ide1:0.startConnected = TRUE
Jun 29 00:14:07.543: vmx| DICT tools.syncTime = FALSE
Jun 29 00:14:07.543: vmx| DICT --- USER DEFAULTS
Jun 29 00:14:07.543: vmx| DICT --- HOST DEFAULTS
Jun 29 00:14:07.543: vmx| DICT guest.commands.enabledonhost = TRUE
Jun 29 00:14:07.543: vmx| DICT vmnet1.hostonlyaddress = 192.168.92.1
Jun 29 00:14:07.543: vmx| DICT authd.client.port = 902
Jun 29 00:14:07.543: vmx| DICT authd.proxy.nfc = vmware-hostd:ha-nfc
Jun 29 00:14:07.543: vmx| DICT loop.fullpath = /usr/bin/vmware-loop
Jun 29 00:14:07.543: vmx| DICT guest.commands.anonguestpasswordonhost =
Jun 29 00:14:07.543: vmx| DICT product.buildnumber = 156745
Jun 29 00:14:07.543: vmx| DICT vmnet1.hostonlynetmask = 255.255.255.0
Jun 29 00:14:07.543: vmx| DICT authd.proxy.vim = vmware-hostd:hostd-vmdb
Jun 29 00:14:07.543: vmx| DICT bindir = /usr/bin
Jun 29 00:14:07.543: vmx| DICT dhcpd.fullpath = /usr/bin/vmnet-dhcpd
Jun 29 00:14:07.543: vmx| DICT guest.commands.anonguestusernameonhost =
Jun 29 00:14:07.543: vmx| DICT control.fullpath = /usr/bin/vmware-cmd
Jun 29 00:14:07.543: vmx| DICT product.version = 2.0.1
Jun 29 00:14:07.543: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd
Jun 29 00:14:07.543: vmx| DICT guest.commands.allowanonrootguestcommandsonhost = FALSE
Jun 29 00:14:07.543: vmx| DICT vmware.fullpath = /usr/bin/vmware
Jun 29 00:14:07.543: vmx| DICT libdir = /usr/lib/vmware
Jun 29 00:14:07.543: vmx| DICT guest.commands.allowanonguestcommandsonhost = FALSE
Jun 29 00:14:07.543: vmx| DICT vmdir = /var/lib/vmware/Virtual Machines
Jun 29 00:14:07.543: vmx| DICT authd.soapserver = TRUE
Jun 29 00:14:07.543: vmx| DICT product.name = VMware Server
Jun 29 00:14:07.543: vmx| DICT vix.libdir = /usr/lib/vmware-vix/lib
Jun 29 00:14:07.543: vmx| DICT --- SITE DEFAULTS
Jun 29 00:14:07.543: vmx| DICT tag.help = introduction.htm
Jun 29 00:14:07.543: vmx| DICT tag.configurationEditor = config_editor_newvm.htm
Jun 29 00:14:07.543: vmx| DICT tag.ideConfig = devices_virtualdrive.htm
Jun 29 00:14:07.543: vmx| DICT tag.floppyConfig = devices_floppy.htm
Jun 29 00:14:07.543: vmx| DICT tag.mouseConfig = devices_mouse.htm
Jun 29 00:14:07.543: vmx| DICT tag.netConfig = devices_netadapter.htm
Jun 29 00:14:07.543: vmx| DICT tag.parallelConfig = devices_parallel.htm
Jun 29 00:14:07.543: vmx| DICT tag.serialConfig = devices_serial.htm
Jun 29 00:14:07.543: vmx| DICT tag.soundConfig = devices_sound.htm
Jun 29 00:14:07.543: vmx| DICT tag.memConfig = configvm_memory.htm
Jun 29 00:14:07.543: vmx| DICT tag.miscConfig = configvm.htm
Jun 29 00:14:07.543: vmx| DICT tag.usbConfig = devices_usb.htm
Jun 29 00:14:07.543: vmx| DICT tag.displayConfig = configvm_display-problems.htm
Jun 29 00:14:07.543: vmx| DICT tag.tools = vmtools.htm
Jun 29 00:14:07.543: vmx| DICT --- GLOBAL SETTINGS
Jun 29 00:14:07.543: vmx| Msg_Hint: msg.guestos.xp (sent)
Jun 29 00:14:07.543: vmx| The Microsoft Windows XP product activation feature creates a key based on the virtual hardware in the virtual machine where it is installed.
Changes in the virtual machine configuration may require you to reactivate the guest operating system.
To minimize those changes, be sure to set the final memory size for the virtual machine and install VMware Tools before you activate Windows XP.
Jun 29 00:14:07.543: vmx| For more information about Windows XP product activation and virtual machines see our Web site at "http://vmware.com/info?id=21".
Jun 29 00:14:07.543: vmx| ---------------------------------------
Jun 29 00:14:07.803: vmx| hostCpuFeatures = 0x404001f8
Jun 29 00:14:07.803: vmx| hostNumPerfCounters = 2
Jun 29 00:14:07.803: vmx| CPU0: PMC: unused [c:1 f:1 e:1]
Jun 29 00:14:07.803: vmx| MONITOR MODE: allowed modes : BT
Jun 29 00:14:07.803: vmx| MONITOR MODE: user requested modes : BT HV HWMMU
Jun 29 00:14:07.803: vmx| MONITOR MODE: guestOS preferred modes: BT HWMMU HV
Jun 29 00:14:07.803: vmx| MONITOR MODE: filtered list : BT
Jun 29 00:14:07.803: vmx| HV Settings: virtual exec = 'software'; virtual mmu = 'software'
Jun 29 00:14:07.986: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Jun 29 00:14:07.993: vmx| Failed to detect host topology, using defaults for width and height
Jun 29 00:14:07.993: vmx| Host display topology 2360x1770.
Jun 29 00:14:07.993: vmx| SVGA using 2360x1770.
Jun 29 00:14:07.993: vmx| WSSCAN: reserved mem (in MB) min=32 max=1952 recommended=1952
Jun 29 00:14:07.993: vmx| WSSCAN: used rec mem (in MB) 1952
Jun 29 00:14:07.993: vmx| PSHARE: deactivated
Jun 29 00:14:07.993: vmx| WSSCAN: Overhead 70942 paged 13820 nonpaged 4096 maxFBSize
Jun 29 00:14:07.993: vmx| WSSCAN 1 0 448446 456638 499712 -1 50 0
Jun 29 00:14:07.994: vmx| Licensecheck: Invalid license file.
Jun 29 00:14:08.006: vmx| LICENSE using: '/usr/lib/vmware/licenses/site/license.vs.1.0-00'
Jun 29 00:14:08.048: vmx| LOG failed to remove stats/stats32-2 failed: No such file or directory
Jun 29 00:14:08.048: vmx| LOG failed to remove stats/stats64-2 failed: No such file or directory
Jun 29 00:14:08.048: vmx| LOG failed to remove stats32-2 failed: No such file or directory
Jun 29 00:14:08.048: vmx| LOG failed to remove stats64-2 failed: No such file or directory
Jun 29 00:14:08.049: vmx| Monitor_PowerOn: HostedVSMP skew tracking is disabled
Jun 29 00:14:08.049: vmx| Monitor_PowerOn: HostedVSMP crosscall yielding is disabled
Jun 29 00:14:08.050: vmx| vmm32-modules:
[vmm.vmm32 .data:0x2b000-0x728 .sdata:0x2c000-0x4e4 .statvars:0x2d000-0x3a0
.peer:0x2e000-0x261c0 .shared:0x56000-0x12ac0 .bss:0x6a000-0x5c88
.rodata:0x71000-0xb430 .text:0x7d000-0x5902d .kstatvars:0x3000-0x0,
mmu-pae.vmm32 .rodata:0x7c430-0x44 .data:0x2b728-0xc .peer:0x541c0-0x16c0 .shared:0x68ac0-0x2c0 .bss:0x6fca0-0x518 .text:0xd6030-0xa4e7
.comment:0x40000d26-0x10e .statvars:0x2000-0x0 .kstatvars:0x2000-0x0
.scb:0x400038a0-0x180 .shared_meta:0x40003c00-0x390
.peer_meta:0x40001020-0x210, sharedmmu-none.vmm32 .text:0xe0518-0x32
.comment:0x40000e34-0x12, pv-none.vmm32 .shared:0x68d80-0x180 .bss:0x701c0-0x84 .text:0xe054c-0xe6 .comment:0x40000e46-0x48
.shared_meta:0x40003f90-0x90, vprobe-none.vmm32 .text:0xe0634-0x79
.comment:0x40000e8e-0x12, hv-none.vmm32 .rodata:0x7c474-0x4 .data:0x1000-0x0
.peer:0x1000-0x0 .shared:0x1000-0x0 .bss:0x1000-0x0 .text:0xe06b0-0x2d
.comment:0x40000ea0-0x12 .statvars:0x1000-0x0 .kstatvars:0x1000-0x0,
gphys-sw.vmm32 .peer:0x55880-0x40 .shared:0x68f00-0x140 .bss:0x2000-0x0 .text:0xe06e0-0xb43 .comment:0x40000eb2-0x12 .scb:0x40003a20-0x60
.shared_meta:0x40004020-0x2a0 .peer_meta:0x40001230-0x60, vassert-none.vmm32
.text:0xe1224-0xf .comment:0x40000ec4-0x12, vmsafe-none.vmm32 .text:0xe1234-0xd
.comment:0x40000ed6-0x12, <MonSrcFile> .rodata:0x7c478-0x36c]
Jun 29 00:14:08.053: vmx| KHZEstimate 1591627
Jun 29 00:14:08.053: vmx| MHZEstimate 1592
Jun 29 00:14:08.053: vmx| NumVCPUs 1
Jun 29 00:14:08.054: vmx| PShare: enabled 0, scanRate 0, checkRate 16
Jun 29 00:14:08.054: vmx| UUID: SMBIOS UUID is reported as '80 04 2b 05 71 09 d9 11-a3 d2 00 16 36 c1 dd 4f'.
Jun 29 00:14:08.054: vmx| UUID: location-UUID is 56 4d 69 1d b8 48 ea 4f-fd 8e 1c 0d e2 1f 3b a9
Jun 29 00:14:08.054: vmx| AIOGNRC: numThreads=4 ide=1, scsi=0, passthru=1
Jun 29 00:14:08.054: vmx| WORKER: Creating new group with numThreads=4 (4)
Jun 29 00:14:08.054: vmx| Replay State = 0
Jun 29 00:14:08.054: vmx| minDEThreshold: 79
Jun 29 00:14:08.075: vmx| MM: Using partialmap, 65536 pages AC 0 CE 1 TM 0 DOHU 0
Jun 29 00:14:08.075: vmx| UUID: location-UUID is 56 4d 69 1d b8 48 ea 4f-fd 8e 1c 0d e2 1f 3b a9
Jun 29 00:14:08.099: vmx| FILE: ScanDirectory discarding M43626.lck from /var/lib/vmware/Virtual Machines/Workstation01/564d691d-b848-ea4f-fd8e-1c0de21f3ba9.vmem.lck': invalid executionID.
Jun 29 00:14:08.137: vmx| MM: using '/var/lib/vmware/Virtual Machines/Workstation01/564d691d-b848-ea4f-fd8e-1c0de21f3ba9.vmem' as a paging file
Jun 29 00:14:08.137: vmx| Opened paging file /var/lib/vmware/Virtual Machines/Workstation01/564d691d-b848-ea4f-fd8e-1c0de21f3ba9.vmem
Jun 29 00:14:08.141: vmx| Mapped mainmem as pageable
Jun 29 00:14:08.141: vmx| MMCHK: Disabling checks which are not forced, cannot do checks on certain processors.
Jun 29 00:14:08.141: vmx| MStat: Creating Stat vm.cpuusage
Jun 29 00:14:08.141: vmx| MStat: Creating Stat vm.ram
Jun 29 00:14:08.141: vmx| MStat: Creating Stat vm.uptime
Jun 29 00:14:08.141: vmx| MStat: Creating Stat vm.suspendTime
Jun 29 00:14:08.141: vmx| MStat: Creating Stat vm.powerOnTimeStamp
Jun 29 00:14:08.141: vmx| VMXAIOMGR: Using: simple=Generic unbuf=Generic
Jun 29 00:14:08.154: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Jun 29 00:14:08.161: vmx| DISK: OPEN ide0:0 '/var/lib/vmware/Virtual Machines/Workstation01/Workstation01.vmdk' persistent R[]
Jun 29 00:14:08.178: vmx| FILE: ScanDirectory discarding M35526.lck from /var/lib/vmware/Virtual Machines/Workstation01/Workstation01.vmdk.lck': invalid executionID.
Jun 29 00:14:08.765: vmx| DISKLIB-DSCPTR: Opened [0]: "Workstation01.vmdk" (0x2a)
Jun 29 00:14:08.765: vmx| DISKLIB-LINK : Opened '/var/lib/vmware/Virtual Machines/Workstation01/Workstation01.vmdk' (0x2a): monolithicSparse, 52428800 sectors / 25 GB.
Jun 29 00:14:08.765: vmx| DISKLIB-LIB : Opened "/var/lib/vmware/Virtual Machines/Workstation01/Workstation01.vmdk" (flags 0x2a).
Jun 29 00:14:08.765: vmx| DiskGetGeometry: Reading of disk partition table
Jun 29 00:14:08.778: vmx| DISK: OPEN '/var/lib/vmware/Virtual Machines/Workstation01/Workstation01.vmdk' Geo (16383/16/63) BIOS Geo (3263/255/63) freeSpace=93072 MB, ide
Jun 29 00:14:08.811: vmx| TimeTracker host to guest rate conversion 85914637296 @ 1591627000Hz -> 85914637296 @ 1591627000Hz
Jun 29 00:14:08.811: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0
Jun 29 00:14:08.812: vmx| USB: Initializing 'Generic' backend
Jun 29 00:14:08.812: vmx| USBGL: Usbfs found at /proc/bus/usb
Jun 29 00:14:08.813: vmx| USB: Initializing 'Virtual Hub' backend
Jun 29 00:14:08.813: vmx| USB: Initializing 'Virtual Mouse' backend
Jun 29 00:14:08.813: vmx| USB: Initializing 'Virtual Keyboard' backend
Jun 29 00:14:08.813: vmx| USB: Initializing 'Remote Device' backend
Jun 29 00:14:08.813: vmx| RemoteUSBVMX: no delay setting is TRUE.
Jun 29 00:14:08.813: vmx| USB: Initializing 'Virtual Mass Storage' backend
Jun 29 00:14:08.813: vmx| USB: Initializing 'Virtual CCID' backend
Jun 29 00:14:08.814: vmx| USB-CCID: CCID init: could not dlopen /usr/lib/vmware/bin/libdir/lib/libpcsclite.so.1.0.0/libpcsclite.so.1.0.0.
(/usr/lib/vmware/bin/libdir/lib/libpcsclite.so.1.0.0/libpcsclite.so.1.0.0: cannot open shared object file: No such file or directory)
Jun 29 00:14:08.814: vmx| USB-CCID: CCID init: could not dlopen /usr/lib/vmware/bin/../lib/libpcsclite.so.1.0.0/libpcsclite.so.1.0.0.
(/usr/lib/vmware/bin/../lib/libpcsclite.so.1.0.0/libpcsclite.so.1.0.0: cannot open shared object file: No such file or directory)
Jun 29 00:14:08.814: vmx| USB: Unable to initialize 'Virtual CCID' backend
Jun 29 00:14:08.821: vmx| Host display topology 2360x1770.
Jun 29 00:14:08.821: vmx| SVGA using 2360x1770.
Jun 29 00:14:08.830: vmx| MKS not attempting to initialize X.
Jun 29 00:14:08.833: vmx| WORKER: Creating new group with numThreads=1 (5)
Jun 29 00:14:08.834: vmx| MKS REMOTE Loading VNC Configuration from VM config file
Jun 29 00:14:08.836: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks
Jun 29 00:14:08.836: vmx| VMXNET: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks, dontClusterSize is 128
Jun 29 00:14:08.837: vmx| NetPkt: checksum cycles/kB: C=783 asm1=758 asm2=721
Jun 29 00:14:08.837: vmx| NetPkt: copy and sum cycles/kB: C=1033 asm1=791 asm2=760
Jun 29 00:14:08.842: vmx| Chipset version: 0x13
Jun 29 00:14:08.851: vmx| DISKUTIL: ide0:0 : capacity=52428800
Jun 29 00:14:08.866: vmx| MStat: Creating Stat ide0:0.bytesread
Jun 29 00:14:08.866: vmx| MStat: Creating Stat ide0:0.byteswritten
Jun 29 00:14:08.866: vmx| DISKUTIL: ide0:0 : capacity=52428800
Jun 29 00:14:08.866: vmx| DISKUTIL: ide0:0 : geometry=3263/255/63
Jun 29 00:14:08.866: vmx| MStat: Creating Stat ide1:0.bytesread
Jun 29 00:14:08.866: vmx| MStat: Creating Stat ide1:0.byteswritten
Jun 29 00:14:08.866: vmx| DISKUTIL: ide1:0 : capacity=0
Jun 29 00:14:08.866: vmx| DISKUTIL: ide1:0 : geometry=0/0/0
Jun 29 00:14:08.867: vmx| Host display topology 2360x1770 with 1 displays.
Jun 29 00:14:08.867: vmx| SVGA using 2360x1770.
Jun 29 00:14:08.867: vmx| USB: Initializing 'UHCI' host controller
Jun 29 00:14:08.868: vmx| MStat: Creating Stat ethernet0.bytesread
Jun 29 00:14:08.868: vmx| MStat: Creating Stat ethernet0.byteswritten
Jun 29 00:14:08.868: vmx| Ethernet0 MAC Address: 00:0c:29:1f:3b:a9
Jun 29 00:14:08.869: vmx| USB: Initializing 'EHCI' host controller
Jun 29 00:14:08.873: vmx| MStat: Creating Stat vm.heartbeat
Jun 29 00:14:08.874: vmx| DISKUTIL: ide0:0 : toolsVersion = 7397
Jun 29 00:14:08.874: vmx| TOOLS INSTALL initializing state to IDLE on power on.
Jun 29 00:14:08.916: vmx| PTSC to VMI Wallclock (nsec) 86084060388 @ 1591627000Hz -> 1246227248000000000 @ 1000000000Hz
Jun 29 00:14:08.916: vmx| PTSC to VMI Wallclock (nsec) ((x * 2698476022) >> 32) + 1246227193914425694
Jun 29 00:14:08.916: vmx| PTSC to ParaTime RealCycles 0 @ 1591627000Hz -> 0 @ 1591627000Hz
Jun 29 00:14:08.917: vmx| PTSC to ParaTime RealCycles ((x * 1) >> 0) + 0
Jun 29 00:14:08.917: vmx| ParaTime RealCycles to PTSC 0 @ 1591627000Hz -> 0 @ 1591627000Hz
Jun 29 00:14:08.917: vmx| ParaTime RealCycles to PTSC ((x * 1) >> 0) + 0
Jun 29 00:14:08.917: vmx| memoryHotplug: Current size = 256MB, Minimum size = 256MB, Maximum size = 256MB
Jun 29 00:14:08.917: vmx| memoryHotplug: Entry[0]: 00000000000000A0-00000000000A0000
Jun 29 00:14:08.917: vmx| memoryHotplug: Entry[1]: 00000000001000A0-0000000010000000
Jun 29 00:14:08.920: vmx| guestCpuFeatures = 0x404001f8
Jun 29 00:14:08.921: vmx| guestCPUID vendor: GenuntelineI
Jun 29 00:14:08.921: vmx| guestCPUID name: Intel(R) Celeron(R) M CPU 420 @ 1.60GHz
Jun 29 00:14:08.921: vmx| guestCPUID level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Jun 29 00:14:08.921: vmx| guestCPUID level 00000001, 0: 0x000006e8 0x00010800 0x80000001 0x0fe9fbff
Jun 29 00:14:08.921: vmx| guestCPUID level 00000002, 0: 0x02b3b001 0x000000f0 0x00000000 0x2c043078
Jun 29 00:14:08.921: vmx| guestCPUID level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:08.921: vmx| guestCPUID level 00000004, 0: 0x00000121 0x01c0003f 0x0000003f 0x00000001
Jun 29 00:14:08.921: vmx| guestCPUID level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00001110
Jun 29 00:14:08.921: vmx| guestCPUID level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Jun 29 00:14:08.921: vmx| guestCPUID level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:08.921: vmx| guestCPUID level 00000008, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:08.921: vmx| guestCPUID level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:08.921: vmx| guestCPUID level 0000000a, 0: 0x07280201 0x00000000 0x00000000 0x00000000
Jun 29 00:14:08.921: vmx| guestCPUID level 40000000, 0: 0x40000010 0x61774d56 0x4d566572 0x65726177
Jun 29 00:14:08.921: vmx| guestCPUID level 40000010, 0: 0x0018494b 0x000101d0 0x00000000 0x00000000
Jun 29 00:14:08.921: vmx| guestCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Jun 29 00:14:08.921: vmx| guestCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000000 0x00100000
Jun 29 00:14:08.921: vmx| guestCPUID level 80000002, 0: 0x65746e49 0x2952286c 0x6c654320 0x6e6f7265
Jun 29 00:14:08.921: vmx| guestCPUID level 80000003, 0: 0x20295228 0x5043204d 0x20202055 0x20202020
Jun 29 00:14:08.921: vmx| guestCPUID level 80000004, 0: 0x30323420 0x20402020 0x30362e31 0x007a4847
Jun 29 00:14:08.921: vmx| guestCPUID level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:08.921: vmx| guestCPUID level 80000006, 0: 0x00000000 0x00000000 0x04004040 0x00000000
Jun 29 00:14:08.921: vmx| guestCPUID level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Jun 29 00:14:08.921: vmx| guestCPUID level 80000008, 0: 0x00002028 0x00000000 0x00000000 0x00000000
Jun 29 00:14:08.933: vmx| BusMemSample: initPercent 75 touched 0
Jun 29 00:14:08.993: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1
Jun 29 00:14:08.993: vmx| VMX setting maximum IPC write buffers to 0 packets, 0 bytes
Jun 29 00:14:08.993: mks| Async MKS thread is alive
Jun 29 00:14:09.003: vcpu-0| APIC: version = 0x14, max LVT = 5
Jun 29 00:14:09.004: vcpu-0| APIC: LDR = 0x1000000, DFR = 0xffffffff
Jun 29 00:14:09.022: vcpu-0| guestCpuFeatures = 0x404001f8
Jun 29 00:14:09.067: vcpu-0| CPU reset: hard
Jun 29 00:14:09.067: vcpu-0| memoryHotplug: Entry[0]: 00000000000000A0-00000000000A0000
Jun 29 00:14:09.067: vcpu-0| memoryHotplug: Entry[1]: 00000000001000A0-0000000010000000
Jun 29 00:14:09.094: vcpu-0| VNET: Notification enabled for Ethernet0
Jun 29 00:14:09.118: vcpu-0| CDROM: Connecting ide1:0 to '/home/shares/iso/winxp sp2 corp.iso'. img=1 raw=0 remote=0
Jun 29 00:14:09.121: vcpu-0| Transitioned vmx/execState/val to poweredOn
Jun 29 00:14:09.196: vcpu-0| sz=3002336
Jun 29 00:14:09.199: vcpu-0| vmm32 initialized: Releasebuild-156745. cflags: 0x00000002.00000080.03000180.00000900
Jun 29 00:14:09.202: vcpu-0| MonitorInitNumaUnmapVMM32
Jun 29 00:14:10.854: vmx| SOCKET 2 (298) recv error 0: Success
Jun 29 00:14:11.021: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0x0) and 0xd8000000(0x0)
Jun 29 00:14:11.027: vcpu-0| SVGA: Unregistering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Jun 29 00:14:11.351: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Jun 29 00:14:11.371: vcpu-0| SVGA: Unregistering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Jun 29 00:14:11.376: vcpu-0| SVGA: Registering IOSpace at 0x10d0
Jun 29 00:14:11.376: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Jun 29 00:14:11.386: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.388: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.389: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.390: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.404: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.405: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.406: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.408: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.419: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.421: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.422: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.423: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.425: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.426: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.427: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.429: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.440: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.442: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.443: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.444: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.446: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.447: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.448: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.450: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.457: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.458: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.460: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.471: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.472: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.474: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.475: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:11.476: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.260: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.262: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.281: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.283: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.284: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.285: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.287: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.288: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.290: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.291: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.292: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.302: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.303: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.304: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.306: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.307: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.308: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.310: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.317: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.319: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.320: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.331: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.333: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.334: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.336: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.337: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.338: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.340: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.345: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.346: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.348: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.360: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:12.420: vcpu-0| VIDE: Curr CHS info cyls: 17475 heads: 15 sects: 63 lba_cap: 52428800
Jun 29 00:14:12.513: vcpu-0| BIOS-UUID is 56 4d 69 1d b8 48 ea 4f-fd 8e 1c 0d e2 1f 3b a9
Jun 29 00:14:17.533: mks| SVGA: display status changed, using optimizations for local consoles.
Jun 29 00:14:40.098: vcpu-0| SVGA: Unregistering IOSpace at 0x10d0
Jun 29 00:14:40.098: vcpu-0| SVGA: Unregistering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Jun 29 00:14:40.103: vcpu-0| SVGA: Registering IOSpace at 0x10d0
Jun 29 00:14:40.104: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Jun 29 00:14:40.132: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.134: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.140: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.141: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.145: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.145: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.147: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.148: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.150: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.150: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.300: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.300: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.303: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.303: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.314: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.315: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.316: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.316: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.319: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.319: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.321: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.322: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.324: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.324: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.327: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.327: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.373: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.374: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.376: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.376: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.428: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.429: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.431: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.431: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.433: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.434: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.462: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.463: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.465: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.465: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.468: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.468: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.470: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.470: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.472: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.473: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.475: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.475: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.477: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.477: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.479: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.480: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.482: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.482: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.484: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.484: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.503: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.503: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.522: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.523: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.525: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.525: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.527: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:40.528: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:47.937: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:48.439: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:48.795: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:48.990: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:49.584: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:49.748: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:49.943: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:50.189: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:50.454: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:50.829: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:50.920: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:51.428: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:51.582: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:51.756: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:51.787: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:51.964: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:52.220: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:52.316: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:52.438: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:52.735: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:53.003: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:53.067: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:53.179: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:53.965: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:54.151: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:54.308: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:54.428: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:54.595: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:54.884: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:55.495: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:55.662: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:14:55.893: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:15:06.487: vcpu-0| VIDE: Curr CHS info cyls: 17475 heads: 15 sects: 63 lba_cap: 52428800
Jun 29 00:15:07.490: vcpu-0| CDROM: Mode Sense for Unsupported Page 0x1B
Jun 29 00:15:07.490: vcpu-0| SCSI DEVICE (ide1:0): MODE SENSE(10) for unsupported page 0x1b
Jun 29 00:15:08.281: vcpu-0| VIDE: (0x1f0) OUTB Cmd 0xf5, Unknown ATA Command on drive 0
Jun 29 00:15:44.594: vmx| ide0:0: Command READ(10) took 1.778 seconds (ok)
Jun 29 00:15:56.315: vmx| ide0:0: Command WRITE(10) took 1.614 seconds (ok)
Jun 29 00:15:58.200: vmx| ide0:0: Command WRITE(10) took 1.881 seconds (ok)
Jun 29 00:15:59.593: vmx| ide0:0: Command WRITE(10) took 1.132 seconds (ok)
Jun 29 00:16:01.302: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Jun 29 00:16:01.302: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Jun 29 00:16:01.303: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Jun 29 00:16:01.303: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Jun 29 00:16:02.271: vcpu-0| SVGA: Unregistering IOSpace at 0x10d0
Jun 29 00:16:02.271: vcpu-0| SVGA: Unregistering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Jun 29 00:16:02.277: vcpu-0| SVGA: Registering IOSpace at 0x10d0
Jun 29 00:16:02.277: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Jun 29 00:16:02.438: vcpu-0| Guest OS = 0x5008
Jun 29 00:16:02.663: vcpu-0| UHCI: Global Reset
Jun 29 00:16:02.705: vcpu-0| VNET: Notification enabled for Ethernet0
Jun 29 00:16:02.713: vcpu-0| Guest: VMXNET: Initialization completed successfully. Version 2.0 Jul 9 2008.
Jun 29 00:16:02.714: vcpu-0| Guest: VMXNET: Features:
Jun 29 00:16:02.715: vcpu-0| Guest: VMXNET: Vmxnet rx ringLen1 = 100, ringLen2 = 1
Jun 29 00:16:04.669: vmx| ide0:0: Command WRITE(10) took 1.299 seconds (ok)
Jun 29 00:16:18.941: mks| MKS switching absolute mouse on
Jun 29 00:16:28.155: vmx| ide0:0: Command WRITE(10) took 1.252 seconds (ok)
Jun 29 00:16:29.634: vmx| ide0:0: Command WRITE(10) took 1.478 seconds (ok)
Jun 29 00:16:32.716: vmx| ide0:0: Command READ(10) took 1.399 seconds (ok)
Jun 29 00:16:34.905: vmx| ide0:0: Command WRITE(10) took 1.297 seconds (ok)
Jun 29 00:17:11.337: vcpu-0| ide0:0: Command READ(10) took 3.274 seconds (ok)
Jun 29 00:17:14.268: vmx| ide0:0: Command READ(10) took 1.299 seconds (ok)
Jun 29 00:17:27.733: vmx| ide0:0: Command READ(10) took 1.124 seconds (ok)
Jun 29 00:17:51.981: vmx| ide0:0: Command READ(10) took 1.739 seconds (ok)
Jun 29 00:18:01.842: vmx| ide0:0: Command WRITE(10) took 4.105 seconds (ok)
Jun 29 00:18:09.275: vmx| ide0:0: Command READ(10) took 1.059 seconds (ok)
Jun 29 00:18:15.875: vmx| ide0:0: Command READ(10) took 2.299 seconds (ok)
Jun 29 00:18:27.114: vmx| ide0:0: Command WRITE(10) took 1.390 seconds (ok)
Jun 29 00:18:36.241: vmx| ide0:0: Command WRITE(10) took 1.539 seconds (ok)
Jun 29 00:18:47.222: vmx| ide0:0: Command WRITE(10) took 5.633 seconds (ok)
Jun 29 00:18:50.304: vmx| ide0:0: Command WRITE(10) took 1.848 seconds (ok)
Jun 29 00:18:52.535: vmx| ide0:0: Command WRITE(10) took 2.203 seconds (ok)
Jun 29 00:19:00.251: vmx| ide0:0: Command WRITE(10) took 7.715 seconds (ok)
Jun 29 00:19:01.297: vmx| ide0:0: Command WRITE(10) took 1.045 seconds (ok)
Jun 29 00:19:02.812: vmx| ide0:0: Command WRITE(10) took 1.514 seconds (ok)
Jun 29 00:19:05.622: vmx| ide0:0: Command WRITE(10) took 2.809 seconds (ok)
Jun 29 00:19:10.089: vmx| ide0:0: Command WRITE(10) took 1.764 seconds (ok)
Jun 29 00:19:14.770: vmx| ide0:0: Command WRITE(10) took 1.500 seconds (ok)
Jun 29 00:19:16.302: vmx| ide0:0: Command WRITE(10) took 1.531 seconds (ok)
Jun 29 00:19:19.192: vmx| ide0:0: Command WRITE(10) took 2.481 seconds (ok)
Jun 29 00:19:32.323: vmx| ide0:0: Command WRITE(10) took 2.064 seconds (ok)
Jun 29 00:19:35.240: vmx| ide0:0: Command WRITE(10) took 2.915 seconds (ok)
Jun 29 00:19:37.175: vmx| ide0:0: Command WRITE(10) took 1.740 seconds (ok)
Jun 29 00:19:40.565: vmx| ide0:0: Command WRITE(10) took 3.390 seconds (ok)
Jun 29 00:19:43.978: vmx| ide0:0: Command WRITE(10) took 3.413 seconds (ok)
Jun 29 00:19:45.857: vmx| ide0:0: Command WRITE(10) took 1.879 seconds (ok)
Jun 29 00:19:48.030: vmx| ide0:0: Command WRITE(10) took 2.172 seconds (ok)
Jun 29 00:19:52.228: vmx| ide0:0: Command WRITE(10) took 1.120 seconds (ok)
Jun 29 00:19:54.178: vmx| ide0:0: Command WRITE(10) took 1.827 seconds (ok)
Jun 29 00:20:00.756: vmx| ide0:0: Command WRITE(10) took 6.577 seconds (ok)
Jun 29 00:20:04.826: vmx| ide0:0: Command WRITE(10) took 3.816 seconds (ok)
Jun 29 00:20:06.808: vmx| ide0:0: Command WRITE(10) took 1.981 seconds (ok)
Jun 29 00:20:10.924: vmx| ide0:0: Command WRITE(10) took 1.405 seconds (ok)
Jun 29 00:20:14.214: vmx| ide0:0: Command WRITE(10) took 1.783 seconds (ok)
Jun 29 00:20:27.942: vmx| ide0:0: Command WRITE(10) took 13.057 seconds (ok)
Jun 29 00:20:31.496: vmx| ide0:0: Command WRITE(10) took 3.553 seconds (ok)
Jun 29 00:20:37.677: vmx| ide0:0: Command WRITE(10) took 3.387 seconds (ok)
Jun 29 00:20:40.287: vmx| ide0:0: Command WRITE(10) took 1.284 seconds (ok)
Jun 29 00:20:44.487: vmx| ide0:0: Command WRITE(10) took 1.412 seconds (ok)
Jun 29 00:20:47.045: vmx| ide0:0: Command WRITE(10) took 1.265 seconds (ok)
Jun 29 00:20:48.872: vmx| ide0:0: Command WRITE(10) took 1.827 seconds (ok)
Jun 29 00:20:52.485: vmx| ide0:0: Command WRITE(10) took 1.033 seconds (ok)
Jun 29 00:20:58.904: vmx| ide0:0: Command WRITE(10) took 6.032 seconds (ok)
Jun 29 00:21:03.457: vmx| ide0:0: Command WRITE(10) took 2.328 seconds (ok)
Jun 29 00:21:05.495: vmx| ide0:0: Command WRITE(10) took 2.038 seconds (ok)
Jun 29 00:21:09.614: vmx| ide0:0: Command WRITE(10) took 2.953 seconds (ok)
Jun 29 00:21:14.596: vmx| ide0:0: Command WRITE(10) took 4.982 seconds (ok)
Jun 29 00:21:16.943: vmx| ide0:0: Command WRITE(10) took 1.820 seconds (ok)
Jun 29 00:21:21.928: vmx| ide0:0: Command WRITE(10) took 1.735 seconds (ok)
Jun 29 00:21:23.414: vmx| ide0:0: Command WRITE(10) took 1.484 seconds (ok)
Jun 29 00:21:28.440: vmx| ide0:0: Command WRITE(10) took 4.176 seconds (ok)
Jun 29 00:21:36.695: vmx| ide0:0: Command WRITE(10) took 1.440 seconds (ok)
Jun 29 00:21:42.434: vmx| ide0:0: Command WRITE(10) took 1.235 seconds (ok)
Jun 29 00:21:49.084: vmx| ide0:0: Command WRITE(10) took 1.886 seconds (ok)
Jun 29 00:21:52.090: vmx| ide0:0: Command WRITE(10) took 1.462 seconds (ok)
Jun 29 00:21:53.296: vmx| ide0:0: Command WRITE(10) took 1.202 seconds (ok)
Jun 29 00:22:03.052: vmx| ide0:0: Command WRITE(10) took 8.518 seconds (ok)
Jun 29 00:22:05.076: vmx| ide0:0: Command WRITE(10) took 1.403 seconds (ok)
Jun 29 00:22:11.533: vmx| ide0:0: Command WRITE(10) took 3.335 seconds (ok)
Jun 29 00:22:14.273: vmx| ide0:0: Command WRITE(10) took 1.141 seconds (ok)
Jun 29 00:22:15.921: vmx| ide0:0: Command WRITE(10) took 1.648 seconds (ok)
Jun 29 00:22:20.035: vmx| ide0:0: Command WRITE(10) took 1.366 seconds (ok)
Jun 29 00:22:23.416: vmx| ide0:0: Command WRITE(10) took 1.593 seconds (ok)
Jun 29 00:22:26.955: vmx| ide0:0: Command WRITE(10) took 1.575 seconds (ok)
Jun 29 00:22:32.659: vmx| ide0:0: Command WRITE(10) took 1.139 seconds (ok)
Jun 29 00:23:00.863: vmx| ide0:0: Command READ(10) took 16.376 seconds (ok)
Jun 29 00:23:18.532: vmx| ide0:0: Command READ(10) took 12.150 seconds (ok)
Jun 29 00:23:30.473: vmx| ide0:0: Command WRITE(10) took 1.522 seconds (ok)
Jun 29 00:23:32.446: vmx| ide0:0: Command WRITE(10) took 1.946 seconds (ok)
Jun 29 00:23:36.707: vmx| ide0:0: Command WRITE(10) took 4.260 seconds (ok)
Jun 29 00:23:38.322: vmx| ide0:0: Command WRITE(10) took 1.127 seconds (ok)
Jun 29 00:23:53.109: vmx| ide0:0: Command WRITE(10) took 4.179 seconds (ok)
Jun 29 00:24:00.861: vmx| ide0:0: Command WRITE(10) took 3.749 seconds (ok)
Jun 29 00:24:08.881: vmx| TOOLS setting the tools version to '0'
Jun 29 00:24:08.881: vmx| DISKLIB-DDB : "toolsVersion" = "0" (was "7397")
Jun 29 00:24:08.968: vmx| ide0:0: Command WRITE(10) took 1.662 seconds (ok)
Jun 29 00:24:14.761: vmx| ide0:0: Command WRITE(10) took 3.674 seconds (ok)
Jun 29 00:24:20.823: vmx| ide0:0: Command WRITE(10) took 6.061 seconds (ok)
Jun 29 00:24:23.957: vmx| ide0:0: Command WRITE(10) took 2.623 seconds (ok)
Jun 29 00:24:37.059: vmx| ide0:0: Command WRITE(10) took 12.105 seconds (ok)
Jun 29 00:24:39.662: vmx| ide0:0: Command WRITE(10) took 2.602 seconds (ok)
Jun 29 00:24:41.879: vmx| ide0:0: Command WRITE(10) took 2.182 seconds (ok)
Jun 29 00:24:45.023: vmx| ide0:0: Command WRITE(10) took 1.509 seconds (ok)
Jun 29 00:24:49.687: vmx| ide0:0: Command WRITE(10) took 4.659 seconds (ok)
Jun 29 00:24:53.283: vmx| ide0:0: Command WRITE(10) took 2.917 seconds (ok)
Jun 29 00:24:56.719: vmx| ide0:0: Command WRITE(10) took 2.666 seconds (ok)
Jun 29 00:24:59.704: vmx| ide0:0: Command WRITE(10) took 2.072 seconds (ok)
Jun 29 00:25:01.849: vmx| ide0:0: Command WRITE(10) took 2.131 seconds (ok)
Jun 29 00:25:07.152: vmx| ide0:0: Command WRITE(10) took 5.302 seconds (ok)
Jun 29 00:25:19.358: vmx| ide0:0: Command WRITE(10) took 12.204 seconds (ok)
Jun 29 00:25:23.060: vmx| ide0:0: Command WRITE(10) took 1.127 seconds (ok)
Jun 29 00:25:26.998: vmx| ide0:0: Command WRITE(10) took 3.467 seconds (ok)
Jun 29 00:25:29.460: vmx| ide0:0: Command WRITE(10) took 1.616 seconds (ok)
Jun 29 00:25:30.549: vmx| ide0:0: Command WRITE(10) took 1.088 seconds (ok)
Jun 29 00:25:34.917: vmx| ide0:0: Command WRITE(10) took 3.600 seconds (ok)
Jun 29 00:25:37.447: vmx| ide0:0: Command WRITE(10) took 1.264 seconds (ok)
Jun 29 00:25:45.016: vmx| ide0:0: Command WRITE(10) took 1.026 seconds (ok)
Jun 29 00:26:00.996: vmx| ide0:0: Command WRITE(10) took 14.767 seconds (ok)
Jun 29 00:26:17.873: vmx| ide0:0: Command WRITE(10) took 1.127 seconds (ok)
Jun 29 00:26:20.492: vmx| ide0:0: Command WRITE(10) took 1.125 seconds (ok)
Jun 29 00:26:24.775: vmx| ide0:0: Command WRITE(10) took 2.551 seconds (ok)
Jun 29 00:26:28.557: vmx| ide0:0: Command WRITE(10) took 1.886 seconds (ok)
Jun 29 00:26:29.582: vmx| ide0:0: Command WRITE(10) took 1.020 seconds (ok)
Jun 29 00:26:30.587: vmx| ide0:0: Command WRITE(10) took 1.004 seconds (ok)
Jun 29 00:26:35.367: vmx| ide0:0: Command WRITE(10) took 1.646 seconds (ok)
Jun 29 00:26:39.424: vmx| ide0:0: Command WRITE(10) took 1.002 seconds (ok)
Jun 29 00:26:47.859: vmx| ide0:0: Command WRITE(10) took 3.278 seconds (ok)
Jun 29 00:27:08.957: vmx| ide0:0: Command WRITE(10) took 5.182 seconds (ok)
Jun 29 00:27:19.120: vmx| ide0:0: Command WRITE(10) took 3.151 seconds (ok)
Jun 29 00:27:24.755: vmx| ide0:0: Command WRITE(10) took 5.628 seconds (ok)
Jun 29 00:27:46.830: vmx| ide0:0: Command WRITE(10) took 12.825 seconds (ok)
Jun 29 00:27:53.114: vmx| ide0:0: Command WRITE(10) took 5.814 seconds (ok)
Jun 29 00:28:01.562: vmx| ide0:0: Command WRITE(10) took 8.160 seconds (ok)
Jun 29 00:28:14.474: vmx| ide0:0: Command WRITE(10) took 12.911 seconds (ok)
Jun 29 00:28:16.591: vmx| ide0:0: Command WRITE(10) took 1.305 seconds (ok)
Jun 29 00:28:22.363: vmx| ide0:0: Command WRITE(10) took 5.350 seconds (ok)
Jun 29 00:28:25.290: vmx| ide0:0: Command WRITE(10) took 1.356 seconds (ok)
Jun 29 00:28:31.752: vmx| ide0:0: Command WRITE(10) took 6.460 seconds (ok)
Jun 29 00:28:37.817: vmx| ide0:0: Command WRITE(10) took 6.064 seconds (ok)
Jun 29 00:28:43.496: vmx| ide0:0: Command WRITE(10) took 3.764 seconds (ok)
Jun 29 00:28:46.146: vmx| ide0:0: Command WRITE(10) took 1.857 seconds (ok)
Jun 29 00:28:47.974: vmx| ide0:0: Command WRITE(10) took 1.826 seconds (ok)
Jun 29 00:28:49.952: vmx| ide0:0: Command WRITE(10) took 1.101 seconds (ok)
Jun 29 00:28:53.077: vmx| ide0:0: Command WRITE(10) took 2.139 seconds (ok)
Jun 29 00:28:54.187: vmx| ide0:0: Command WRITE(10) took 1.108 seconds (ok)
Jun 29 00:28:56.148: vmx| ide0:0: Command WRITE(10) took 1.426 seconds (ok)
Jun 29 00:29:00.730: vmx| ide0:0: Command WRITE(10) took 2.855 seconds (ok)
Jun 29 00:29:06.523: vmx| ide0:0: Command WRITE(10) took 5.792 seconds (ok)
Jun 29 00:29:09.953: vmx| ide0:0: Command WRITE(10) took 3.368 seconds (ok)
Jun 29 00:29:11.032: vmx| ide0:0: Command WRITE(10) took 1.077 seconds (ok)
Jun 29 00:29:19.077: vmx| ide0:0: Command WRITE(10) took 1.900 seconds (ok)
Jun 29 00:29:20.272: vmx| ide0:0: Command WRITE(10) took 1.194 seconds (ok)
Jun 29 00:29:24.136: vmx| ide0:0: Command WRITE(10) took 2.154 seconds (ok)
Jun 29 00:29:29.455: vmx| ide0:0: Command WRITE(10) took 4.472 seconds (ok)
Jun 29 00:29:30.882: vmx| ide0:0: Command WRITE(10) took 1.426 seconds (ok)
Jun 29 00:29:32.654: vmx| ide0:0: Command WRITE(10) took 1.771 seconds (ok)
Jun 29 00:29:33.909: vmx| ide0:0: Command WRITE(10) took 1.254 seconds (ok)
Jun 29 00:29:36.963: vmx| ide0:0: Command WRITE(10) took 1.014 seconds (ok)
Jun 29 00:29:38.326: vmx| ide0:0: Command WRITE(10) took 1.362 seconds (ok)
Jun 29 00:29:45.638: vcpu-0| ide0:0: Command READ(10) took 3.625 seconds (ok)
Jun 29 00:29:57.065: vcpu-0| ide0:0: Command READ(10) took 5.492 seconds (ok)
Jun 29 00:30:00.626: vmx| ide0:0: Command WRITE(10) took 1.793 seconds (ok)
Jun 29 00:30:05.291: vmx| ide0:0: Command WRITE(10) took 1.980 seconds (ok)
Jun 29 00:30:10.166: vmx| ide0:0: Command WRITE(10) took 3.801 seconds (ok)
Jun 29 00:30:12.119: vmx| ide0:0: Command WRITE(10) took 1.293 seconds (ok)
Jun 29 00:30:17.441: vmx| ide0:0: Command WRITE(10) took 1.740 seconds (ok)
Jun 29 00:30:22.165: vmx| ide0:0: Command WRITE(10) took 1.449 seconds (ok)
Jun 29 00:30:24.922: vmx| ide0:0: Command WRITE(10) took 1.857 seconds (ok)
Jun 29 00:30:27.641: vmx| ide0:0: Command WRITE(10) took 2.059 seconds (ok)
Jun 29 00:30:29.375: vmx| ide0:0: Command WRITE(10) took 1.733 seconds (ok)
Jun 29 00:30:35.051: vmx| ide0:0: Command WRITE(10) took 2.645 seconds (ok)
Jun 29 00:30:39.751: vmx| ide0:0: Command WRITE(10) took 4.699 seconds (ok)
Jun 29 00:30:44.904: vmx| ide0:0: Command WRITE(10) took 1.606 seconds (ok)
Jun 29 00:30:47.115: vmx| ide0:0: Command WRITE(10) took 1.041 seconds (ok)
Jun 29 00:30:52.162: vmx| ide0:0: Command WRITE(10) took 1.774 seconds (ok)
Jun 29 00:31:03.297: vmx| ide0:0: Command WRITE(10) took 1.087 seconds (ok)
Jun 29 00:31:06.208: vmx| ide0:0: Command WRITE(10) took 1.570 seconds (ok)
Jun 29 00:31:07.263: vmx| ide0:0: Command WRITE(10) took 1.054 seconds (ok)
Jun 29 00:31:10.196: vmx| ide0:0: Command WRITE(10) took 2.044 seconds (ok)
Jun 29 00:31:14.687: vmx| ide0:0: Command WRITE(10) took 1.281 seconds (ok)
Jun 29 00:31:17.145: vmx| ide0:0: Command WRITE(10) took 1.245 seconds (ok)
Jun 29 00:31:30.655: vmx| ide0:0: Command WRITE(10) took 1.229 seconds (ok)
Jun 29 00:31:34.111: vmx| ide0:0: Command WRITE(10) took 2.396 seconds (ok)
Jun 29 00:31:47.813: vmx| ide0:0: Command WRITE(10) took 1.416 seconds (ok)
Jun 29 00:31:52.119: vmx| ide0:0: Command WRITE(10) took 1.885 seconds (ok)
Jun 29 00:31:54.868: vmx| ide0:0: Command WRITE(10) took 1.322 seconds (ok)
Jun 29 00:31:57.569: vmx| ide0:0: Command WRITE(10) took 1.283 seconds (ok)
Jun 29 00:31:59.020: vmx| ide0:0: Command WRITE(10) took 1.039 seconds (ok)
Jun 29 00:32:02.060: vmx| ide0:0: Command WRITE(10) took 2.658 seconds (ok)
Jun 29 00:32:22.958: vmx| ide0:0: Command WRITE(10) took 2.689 seconds (ok)
Jun 29 00:32:24.809: vmx| ide0:0: Command WRITE(10) took 1.326 seconds (ok)
Jun 29 00:32:27.180: vmx| ide0:0: Command WRITE(10) took 1.600 seconds (ok)
Jun 29 00:32:29.765: vmx| ide0:0: Command WRITE(10) took 1.694 seconds (ok)
Jun 29 00:32:32.723: vmx| ide0:0: Command WRITE(10) took 2.294 seconds (ok)
Jun 29 00:32:35.867: vmx| ide0:0: Command WRITE(10) took 1.400 seconds (ok)
Jun 29 00:33:03.019: vmx| ide0:0: Command WRITE(10) took 1.630 seconds (ok)
Jun 29 00:33:08.376: vmx| ide0:0: Command WRITE(10) took 5.341 seconds (ok)
Jun 29 00:33:12.442: vmx| ide0:0: Command WRITE(10) took 1.104 seconds (ok)
Jun 29 00:33:30.548: vmx| ide0:0: Command WRITE(10) took 1.143 seconds (ok)
Jun 29 00:34:23.706: vmx| ide0:0: Command WRITE(10) took 2.750 seconds (ok)
Jun 29 00:34:26.291: vmx| ide0:0: Command WRITE(10) took 2.584 seconds (ok)
Jun 29 00:34:28.762: vmx| ide0:0: Command WRITE(10) took 1.002 seconds (ok)
Jun 29 00:34:36.185: vmx| ide0:0: Command WRITE(10) took 1.712 seconds (ok)
Jun 29 00:34:40.018: vmx| ide0:0: Command WRITE(10) took 1.652 seconds (ok)
Jun 29 00:35:07.107: vcpu-0| ide0:0: Command READ(10) took 2.974 seconds (ok)
Jun 29 00:35:10.250: vmx| ide0:0: Command WRITE(10) took 1.268 seconds (ok)
Jun 29 00:35:19.889: vmx| ide0:0: Command WRITE(10) took 1.425 seconds (ok)
Jun 29 00:35:42.391: vcpu-0| VNET: Notification enabled for Ethernet0
Jun 29 00:35:59.030: vcpu-0| UHCI: HCReset
Jun 29 00:35:59.055: vcpu-0| CPU reset: soft
Jun 29 00:35:59.357: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.358: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.358: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.358: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.358: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.358: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.358: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.358: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.359: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.359: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.359: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.359: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Jun 29 00:35:59.359: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ct

[Dayworker]

Jun 29 00:14:07.177: vmx| CPUID[0] name: Intel(R) Celeron(R) M CPU 420 @ 1.60GHz

Jun 29 00:14:07.542: vmx| DICT memsize = 256

Jun 29 00:14:08.765: vmx| DISKLIB-LINK : Opened '/var/lib/vmware/Virtual Machines/Workstation01/Workstation01.vmdk' (0x2a): monolithicSparse, 52428800 sectors / 25 GB.

Also erstmal ist dein Host etwas schwach auf der Brust mit seinem Singlecore Celeron, dadurch bleibt eh nur wenig Leistung für eine VM übrig. Dazu kommt zusätzlich, daß die VM nur auf 256MB RAM zugreifen darf (512MB sollte XP schon haben und der Host hat 2GB RAM zur Verfügung) und als Bonbon ist die v.HDD auch noch als Sparse, also mitwachsend, angelegt. Des weiteren finde ich keine Eintragung für eine Speicherreservierung für VMware in den "HOST DEFAULTS".

Da gibts also mehrere Baustellen, wo du ohne finanziellen Aufwand was verändern kannst.

[sunghost]

Hi,
da magst du recht haben, allerdings ist die CPU nicht so ausgelastet wie die Platte. MS empfiehlt für XP min 256MB und das hat sie bekommen. Davon wird außerdem nur 85-90Prozent verwendet, also langt es, da läuft ja auch nichts wildes Mitwachsend, deshalb, damit nicht der gesamte Platz sofort weg ist, sondern nur für den Notfall das Maximum von 20GB benutzt wird.
Wie meinst du das mit der Reservierung für den Server? Was noch sein könnte, das fiel mir später ein, dass die anderen 2 System evtl. auch öfter mal etwas auslagern und daher der Schreib- /Lesekopf ständig hin und her springen muss und daher nicht die volle Performance der HDD erreicht wird.

[continuum]

sparse Platten koennen, wenn sie nicht regelmaessig geshrinkt werden , extrem langsam werden - manchmal bis zur voelligen Unbrauchbarkeit ...

Wann hast du also zum letzten mal geshrinkt ?

[sunghost]

mh. Ich bin bei der Erstellung der Platte davon ausgegangen, dass die virtuelle Platte nur bis zur Maximalgröße wachsen kann. Antwort noch nie.

[e-e-e]

Hallo,

übrigens, wenn Dein vRAM auch "nur" mit max. 85-90% ausgelastet ist, wird das XP swappen und damit tüchtig die vHDD belasten.

[sunghost]

Richtig ist mir bekannt. Ich kann mir auf dem Wirt die IOLast live ansehen und sehe dort grade mal eine Spitze von 7MB und das hatte mich etwas stutzig gemacht. Nun kann es sein, dass es falsche Werte anzeigt, aber eine 4,5GB große Iso zu entpacken dauert länger als 20 Minuten. Da dies hauptsächlich die (natürlich auch CPU) Festplatte übernimmt, kam mir das etwas komsich vor.

[Dayworker]

mh. Ich bin bei der Erstellung der Platte davon ausgegangen, dass die virtuelle Platte nur bis zur Maximalgröße wachsen kann. Antwort noch nie.

Im Gast ist das schon richtig, der sieht immer seine komplette v.HDD-Größe. Aber auf dem Host verteilen sich die Datenfragmente der v.HDD quer über alle Sektoren seiner HDD. Je nach dem wie stark die Fragmentierung fortgeschritten ist, kann eine VM dadurch auch stehenbleiben.

Um die möglichen Performance einer v.HDD abzuschätzen, mußt du einfach nur 25-33% der Datenrate vom Host-LW abziehen. Wenn die Host-CPU sehr langsam ist, kann die Performance auch um mehr als 50% einbrechen.

[continuum]

wenn du die VM noch nie geshrinkt hast brauchen wir derzeit nicht weiter diskutieren
Shrink die VM jetzt als aller erstes

[sunghost]

Ich habe die Windows XP VM nun geshrink. Wenn ich nun eine iso entpacke, dann dauert es ewig. Soll heißen, er entpackt statt wie vorher mit 1,3MB nur mit 300kb. Irgendwie war das shrinken nicht so gut. Ach ja, das Hostsystem ist dabei nicht ausgelastet.

[continuum]

Irgendwie war das shrinken nicht so gut.

? - schaden kann es aber auch nicht - um wieviel ist die Platte denn kleiner geworden ?

[sunghost]

Schwer zu sagen, da die ISO mit fast 7GB entpackt wurde. Ca. 2GB - hatte vorher leider nicht nachgesehen.
Ich werde das Thema wohl hier abhaken. Eingangs dachte ich, dass es ein paar Schrauben gibt, an denen man rumdrehen kann. Aber danke für eure Hilfe.

[continuum]

wenn du solche aktionen mit der VM machen willst wie riesige ISOS entpacken dann bist du mit einer wachsenden VMDK denkbar schlecht bedient.
Warum verwendest du keine preallocated vmdk ?

[sunghost]

Ich wollte den Speicherplatz nicht gleich von vornerein belegen, da ich davon ausgegangen bin, dass ich nicht alles brauche. Ich werde sonst mal eine VM mit fester Größe anlegen und das dann noch mal testen.

[Dayworker]

Dazu mußt du keine neue VMDK anlegen, nutz den "vmware-vdiskmanager".

Code: Alles auswählen

vmware-vdiskmanger -r old.vmdk -t 3 neu.vmdk

...konvertiert dir deine vorhandene in eine preallocated Disk um und läßt die alte als Backup stehen. Du mußt dann nur noch die VMX entsprechend auf den neuen v.HDD-Namen anpassen.

[sunghost]

Sicher das ich Typ 3 nehmen soll?

Disk types:
0 : single growable virtual disk
1 : growable virtual disk split in 2GB files
2 : preallocated virtual disk
3 : preallocated virtual disk split in 2GB files
4 : preallocated ESX-type virtual disk
5 : compressed disk optimized for streaming

Übrigens muss ich min 1x die Woche den XP Gast neustarten, da er verzögert auf Eingaben reagiert.

[Dayworker]

Du kannst auch Type 2 nehmen, dann erstellt VMware eine Datei in der Gesamtgröße. Die läßt sich aber meist schlecht sichern, nicht jeder hat Terabyteweise Platz rumzuliegen. Mit der 2GB-Stückelung bist du daher flexibler beim Sicherungsmedium und Performanceverluste sind auch nicht bekannt.

Wenn du keine unserer vorgeschlagenen Änderungen am Gesamt-System vorgenommen hast, ist das leider nicht zu ändern. Dann ist der Host leistungsmäßig einfach überfordert, swappt dazu noch völlig unnötig und die VM hat zuwenig Arbeitsspeicher. Wobei XP im Gegensatz zu W2k in meinen Augen sowieso kein Langläufer war/ist. Wenn du also viel im Explorer des virtualisierten XP rumrödelst, kannst du sowieso allerspätestens nach 14Tagen sämtliche Explorer-Prozesse abwürgen und diesen erneut starten. Die VM läuft ja trotzdem weiter.