Die Foren-SW läuft schon geraume Zeit ohne erkennbare Probleme. Sollte etwas nicht funktionieren, bitte gerne hier jederzeit melden und wir kümmern uns zeitnah darum. Danke!

VMs manchmal komplett eingefroren

Alles zum Thema vSphere 6.7, ESXi 6.7 und vCenter Server.

Moderatoren: continuum, irix, Dayworker

Member
Beiträge: 119
Registriert: 07.01.2017, 04:25

VMs manchmal komplett eingefroren

Beitragvon jgreil » 14.07.2019, 16:47

Hallo,

mir fällt in letzter Zeit häufiger auf, dass alle paar Tage eine oder mehrere meiner VMs komplett eingefroren sind.
Woran kann denn das liegen bzw. wo und wie fängt man da am besten zu suchen an?

Jenseits von Gut & Böse
Beiträge: 11813
Registriert: 02.08.2008, 15:06
Wohnort: Hannover/Wuerzburg
Kontaktdaten:

Re: VMs manchmal komplett eingefroren

Beitragvon irix » 14.07.2019, 17:02

Schau halt mal ins vmware.log der entsprechenden VM.

Gruss
Joerg

Member
Beiträge: 119
Registriert: 07.01.2017, 04:25

Re: VMs manchmal komplett eingefroren

Beitragvon jgreil » 14.07.2019, 17:28

Danke für den Tipp, wo ich ansetzen muss. Ich werde mal bis zum nächsten Freeze warten, damit ich den genauen Zeitpunkt weiß und dann dort nachsehen.

Guru
Beiträge: 2673
Registriert: 27.12.2004, 22:17

Re: VMs manchmal komplett eingefroren

Beitragvon rprengel » 18.07.2019, 06:29

jgreil hat geschrieben:Hallo,

mir fällt in letzter Zeit häufiger auf, dass alle paar Tage eine oder mehrere meiner VMs komplett eingefroren sind.
Woran kann denn das liegen bzw. wo und wie fängt man da am besten zu suchen an?


Wenn der Rest des Hosts weiter sauber läuft wurde ich als erstes prüfen ob in den VMs Powermanagement aktiv ist.

Gruss

Member
Beiträge: 119
Registriert: 07.01.2017, 04:25

Re: VMs manchmal komplett eingefroren

Beitragvon jgreil » 18.07.2019, 09:18

Danke.
Meinst du die Windows Einstellung "Energieoptionen" oder was genau?

Guru
Beiträge: 2673
Registriert: 27.12.2004, 22:17

Re: VMs manchmal komplett eingefroren

Beitragvon rprengel » 18.07.2019, 09:23

jgreil hat geschrieben:Danke.
Meinst du die Windows Einstellung "Energieoptionen" oder was genau?

Yeap,
genau die.

Gruss

Member
Beiträge: 119
Registriert: 07.01.2017, 04:25

Re: VMs manchmal komplett eingefroren

Beitragvon jgreil » 18.07.2019, 09:24

Und auf was sollen die eingestellt werden?

Guru
Beiträge: 2673
Registriert: 27.12.2004, 22:17

Re: VMs manchmal komplett eingefroren

Beitragvon rprengel » 18.07.2019, 09:31

jgreil hat geschrieben:Und auf was sollen die eingestellt werden?


Volle Pulle
Höchstleistung
Energiesparmodus auf niemals
Bidschirm ausschalten niemals

King of the Hill
Beiträge: 12611
Registriert: 01.10.2008, 12:54
Wohnort: laut USV-Log am Ende der Welt...

Re: VMs manchmal komplett eingefroren

Beitragvon Dayworker » 19.07.2019, 14:12

Bis auf die Bildschirmabschaltung stimme ich dem von "rprengel" zu, weil dessen Abschaltung keinen Einfluß auf die CPU hat. Anders sieht es dagegen mit einem beliebigen Bildschirmschoner aus, der gehört abgeschaltet, weil er die CPU für die in SW abgebildete Graka belastet.

Guru
Beiträge: 2673
Registriert: 27.12.2004, 22:17

Re: VMs manchmal komplett eingefroren

Beitragvon rprengel » 19.07.2019, 14:28

Dayworker hat geschrieben:Bis auf die Bildschirmabschaltung stimme ich dem von "rprengel" zu, weil dessen Abschaltung keinen Einfluß auf die CPU hat. Anders sieht es dagegen mit einem beliebigen Bildschirmschoner aus, der gehört abgeschaltet, weil er die CPU für die in SW abgebildete Graka belastet.


Hallo,
ich habe die Erfahrung gemacht das mnchmal rdp Ärger macht wenn der Bildschirm inaktiv ist.
Gruss

Member
Beiträge: 119
Registriert: 07.01.2017, 04:25

Re: VMs manchmal komplett eingefroren

Beitragvon jgreil » 13.09.2019, 19:16

Heute hat sich wieder eine VM aufgehängt.
Am eingefrorern Bild sehe ich noch die Windows-Uhrzeit mit 18:36 Uhr. Der letzte Eintrag im entsprechenden vmware.log ist aber um 14:18 Uhr:

Erkennt jemand da ein Problem?
Nur die letzten paar Zeilen sind von heute (13.9.):

Code: Alles auswählen

2019-09-12T22:37:40.635Z| vmx| I125: VigorTransportProcessClientPayload: opID=c346dc75 seq=2115540: Receiving Snapshot.Take request.
2019-09-12T22:37:40.635Z| vmx| I125: SnapshotVMX_TakeSnapshot start: 'VEEAM BACKUP TEMPORARY SNAPSHOT', deviceState=0, lazy=0, quiesced=0, forceNative=0, tryNative=1, saveAllocMaps=0
2019-09-12T22:37:40.639Z| vmx| I125: DiskLib_IsVMFSSparseSupported: vmfssparse is not supported on /vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8: f532.
2019-09-12T22:37:40.639Z| vmx| I125: DISKLIB-LIB_CREATE   : DiskLibCreateCreateParam: Selecting the default child type as SeSparse for /vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk.
2019-09-12T22:37:40.639Z| vmx| I125: DISKLIB-LIB_CREATE   : DiskLibCreateCreateParam: seSparse grain size is set to 8 for '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk'
2019-09-12T22:37:40.639Z| vmx| I125: SNAPSHOT: SnapshotPrepareTakeDoneCB: Prepare phase complete (The operation completed successfully).
2019-09-12T22:37:40.640Z| vcpu-0| I125: Destroying virtual dev for scsi0:0 vscsi=12550
2019-09-12T22:37:40.640Z| vcpu-0| I125: VMMon_VSCSIStopVports: No such target on adapter
2019-09-12T22:37:40.642Z| vcpu-0| I125: SnapshotVMXTakeSnapshotWork: Transition to mode 0.
2019-09-12T22:37:40.642Z| vcpu-0| I125: Closing all the disks of the VM.
2019-09-12T22:37:40.642Z| vcpu-0| I125: Closing disk 'scsi0:0'
2019-09-12T22:37:40.643Z| vcpu-0| I125: DISKLIB-CBT   : Shutting down change tracking for untracked fid 1571620802.
2019-09-12T22:37:40.643Z| vcpu-0| I125: DISKLIB-CBT   : Successfully disconnected CBT node.
2019-09-12T22:37:40.649Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : closed.
2019-09-12T22:37:40.650Z| vcpu-0| I125: SNAPSHOT: SnapshotConfigInfoOpenVmsd: Creating new snapshot dictionary, '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmsd.usd'.
2019-09-12T22:37:40.658Z| vcpu-0| I125: SNAPSHOT: SnapshotDumperOpenFromInfo: Creating checkpoint file /vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-Snapshot128.vmsn
2019-09-12T22:37:40.662Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : open successful (13) size = 32212254720, hd = 0. Type 3
2019-09-12T22:37:40.663Z| vcpu-0| I125: DiskLib_IsVMFSSparseSupported: vmfssparse is not supported on /vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8: f532.
2019-09-12T22:37:40.663Z| vcpu-0| I125: DISKLIB-LIB_CREATE   : DiskLibCreateCreateParam: Selecting the default child type as SeSparse for /vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk.
2019-09-12T22:37:40.663Z| vcpu-0| I125: DISKLIB-LIB_CREATE   : DiskLibCreateCreateParam: seSparse grain size is set to 8 for '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk'
2019-09-12T22:37:40.664Z| vcpu-0| I125: DISKLIB-LIB_CREATE   : CREATE CHILD: "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk" -- seSparse cowGran=8 allocType=0 policy=''
2019-09-12T22:37:40.664Z| vcpu-0| I125: DISKLIB-LIB_CREATE   : CREATE-CHILD: Creating disk backed by 'file'
2019-09-12T22:37:40.666Z| vcpu-0| I125: Const Header:
2019-09-12T22:37:40.666Z| vcpu-0| I125+  constMagic     = 0xcafebabe
2019-09-12T22:37:40.666Z| vcpu-0| I125+  version        = 2.1
2019-09-12T22:37:40.666Z| vcpu-0| I125+  capacity       = 62914560
2019-09-12T22:37:40.666Z| vcpu-0| I125+  grainSize      = 8
2019-09-12T22:37:40.666Z| vcpu-0| I125+  grainTableSize = 64
2019-09-12T22:37:40.666Z| vcpu-0| I125+  flags          = 0
2019-09-12T22:37:40.666Z| vcpu-0| I125: Extents:
2019-09-12T22:37:40.666Z| vcpu-0| I125+  Header         : <1 : 1>
2019-09-12T22:37:40.666Z| vcpu-0| I125+  JournalHdr     : <2 : 2>
2019-09-12T22:37:40.666Z| vcpu-0| I125+  Journal        : <2048 : 2048>
2019-09-12T22:37:40.666Z| vcpu-0| I125+  GrainDirectory : <4096 : 2048>
2019-09-12T22:37:40.666Z| vcpu-0| I125+  GrainTables    : <6144 : 122880>
2019-09-12T22:37:40.666Z| vcpu-0| I125+  FreeBitmap     : <129024 : 2048>
2019-09-12T22:37:40.666Z| vcpu-0| I125+  BackMap        : <131072 : 122880>
2019-09-12T22:37:40.666Z| vcpu-0| I125+  Grain          : <253952 : 62914560>
2019-09-12T22:37:40.666Z| vcpu-0| I125: Volatile Header:
2019-09-12T22:37:40.666Z| vcpu-0| I125+ volatileMagic     = 0xcafecafe
2019-09-12T22:37:40.666Z| vcpu-0| I125+ FreeGTNumber      = 0
2019-09-12T22:37:40.666Z| vcpu-0| I125+ nextTxnSeqNumber  = 0
2019-09-12T22:37:40.666Z| vcpu-0| I125+ replayJournal     = 0
2019-09-12T22:37:40.668Z| vcpu-0| I125: DISKLIB-SESPARSE : seSparseExtentCreate: "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : success
2019-09-12T22:37:40.670Z| vcpu-0| I125: DISKLIB-DSCPTR: "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk" : creation successful.
2019-09-12T22:37:40.674Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : open successful (1) size = 130023424, hd = 0. Type 19
2019-09-12T22:37:40.699Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : closed.
2019-09-12T22:37:40.699Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : closed.
2019-09-12T22:37:40.701Z| vcpu-0| A100: ConfigDB: Setting scsi0:0.fileName = "B8-000001.vmdk"
2019-09-12T22:37:40.706Z| vcpu-0| I125: Checkpoint_Unstun: vm stopped for 66383 us
2019-09-12T22:37:40.706Z| vcpu-0| I125: SCSI: switching scsi0 to push completion mode
2019-09-12T22:37:40.707Z| vcpu-0| A100: ConfigDB: Setting scsi0:0.redo = ""
2019-09-12T22:37:40.707Z| vcpu-0| I125: DISK: OPEN scsi0:0 '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk' persistent R[]
2019-09-12T22:37:40.708Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : open successful (10) size = 130023424, hd = 1863780293. Type 19
2019-09-12T22:37:40.708Z| vcpu-0| I125: DISKLIB-DSCPTR: Opened [0]: "B8-000001-sesparse.vmdk" (0xa)
2019-09-12T22:37:40.708Z| vcpu-0| I125: DISKLIB-LINK  : Opened '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk' (0xa): seSparse, 62914560 sectors / 30 GB.
2019-09-12T22:37:40.709Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : open successful (14) size = 32212254720, hd = 1105922120. Type 3
2019-09-12T22:37:40.709Z| vcpu-0| I125: DISKLIB-DSCPTR: Opened [0]: "B8-flat.vmdk" (0xe)
2019-09-12T22:37:40.709Z| vcpu-0| I125: DISKLIB-LINK  : Opened '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk' (0xe): vmfs, 62914560 sectors / 30 GB.
2019-09-12T22:37:40.709Z| vcpu-0| I125: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 1105922120, extentType = 2
2019-09-12T22:37:40.709Z| vcpu-0| I125: DISKLIB-CHAINESX : ChainESXOpenSubChain:(1) fid = 1863780293, extentType = 1
2019-09-12T22:37:40.709Z| vcpu-0| I125: DISKLIB-LIB_BLOCKTRACK   : Resuming change tracking.
2019-09-12T22:37:40.710Z| vcpu-0| I125: DISKLIB-CBT   : Initializing ESX kernel change tracking for fid 1325467721.
2019-09-12T22:37:40.710Z| vcpu-0| I125: DISKLIB-CBT   : Successfuly created cbt node 4f010849-cbt.
2019-09-12T22:37:40.710Z| vcpu-0| I125: DISKLIB-CBT   : Opening cbt node /vmfs/devices/cbt/4f010849-cbt
2019-09-12T22:37:40.710Z| vcpu-0| I125: DISKLIB-LIB   : Opened "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk" (flags 0xa, type vmfs).
2019-09-12T22:37:40.710Z| vcpu-0| I125: DISK: Disk '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk' has UUID '60 00 c2 98 cb 5b 19 cf-cd d1 a4 a0 a9 d2 65 b7'
2019-09-12T22:37:40.710Z| vcpu-0| I125: DISK: OPEN '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk' Geo (3916/255/63) BIOS Geo (0/0/0)
2019-09-12T22:37:40.710Z| vcpu-0| I125: Creating virtual dev for 'scsi0:0'.
2019-09-12T22:37:40.710Z| vcpu-0| I125: DumpDiskInfo: scsi0:0 createType=11, capacity = 62914560, numLinks = 2, allocationType = 2
2019-09-12T22:37:40.710Z| vcpu-0| I125: SCSIDiskESXPopulateVDevDesc: Using FS backend
2019-09-12T22:37:40.710Z| vcpu-0| I125: DISKUTIL: scsi0:0 : geometry=3916/255/63
2019-09-12T22:37:40.710Z| vcpu-0| I125: SCSIFilterESXAttachCBRCInt: CBRC not enabled or opened without filters,skipping CBRC           filter attach.
2019-09-12T22:37:40.710Z| vcpu-0| I125: SCSIFilterSBDAttachCBRC: device scsi0:0 is not SBD. Skipping CBRC attach SBD way.
2019-09-12T22:37:40.711Z| vcpu-0| I125: SnapshotVMXTakeSnapshotWork: Transition to mode 1.
2019-09-12T22:37:40.711Z| vcpu-0| I125: SnapshotVMXTakeSnapshotComplete: Done with snapshot 'VEEAM BACKUP TEMPORARY SNAPSHOT': 128
2019-09-12T22:37:40.711Z| vcpu-0| I125: VigorTransport_ServerSendResponse opID=c346dc75 seq=2115540: Completed Snapshot request.
2019-09-12T22:37:44.728Z| vcpu-1| I125: HBACommon: First write on scsi0:0.fileName='/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk'
2019-09-12T22:37:44.728Z| vcpu-1| I125: DDB: "longContentID" = "3cc100780c7640772660ea9d5a36bc7a" (was "3064ea51aa3180dc1a467fa59894f27a")
2019-09-12T22:37:44.732Z| vcpu-1| I125: DISKLIB-CHAIN : DiskChainUpdateContentID: old=0x9894f27a, new=0x5a36bc7a (3cc100780c7640772660ea9d5a36bc7a)
2019-09-12T22:38:56.828Z| vmx| I125: VigorTransportProcessClientPayload: opID=c346df33 seq=2115838: Receiving Snapshot.Delete request.
2019-09-12T22:38:56.833Z| vmx| I125: SNAPSHOT: SnapshotDeleteWork '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmx' : 128
2019-09-12T22:38:56.834Z| vmx| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : open successful (5) size = 146804736, hd = 0. Type 19
2019-09-12T22:38:56.834Z| vmx| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : closed.
2019-09-12T22:38:56.834Z| vmx| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : open successful (5) size = 32212254720, hd = 0. Type 3
2019-09-12T22:38:56.834Z| vmx| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : closed.
2019-09-12T22:38:56.844Z| vmx| I125: VigorTransport_ServerSendResponse opID=c346df33 seq=2115838: Completed Snapshot request.
2019-09-12T22:38:56.846Z| vmx| I125: VigorTransportProcessClientPayload: opID=c346df33 seq=2115839: Receiving Snapshot.Consolidate request.
2019-09-12T22:38:56.847Z| vmx| I125: SnapshotVMX_Consolidate: Starting online snapshot consolidate operation.
2019-09-12T22:38:56.847Z| vmx| I125: Vix: [mainDispatch.c:4239]: VMAutomation_ReportPowerOpFinished: statevar=3, newAppState=1880, success=1 additionalError=0
2019-09-12T22:38:56.847Z| vmx| I125: Turning on snapshot info cache. VM=B8.vmx.
2019-09-12T22:38:56.849Z| vmx| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : open successful (1114117) size = 0, hd = 0. Type 19
2019-09-12T22:38:56.849Z| vmx| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : closed.
2019-09-12T22:38:56.849Z| vmx| I125: SNAPSHOT: Turning on snapshot disk cache.
2019-09-12T22:38:56.850Z| vmx| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : open successful (5) size = 146804736, hd = 0. Type 19
2019-09-12T22:38:56.850Z| vmx| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : closed.
2019-09-12T22:38:56.850Z| vmx| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : open successful (5) size = 32212254720, hd = 0. Type 3
2019-09-12T22:38:56.850Z| vmx| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : closed.
2019-09-12T22:38:56.850Z| vmx| I125: WORKER: Creating new group with numThreads=1 (16)
2019-09-12T22:38:56.850Z| vmx| I125: WORKER: Creating new group with numThreads=1 (16)
2019-09-12T22:38:56.851Z| vcpu-0| I125: Destroying virtual dev for scsi0:0 vscsi=12796
2019-09-12T22:38:56.851Z| vcpu-0| I125: VMMon_VSCSIStopVports: No such target on adapter
2019-09-12T22:38:56.853Z| vcpu-0| I125: Closing disk 'scsi0:0'
2019-09-12T22:38:56.854Z| vcpu-0| I125: DISKLIB-CBT   : Shutting down change tracking for untracked fid 1325467721.
2019-09-12T22:38:56.854Z| vcpu-0| I125: DISKLIB-CBT   : Successfully disconnected CBT node.
2019-09-12T22:38:56.861Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : closed.
2019-09-12T22:38:56.861Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : closed.
2019-09-12T22:38:56.861Z| vcpu-0| I125: Checkpoint_Unstun: vm stopped for 11081 us
2019-09-12T22:38:56.861Z| vcpu-0| I125: SCSI: switching scsi0 to push completion mode
2019-09-12T22:38:56.862Z| vcpu-0| A100: ConfigDB: Setting scsi0:0.redo = ""
2019-09-12T22:38:56.863Z| vcpu-0| I125: DISK: OPEN scsi0:0 '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk' persistent R[]
2019-09-12T22:38:56.864Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : open successful (8) size = 146804736, hd = 1325664329. Type 19
2019-09-12T22:38:56.864Z| vcpu-0| I125: DISKLIB-DSCPTR: Opened [0]: "B8-000001-sesparse.vmdk" (0x8)
2019-09-12T22:38:56.864Z| vcpu-0| I125: DISKLIB-LINK  : Opened '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk' (0x8): seSparse, 62914560 sectors / 30 GB.
2019-09-12T22:38:56.864Z| vcpu-0| I125: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 1325664329, extentType = 1
2019-09-12T22:38:56.864Z| vcpu-0| I125: DISKLIB-LIB_BLOCKTRACK   : Resuming change tracking.
2019-09-12T22:38:56.864Z| vcpu-0| I125: DISKLIB-CBT   : Initializing ESX kernel change tracking for fid 1986857034.
2019-09-12T22:38:56.865Z| vcpu-0| I125: DISKLIB-CBT   : Successfuly created cbt node 766d084a-cbt.
2019-09-12T22:38:56.865Z| vcpu-0| I125: DISKLIB-CBT   : Opening cbt node /vmfs/devices/cbt/766d084a-cbt
2019-09-12T22:38:56.865Z| vcpu-0| I125: DISKLIB-LIB   : Opened "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk" (flags 0x8, type seSparse).
2019-09-12T22:38:56.865Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : open successful (522) size = 32212254720, hd = 2526218317. Type 3
2019-09-12T22:38:56.865Z| vcpu-0| I125: DISKLIB-DSCPTR: Opened [0]: "B8-flat.vmdk" (0x20a)
2019-09-12T22:38:56.865Z| vcpu-0| I125: DISKLIB-LINK  : Opened '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk' (0x20a): vmfs, 62914560 sectors / 30 GB.
2019-09-12T22:38:56.865Z| vcpu-0| I125: DISKLIB-LIB_BLOCKTRACK   : Resuming change tracking.
2019-09-12T22:38:56.866Z| vcpu-0| I125: DISKLIB-CBT   : Initializing ESX kernel change tracking for fid 2526218317.
2019-09-12T22:38:56.866Z| vcpu-0| I125: DISKLIB-CBT   : Successfuly created cbt node 9693084d-cbt.
2019-09-12T22:38:56.866Z| vcpu-0| I125: DISKLIB-CBT   : Opening cbt node /vmfs/devices/cbt/9693084d-cbt
2019-09-12T22:38:56.866Z| vcpu-0| I125: DISKLIB-LIB   : Opened "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk" (flags 0x20a, type vmfs).
2019-09-12T22:38:56.867Z| vcpu-0| I125: DISKLIB-CBT   : Shutting down change tracking for untracked fid 1986857034.
2019-09-12T22:38:56.867Z| vcpu-0| I125: DISKLIB-CBT   : Successfully disconnected CBT node.
2019-09-12T22:38:56.867Z| vcpu-0| I125: DISKLIB-CBT   : Shutting down change tracking for untracked fid 2526218317.
2019-09-12T22:38:56.867Z| vcpu-0| I125: DISKLIB-CBT   : Successfully disconnected CBT node.
2019-09-12T22:38:56.870Z| vcpu-0| I125: DISKLIB-CHAINESX : ChainESXOpenSubChain:(0) fid = 2526218317, extentType = 2
2019-09-12T22:38:56.870Z| vcpu-0| I125: DISKLIB-CHAINESX : ChainESXOpenSubChain:(1) fid = 1325664329, extentType = 1
2019-09-12T22:38:56.870Z| vcpu-0| I125: DISKLIB-CBT   : Initializing ESX kernel change tracking for fid 1986988106.
2019-09-12T22:38:56.870Z| vcpu-0| I125: DISKLIB-CBT   : Successfuly created cbt node 766f084a-cbt.
2019-09-12T22:38:56.870Z| vcpu-0| I125: DISKLIB-CBT   : Opening cbt node /vmfs/devices/cbt/766f084a-cbt
2019-09-12T22:38:56.870Z| vcpu-0| I125: DISK: Disk '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk' has UUID '60 00 c2 98 cb 5b 19 cf-cd d1 a4 a0 a9 d2 65 b7'
2019-09-12T22:38:56.870Z| vcpu-0| I125: DISK: OPEN '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk' Geo (3916/255/63) BIOS Geo (0/0/0)
2019-09-12T22:38:56.870Z| vcpu-0| I125: ConsolidateDiskOpenCB: Opening disk node 'scsi0:0' for combining '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk' -> '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk'.
2019-09-12T22:38:56.872Z| vcpu-0| I125: DISKLIB-LIB_MISC   : Opening mirror node /vmfs/devices/svm/766f084a-9693084d-svmmirror
2019-09-12T22:38:56.872Z| vcpu-0| I125: Creating virtual dev for 'scsi0:0'.
2019-09-12T22:38:56.872Z| vcpu-0| I125: DumpDiskInfo: scsi0:0 createType=11, capacity = 62914560, numLinks = 2, allocationType = 2
2019-09-12T22:38:56.872Z| vcpu-0| I125: SCSIDiskESXPopulateVDevDesc: Using FS backend
2019-09-12T22:38:56.872Z| vcpu-0| I125: DISKUTIL: scsi0:0 : geometry=3916/255/63
2019-09-12T22:38:56.872Z| vcpu-0| I125: SCSIFilterESXAttachCBRCInt: CBRC not enabled or opened without filters,skipping CBRC           filter attach.
2019-09-12T22:38:56.872Z| vcpu-0| I125: SCSIFilterSBDAttachCBRC: device scsi0:0 is not SBD. Skipping CBRC attach SBD way.
2019-09-12T22:38:56.914Z| worker-2100772| I125: DISKLIB-LIB   : DiskLib_IsCombinePossible: Combine 2 links at 0. Need 23 MB of free space (334682 MB available)
2019-09-12T22:38:56.914Z| vmx| I125: ConsolidateMirrorCombineStart: Combine '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk' -> '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk' (node = scsi0:0): 2 links starting from 0. usesCurrent = 1, isNative = 0
2019-09-12T22:38:56.914Z| vmx| I125: Mirror_DiskCopy: Starting disk copy.
2019-09-12T22:38:57.017Z| vcpu-0| I125: Destroying virtual dev for scsi0:0 vscsi=12802
2019-09-12T22:38:57.017Z| vcpu-0| I125: VMMon_VSCSIStopVports: No such target on adapter
2019-09-12T22:38:57.019Z| vcpu-0| I125: Closing disk 'scsi0:0'
2019-09-12T22:38:57.019Z| vcpu-0| I125: ConsolidateDiskCloseCB: Closing disk node 'scsi0:0' after combining '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001.vmdk' -> '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk'.
2019-09-12T22:38:57.020Z| vcpu-0| I125: DISKLIB-CTK   : Forcing flush of change info for "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-ctk.vmdk".
2019-09-12T22:38:57.022Z| vcpu-0| I125: DISKLIB-CTK   : ChangeTracker_EndCombine()
2019-09-12T22:38:57.026Z| vcpu-0| I125: DISKLIB-CTK   : Unlinked /vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-ctk.vmdk, tmp file: /vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-ctk.vmdk-tmp
2019-09-12T22:38:57.035Z| vcpu-0| I125: DISKLIB-CTK   : resuming /vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-ctk.vmdk-tmp
2019-09-12T22:38:57.037Z| vcpu-0| I125: DISKLIB-CTK   : ChangeTracker_EndCombine: Renaming /vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-ctk.vmdk-tmp to /vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-ctk.vmdk
2019-09-12T22:38:57.038Z| vcpu-0| I125: DISKLIB-CTK   : ChangeTracker_EndCombine: Attempting to unlink /vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-ctk.vmdk-tmp
2019-09-12T22:38:57.038Z| vcpu-0| I125: DDB: "longContentID" = "3cc100780c7640772660ea9d5a36bc7a" (was "3064ea51aa3180dc1a467fa59894f27a")
2019-09-12T22:38:57.052Z| vcpu-0| I125: DISKLIB-CBT   : Shutting down change tracking for untracked fid 1986988106.
2019-09-12T22:38:57.052Z| vcpu-0| I125: DISKLIB-CBT   : Successfully disconnected CBT node.
2019-09-12T22:38:57.053Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : closed.
2019-09-12T22:38:57.053Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : closed.
2019-09-12T22:38:57.054Z| vcpu-0| A100: ConfigDB: Setting scsi0:0.fileName = "B8.vmdk"
2019-09-12T22:38:57.055Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : open successful (1115137) size = 0, hd = 0. Type 19
2019-09-12T22:38:57.055Z| vcpu-0| I125: DISKLIB-LIB_BLOCKTRACK   : Resuming change tracking.
2019-09-12T22:38:57.061Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-000001-sesparse.vmdk" : closed.
2019-09-12T22:38:57.064Z| vcpu-0| I125: Checkpoint_Unstun: vm stopped for 47455 us
2019-09-12T22:38:57.064Z| vcpu-0| I125: SCSI: switching scsi0 to push completion mode
2019-09-12T22:38:57.065Z| vcpu-0| A100: ConfigDB: Setting scsi0:0.redo = ""
2019-09-12T22:38:57.065Z| vcpu-0| I125: DISK: OPEN scsi0:0 '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk' persistent R[]
2019-09-12T22:38:57.066Z| vcpu-0| I125: DISKLIB-VMFS  : "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8-flat.vmdk" : open successful (10) size = 32212254720, hd = 2378958926. Type 3
2019-09-12T22:38:57.066Z| vcpu-0| I125: DISKLIB-DSCPTR: Opened [0]: "B8-flat.vmdk" (0xa)
2019-09-12T22:38:57.066Z| vcpu-0| I125: DISKLIB-LINK  : Opened '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk' (0xa): vmfs, 62914560 sectors / 30 GB.
2019-09-12T22:38:57.066Z| vcpu-0| I125: DISKLIB-LIB_BLOCKTRACK   : Resuming change tracking.
2019-09-12T22:38:57.066Z| vcpu-0| I125: DISKLIB-CBT   : Initializing ESX kernel change tracking for fid 2378958926.
2019-09-12T22:38:57.067Z| vcpu-0| I125: DISKLIB-CBT   : Successfuly created cbt node 8dcc084e-cbt.
2019-09-12T22:38:57.067Z| vcpu-0| I125: DISKLIB-CBT   : Opening cbt node /vmfs/devices/cbt/8dcc084e-cbt
2019-09-12T22:38:57.067Z| vcpu-0| I125: DISKLIB-LIB   : Opened "/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk" (flags 0xa, type vmfs).
2019-09-12T22:38:57.067Z| vcpu-0| I125: DISK: Disk '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk' has UUID '60 00 c2 98 cb 5b 19 cf-cd d1 a4 a0 a9 d2 65 b7'
2019-09-12T22:38:57.067Z| vcpu-0| I125: DISK: OPEN '/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk' Geo (3916/255/63) BIOS Geo (0/0/0)
2019-09-12T22:38:57.067Z| vcpu-0| I125: Creating virtual dev for 'scsi0:0'.
2019-09-12T22:38:57.067Z| vcpu-0| I125: DumpDiskInfo: scsi0:0 createType=11, capacity = 62914560, numLinks = 1, allocationType = 2
2019-09-12T22:38:57.067Z| vcpu-0| I125: SCSIDiskESXPopulateVDevDesc: Using FS backend
2019-09-12T22:38:57.067Z| vcpu-0| I125: DISKUTIL: scsi0:0 : geometry=3916/255/63
2019-09-12T22:38:57.067Z| vcpu-0| I125: SCSIFilterESXAttachCBRCInt: CBRC not enabled or opened without filters,skipping CBRC           filter attach.
2019-09-12T22:38:57.067Z| vcpu-0| I125: SCSIFilterSBDAttachCBRC: device scsi0:0 is not SBD. Skipping CBRC attach SBD way.
2019-09-12T22:38:57.071Z| vcpu-0| I125: VigorTransport_ServerSendResponse opID=c346df33 seq=2115839: Completed Snapshot request.
2019-09-12T22:38:57.071Z| vcpu-0| I125: Turning off snapshot info cache.
2019-09-12T22:38:57.071Z| vcpu-0| I125: Turning off snapshot disk cache.
2019-09-12T22:38:57.071Z| vcpu-0| I125: ConsolidateEnd: Snapshot consolidate complete: The operation completed successfully (0).
2019-09-12T22:38:57.071Z| vcpu-0| I125: Vix: [mainDispatch.c:4239]: VMAutomation_ReportPowerOpFinished: statevar=3, newAppState=1881, success=1 additionalError=0
2019-09-12T22:39:12.060Z| vcpu-1| I125: HBACommon: First write on scsi0:0.fileName='/vmfs/volumes/5ca33151-6fd38858-b467-38d5470026a5/B8/B8.vmdk'
2019-09-12T22:39:12.060Z| vcpu-1| I125: DDB: "longContentID" = "6f7701029c93afbfc3b1ddeaf3a21e00" (was "3cc100780c7640772660ea9d5a36bc7a")
2019-09-12T22:39:12.064Z| vcpu-1| I125: DISKLIB-CHAIN : DiskChainUpdateContentID: old=0x5a36bc7a, new=0xf3a21e00 (6f7701029c93afbfc3b1ddeaf3a21e00)
2019-09-13T14:16:48.531Z| vmx| I125: GuestRpcSendTimedOut: message to toolbox timed out.
2019-09-13T14:17:08.535Z| vmx| I125: GuestRpcSendTimedOut: message to toolbox timed out.
2019-09-13T14:17:08.535Z| vmx| I125: GuestRpc: app toolbox's second ping timeout; assuming app is down
2019-09-13T14:17:08.536Z| vmx| I125: GuestRpc: Reinitializing Channel 0(toolbox)
2019-09-13T14:17:08.536Z| vmx| I125: GuestMsg: Channel 0, Cannot unpost because the previous post is already completed


Eine andere VM ist ebenfalls heute Nachmittag eingefroren und die letzten Einträge in deren vmware.log waren aber am Vormittag:

Code: Alles auswählen

2019-09-13T17:09:10.409Z| vmx| I125: VigorTransportProcessClientPayload: opID=c34a228e seq=2142491: Receiving Sched.SetResourceGroup request.
2019-09-13T17:09:10.409Z| vmx| I125: VigorTransport_ServerSendResponse opID=c34a228e seq=2142491: Completed Sched request.
2019-09-13T17:09:10.410Z| vmx| I125: VigorTransportProcessClientPayload: opID=c34a228e seq=2142492: Receiving PowerState.InitiateReset request.
2019-09-13T17:09:10.410Z| vmx| I125: Vix: [vmxCommands.c:686]: VMAutomation_Reset. Trying hard reset
2019-09-13T17:09:10.410Z| vmx| I125: VM reset requested: Attempting CPUs/Devices reset.
2019-09-13T17:09:10.412Z| vmx| I125: Vigor_Reset: Attaching to reset.
2019-09-13T17:09:10.412Z| vcpu-0| I125: Destroying virtual dev for scsi0:0 vscsi=12779
2019-09-13T17:09:10.412Z| vcpu-0| I125: VMMon_VSCSIStopVports: No such target on adapter
2019-09-13T17:09:10.415Z| vcpu-0| I125: DEVICE: Resetting device 'ALL'.
2019-09-13T17:09:10.415Z| vcpu-0| I125: USB: Per-Device Resetting device 0x200000050e0f0003
2019-09-13T17:09:10.417Z| vcpu-0| I125: Tools: ToolsRunningStatus_Reset, delayedRequest is 0x0
2019-09-13T17:09:10.417Z| vcpu-0| I125: Tools: Changing running status: 1 => 0.
2019-09-13T17:09:10.418Z| vcpu-0| I125: GuestLib Generated SessionId 8968096478686975585
2019-09-13T17:09:10.418Z| vcpu-0| I125: GuestRpc: Reinitializing Channel 2(toolbox-dnd)
2019-09-13T17:09:10.418Z| vcpu-0| I125: GuestMsg: Channel 2, Cannot unpost because the previous post is already completed
2019-09-13T17:09:10.420Z| vcpu-0| I125: Checkpoint_Unstun: vm stopped for 9755 us
2019-09-13T17:09:10.420Z| vcpu-0| I125: CPU reset: hard (mode 0)
2019-09-13T17:09:10.420Z| vcpu-3| I125: CPU reset: hard (mode 0)
2019-09-13T17:09:10.420Z| vcpu-2| I125: CPU reset: hard (mode 0)
2019-09-13T17:09:10.420Z| vcpu-1| I125: CPU reset: hard (mode 0)
2019-09-13T17:09:10.429Z| svga| I125: SVGA-ScreenMgr: Screen type changed to RegisterMode
2019-09-13T17:09:10.447Z| svga| I125: VNCENCODE 14 VNCEncode: VNCEncode_ServerSetTopology - original root: (0, 0) size: (1024, 768)
2019-09-13T17:09:10.447Z| svga| I125: VNCENCODE 14 VNCEncode: screen: 0 BoundingBox: (1024x768) Screen (1024x768) @ (0,0) bytesPerLine: 4096
2019-09-13T17:09:10.559Z| vcpu-0| I125: SVGA: Unregistering IOSpace at 0x1070
2019-09-13T17:09:10.569Z| vcpu-0| I125: SVGA: Unregistering MemSpace at 0xe8000000(0xe8000000) and 0xfe000000(0xfe000000)
2019-09-13T17:09:10.576Z| vcpu-0| I125: SCSI: switching scsi0 to push completion mode
2019-09-13T17:09:10.577Z| vcpu-0| I125: Creating virtual dev for 'scsi0:0'.
2019-09-13T17:09:10.577Z| vcpu-0| I125: DumpDiskInfo: scsi0:0 createType=11, capacity = 62914560, numLinks = 1, allocationType = 2
2019-09-13T17:09:10.577Z| vcpu-0| I125: SCSIDiskESXPopulateVDevDesc: Using FS backend
2019-09-13T17:09:10.577Z| vcpu-0| I125: DISKUTIL: scsi0:0 : geometry=3916/255/63
2019-09-13T17:09:10.578Z| vcpu-0| I125: SCSIFilterESXAttachCBRCInt: CBRC not enabled or opened without filters,skipping CBRC           filter attach.
2019-09-13T17:09:10.578Z| vcpu-0| I125: SCSIFilterSBDAttachCBRC: device scsi0:0 is not SBD. Skipping CBRC attach SBD way.
2019-09-13T17:09:10.580Z| vcpu-0| I125: VigorTransport_ServerSendResponse opID=c34a228e seq=2142492: Completed PowerState request.
2019-09-13T17:09:10.580Z| vcpu-0| I125: Vix: [mainDispatch.c:4239]: VMAutomation_ReportPowerOpFinished: statevar=0, newAppState=1872, success=1 additionalError=0
2019-09-13T17:09:10.580Z| vcpu-0| I125: Vix: [mainDispatch.c:4156]: VMAutomationReportPowerStateChange: Reporting power state change (opcode=0, err=0).
2019-09-13T17:09:10.580Z| vcpu-0| I125: Vix: [mainDispatch.c:4156]: VMAutomationReportPowerStateChange: Reporting power state change (opcode=2, err=0).
2019-09-13T17:09:10.580Z| vcpu-0| I125: Transitioned vmx/execState/val to poweredOn
2019-09-13T17:09:10.586Z| vcpu-0| I125: CDROM: Reset guest state on 'sata0:0'. Invalidating last poll command time.
2019-09-13T17:09:10.660Z| svga| I125: SVGA disabling SVGA
2019-09-13T17:09:10.675Z| vcpu-0| I125: PCIXHCI: Interrupt type changed from MSIX to INTX
2019-09-13T17:09:10.680Z| svga| I125: VNCENCODE 14 VNCEncode: VNCEncode_ServerSetTopology - original root: (0, 0) size: (640, 480)
2019-09-13T17:09:10.680Z| svga| I125: VNCENCODE 14 VNCEncode: screen: 0 BoundingBox: (640x480) Screen (640x480) @ (0,0) bytesPerLine: 2560
2019-09-13T17:09:10.687Z| vcpu-0| I125: PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.687Z| vcpu-0| I125: pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.687Z| vcpu-0| I125: pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.688Z| vcpu-0| I125: pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.688Z| vcpu-0| I125: pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.688Z| vcpu-0| I125: pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.689Z| vcpu-0| I125: pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.689Z| vcpu-0| I125: pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.689Z| vcpu-0| I125: PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.690Z| vcpu-0| I125: pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.690Z| vcpu-0| I125: pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.691Z| vcpu-0| I125: pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.691Z| vcpu-0| I125: pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.691Z| vcpu-0| I125: pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.692Z| vcpu-0| I125: pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.692Z| vcpu-0| I125: pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.692Z| vcpu-0| I125: PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.693Z| vcpu-0| I125: pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.693Z| vcpu-0| I125: pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.693Z| vcpu-0| I125: pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.694Z| vcpu-0| I125: pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.694Z| vcpu-0| I125: pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.694Z| vcpu-0| I125: pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.695Z| vcpu-0| I125: pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.695Z| vcpu-0| I125: PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.696Z| vcpu-0| I125: pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.696Z| vcpu-0| I125: pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.696Z| vcpu-0| I125: pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.697Z| vcpu-0| I125: pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.697Z| vcpu-0| I125: pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.697Z| vcpu-0| I125: pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.698Z| vcpu-0| I125: pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:10.872Z| vcpu-0| I125: SVGA: Registering MemSpace at 0xe8000000(0xe8000000) and 0xfe000000(0xfe000000)
2019-09-13T17:09:10.878Z| vcpu-0| I125: SVGA: Unregistering MemSpace at 0xe8000000(0xe8000000) and 0xfe000000(0xfe000000)
2019-09-13T17:09:11.055Z| vcpu-0| I125: SVGA: Registering MemSpace at 0xe8000000(0xe8000000) and 0xfe000000(0xfe000000)
2019-09-13T17:09:11.066Z| vcpu-0| I125: SVGA: Unregistering MemSpace at 0xe8000000(0xe8000000) and 0xfe000000(0xfe000000)
2019-09-13T17:09:11.067Z| vcpu-0| I125: SVGA: Registering IOSpace at 0x1070
2019-09-13T17:09:11.067Z| vcpu-0| I125: SVGA: Registering MemSpace at 0xe8000000(0xe8000000) and 0xfe000000(0xfe000000)
2019-09-13T17:09:11.073Z| vcpu-0| I125: PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.075Z| vcpu-0| I125: pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.076Z| vcpu-0| I125: pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.077Z| vcpu-0| I125: pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.079Z| vcpu-0| I125: pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.080Z| vcpu-0| I125: pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.082Z| vcpu-0| I125: pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.083Z| vcpu-0| I125: pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.084Z| vcpu-0| I125: PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.085Z| vcpu-0| I125: pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.087Z| vcpu-0| I125: pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.088Z| vcpu-0| I125: pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.090Z| vcpu-0| I125: pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.091Z| vcpu-0| I125: pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.092Z| vcpu-0| I125: pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.094Z| vcpu-0| I125: pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.095Z| vcpu-0| I125: PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.096Z| vcpu-0| I125: pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.098Z| vcpu-0| I125: pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.099Z| vcpu-0| I125: pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.100Z| vcpu-0| I125: pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.102Z| vcpu-0| I125: pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.103Z| vcpu-0| I125: pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.105Z| vcpu-0| I125: pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.106Z| vcpu-0| I125: PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.108Z| vcpu-0| I125: pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.109Z| vcpu-0| I125: pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.110Z| vcpu-0| I125: pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.112Z| vcpu-0| I125: pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.113Z| vcpu-0| I125: pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.115Z| vcpu-0| I125: pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.116Z| vcpu-0| I125: pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.118Z| vcpu-1| I125: CPU reset: soft (mode 0)
2019-09-13T17:09:11.121Z| vcpu-2| I125: CPU reset: soft (mode 0)
2019-09-13T17:09:11.123Z| vcpu-3| I125: CPU reset: soft (mode 0)
2019-09-13T17:09:11.150Z| mks| W115: VNCENCODE 14 JPEG quality levels (min, mid, max). Input: (25, 35, 90) Clamped: (25, 35, 90)
2019-09-13T17:09:11.150Z| mks| I125: VNCENCODE 14 VNCEncodeChooseRegionEncoder: region encoder adaptive. Screen 1/1 @ Resolution: 640 x 480
2019-09-13T17:09:11.264Z| vcpu-0| I125: AHCI: Tried to enable/disable IO space.
2019-09-13T17:09:11.267Z| vcpu-0| I125: PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.268Z| vcpu-0| I125: pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.270Z| vcpu-0| I125: pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.271Z| vcpu-0| I125: pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.273Z| vcpu-0| I125: pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.274Z| vcpu-0| I125: pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.276Z| vcpu-0| I125: pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.277Z| vcpu-0| I125: pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.280Z| vcpu-0| I125: PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.281Z| vcpu-0| I125: pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.283Z| vcpu-0| I125: pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.284Z| vcpu-0| I125: pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.286Z| vcpu-0| I125: pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.287Z| vcpu-0| I125: pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.289Z| vcpu-0| I125: pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.291Z| vcpu-0| I125: pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.292Z| vcpu-0| I125: PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.294Z| vcpu-0| I125: pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.295Z| vcpu-0| I125: pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.297Z| vcpu-0| I125: pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.298Z| vcpu-0| I125: pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.300Z| vcpu-0| I125: pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.301Z| vcpu-0| I125: pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.303Z| vcpu-0| I125: pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.304Z| vcpu-0| I125: PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.306Z| vcpu-0| I125: pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.308Z| vcpu-0| I125: pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.309Z| vcpu-0| I125: pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.310Z| vcpu-0| I125: pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.312Z| vcpu-0| I125: pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.313Z| vcpu-0| I125: pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.315Z| vcpu-0| I125: pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
2019-09-13T17:09:11.329Z| vcpu-0| I125: AHCI: Tried to enable/disable IO space.
2019-09-13T17:09:11.330Z| vcpu-0| I125: AHCI-VMM:HBA reset issued on sata0.
2019-09-13T17:09:11.349Z| vcpu-0| I125: DISKUTIL: scsi0:0 : geometry=3916/255/63
2019-09-13T17:09:11.349Z| vcpu-0| I125: DISKUTIL: scsi0:0 : capacity=62914560 logical sector size=512
2019-09-13T17:09:11.719Z| vcpu-0| I125: AHCI-USER: Already in check condition 02 3a 00
2019-09-13T17:09:11.719Z| vcpu-0| I125: AHCI-USER: Already in check condition 02 3a 00
2019-09-13T17:09:11.733Z| vcpu-1| I125: CPU reset: soft (mode 0)
2019-09-13T17:09:11.735Z| vcpu-2| I125: CPU reset: soft (mode 0)
2019-09-13T17:09:11.736Z| vcpu-3| I125: CPU reset: soft (mode 0)
2019-09-13T17:09:11.753Z| vcpu-0| I125: BIOS-UUID is 42 3f 0d 2a ec 2e 4f 21-64 56 31 65 47 d5 bc e2
2019-09-13T17:09:11.856Z| svga| I125: VNCENCODE 14 VNCEncode: VNCEncode_ServerSetTopology - original root: (0, 0) size: (720, 400)
2019-09-13T17:09:11.856Z| svga| I125: VNCENCODE 14 VNCEncode: screen: 0 BoundingBox: (720x400) Screen (720x400) @ (0,0) bytesPerLine: 2880
2019-09-13T17:09:11.857Z| mks| W115: VNCENCODE 14 JPEG quality levels (min, mid, max). Input: (25, 35, 90) Clamped: (25, 35, 90)
2019-09-13T17:09:11.857Z| mks| I125: VNCENCODE 14 VNCEncodeChooseRegionEncoder: region encoder adaptive. Screen 1/1 @ Resolution: 720 x 400
2019-09-13T17:09:26.947Z| vcpu-0| I125: Tools: Tools heartbeat timeout.
2019-09-13T17:09:26.947Z| vcpu-0| I125: Tools: Running status rpc handler: 0 => 0.


Scheint also ob man dort nichts brauchbares findet, oder?
Wo könnte man sonst noch ansetzen?

Member
Beiträge: 119
Registriert: 07.01.2017, 04:25

Re: VMs manchmal komplett eingefroren

Beitragvon jgreil » 14.09.2019, 06:35

Heute Nacht ist um 1:42 wieder eine VM eingefroren. Diesesmal müsste es mit den Einträgen im Logfile zusammenpassen, da die ESXi Systemzeit ja 2 Stunden früher ist:

Code: Alles auswählen

2019-09-13T23:41:56.986Z| vmx| I125: GuestRpcSendTimedOut: message to toolbox timed out.
2019-09-13T23:42:16.990Z| vmx| I125: GuestRpcSendTimedOut: message to toolbox timed out.
2019-09-13T23:42:16.990Z| vmx| I125: GuestRpc: app toolbox's second ping timeout; assuming app is down
2019-09-13T23:42:16.991Z| vmx| I125: GuestRpc: Reinitializing Channel 0(toolbox)
2019-09-13T23:42:16.991Z| vmx| I125: GuestMsg: Channel 0, Cannot unpost because the previous post is already completed
2019-09-13T23:42:37.706Z| vmx| I125: GuestRpcSendTimedOut: message to toolbox-dnd timed out.
2019-09-13T23:42:39.018Z| vcpu-0| I125: Tools: Tools heartbeat timeout.
2019-09-13T23:42:39.018Z| vcpu-0| I125: Tools: Running status rpc handler: 1 => 0.
2019-09-13T23:42:39.018Z| vcpu-0| I125: Tools: Changing running status: 1 => 0.
2019-09-13T23:42:57.711Z| vmx| I125: GuestRpcSendTimedOut: message to toolbox-dnd timed out.
2019-09-13T23:42:57.711Z| vmx| I125: GuestRpc: app toolbox-dnd's second ping timeout; assuming app is down
2019-09-13T23:42:57.712Z| vmx| I125: GuestRpc: Reinitializing Channel 2(toolbox-dnd)
2019-09-13T23:42:57.712Z| vmx| I125: GuestMsg: Channel 2, Cannot unpost because the previous post is already completed


Kann jemand hier was rauslesen?

Profi
Beiträge: 802
Registriert: 18.03.2005, 14:05
Wohnort: Ludwigshafen

Re: VMs manchmal komplett eingefroren

Beitragvon Martin » 14.09.2019, 09:53

Die Zeilen des Logfiles zeigen nur, daß das OS in der VM nicht mehr reagiert und damit der Host kein Heartbeat der VMware Tools erhält.

Member
Beiträge: 119
Registriert: 07.01.2017, 04:25

Re: VMs manchmal komplett eingefroren

Beitragvon jgreil » 14.09.2019, 09:59

Ok, danke.
Wisst ihr, wo ich sonst ansetzen könnte um den Fehler zu finden?

Profi
Beiträge: 802
Registriert: 18.03.2005, 14:05
Wohnort: Ludwigshafen

Re: VMs manchmal komplett eingefroren

Beitragvon Martin » 14.09.2019, 19:31

Gibt's Logfiles innerhalb der VMs?

Member
Beiträge: 119
Registriert: 07.01.2017, 04:25

Re: VMs manchmal komplett eingefroren

Beitragvon jgreil » 14.09.2019, 19:33

Meinst du z.B. den Windows Eventviewer in der VM?
Läuft überall Win 10.

Jenseits von Gut & Böse
Beiträge: 11813
Registriert: 02.08.2008, 15:06
Wohnort: Hannover/Wuerzburg
Kontaktdaten:

Re: VMs manchmal komplett eingefroren

Beitragvon irix » 14.09.2019, 19:38

Ja.. windows eventlog.

Ansonsten hostd.log auf dem Host wenn in dem vmware.log nichts ist. Ein Supportbundle erstellen und GSS fragen ist nicht drin oder?

Gruss
Joerg

King of the Hill
Beiträge: 12611
Registriert: 01.10.2008, 12:54
Wohnort: laut USV-Log am Ende der Welt...

Re: VMs manchmal komplett eingefroren

Beitragvon Dayworker » 23.09.2019, 07:22

rprengel hat geschrieben:
Dayworker hat geschrieben:Bis auf die Bildschirmabschaltung stimme ich dem von "rprengel" zu, weil dessen Abschaltung keinen Einfluß auf die CPU hat. Anders sieht es dagegen mit einem beliebigen Bildschirmschoner aus, der gehört abgeschaltet, weil er die CPU für die in SW abgebildete Graka belastet.


Hallo,
ich habe die Erfahrung gemacht das mnchmal rdp Ärger macht wenn der Bildschirm inaktiv ist.
Gruss

Das RDP-Problem mit Schwarzen Fenstern ist nur in der 1903 vorhanden und läßt sich unter Nutzung der XDDM-Treiber umgehen. Richtig beseitigt wurde das Problem erst mit dem kumulativen Update KB4512941, daß jedoch leider - inzwischen muß man eher sagen wieder - mit neuen Bugs daherkommt.


Zurück zu „vSphere 6.7“

Wer ist online?

Mitglieder in diesem Forum: 0 Mitglieder und 1 Gast