:$
2025-03-18 11:51:50,773+01 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (EE-ManagedThreadFactory-engine-Thread-109671) [eafde1fa-a5ad-4139-8b30-a4ef8935c968] Running command: ShutdownVmCommand internal: false. Entities affected : ID: 324db7f7-cb34-43a0-b02b-d9bc2c8f8c8a Type: VMAction group SHUT_DOWN_VM with role type USER
2025-03-18 11:51:50,777+01 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (EE-ManagedThreadFactory-engine-Thread-109671) [eafde1fa-a5ad-4139-8b30-a4ef8935c968] Entered (VM 'test-vm-prellocated').
2025-03-18 11:51:50,777+01 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (EE-ManagedThreadFactory-engine-Thread-109671) [eafde1fa-a5ad-4139-8b30-a4ef8935c968] Sending shutdown command for VM 'test-vm-prellocated'.
2025-03-18 11:51:50,778+01 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (EE-ManagedThreadFactory-engine-Thread-109671) [eafde1fa-a5ad-4139-8b30-a4ef8935c968] START, DestroyVmVDSCommand( DestroyVmVDSCommandParameters:{hostId='14ecd63c-13e3-4638-b083-6761ba859b06', vmId='324db7f7-cb34-43a0-b02b-d9bc2c8f8c8a', secondsToWait='30', gracefully='true', reason='', ignoreNoVm='false'}), log id: 5d844a8b
2025-03-18 11:51:54,390+01 INFO [org.ovirt.engine.core.bll.snapshots.RemoveSnapshotSingleDiskLiveCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-55) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshotSingleDiskLive' (id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1') waiting on child command id: '42f7aa6b-7939-4654-9c7c-5a61eeb35028' type:'Merge' to complete
2025-03-18 11:51:55,391+01 INFO [org.ovirt.engine.core.bll.MergeCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-30) [67182d1b-712e-458c-9646-520d8d602da5] Waiting on merge command to complete (jobId = 89d5c1ff-2d1c-48f2-9e2f-1893201776db)
2025-03-18 11:52:00,394+01 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-95) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshot' (id: '627cdcfb-e028-4579-9d5e-d759bfd119cd') waiting on child command id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1' type:'RemoveSnapshotSingleDiskLive' to complete
2025-03-18 11:52:04,397+01 INFO [org.ovirt.engine.core.bll.snapshots.RemoveSnapshotSingleDiskLiveCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshotSingleDiskLive' (id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1') waiting on child command id: '42f7aa6b-7939-4654-9c7c-5a61eeb35028' type:'Merge' to complete
2025-03-18 11:52:05,397+01 INFO [org.ovirt.engine.core.bll.MergeCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-46) [67182d1b-712e-458c-9646-520d8d602da5] Waiting on merge command to complete (jobId = 89d5c1ff-2d1c-48f2-9e2f-1893201776db)
2025-03-18 11:52:10,400+01 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshot' (id: '627cdcfb-e028-4579-9d5e-d759bfd119cd') waiting on child command id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1' type:'RemoveSnapshotSingleDiskLive' to complete
2025-03-18 11:52:14,403+01 INFO [org.ovirt.engine.core.bll.snapshots.RemoveSnapshotSingleDiskLiveCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-79) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshotSingleDiskLive' (id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1') waiting on child command id: '42f7aa6b-7939-4654-9c7c-5a61eeb35028' type:'Merge' to complete
2025-03-18 11:52:15,034+01 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (default task-55) [4ebd2baf] Failed in 'SetVmTicketVDS' method
2025-03-18 11:52:15,039+01 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-55) [4ebd2baf] EVENT_ID: VDS_BROKER_COMMAND_FAILURE(10,802), VDSM ovirt-1.anonimize_domain.net command SetVmTicketVDS failed: Error while setting spice ticket
2025-03-18 11:52:15,039+01 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (default task-55) [4ebd2baf] Command 'org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand' return value 'StatusOnlyReturn [status=Status [code=18, message=Error while setting spice ticket]]'
2025-03-18 11:52:15,039+01 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (default task-55) [4ebd2baf] HostName = ovirt-1.anonimize_domain.net
2025-03-18 11:52:15,039+01 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (default task-55) [4ebd2baf] Command 'SetVmTicketVDSCommand(HostName = ovirt-1.anonimize_domain.net, SetVmTicketVDSCommandParameters:{hostId='14ecd63c-13e3-4638-b083-6761ba859b06', vmId='324db7f7-cb34-43a0-b02b-d9bc2c8f8c8a', protocol='VNC', ticket='0D1s69fg', validTime='120', userName='admin@ovirt', userId='4278d2f4-9628-4264-90f4-c59242b2203b', disconnectAction='LOCK_SCREEN', consoleDisconnectActionDelay='0'})' execution failed: VDSGenericException: VDSErrorException: Failed to SetVmTicketVDS, error = Error while setting spice ticket, code = 18
2025-03-18 11:52:15,039+01 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetVmTicketVDSCommand] (default task-55) [4ebd2baf] FINISH, SetVmTicketVDSCommand, return: , log id: 27467a24
2025-03-18 11:52:15,039+01 ERROR [org.ovirt.engine.core.bll.SetVmTicketCommand] (default task-55) [4ebd2baf] Command 'org.ovirt.engine.core.bll.SetVmTicketCommand' failed: EngineException: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to SetVmTicketVDS, error = Error while setting spice ticket, code = 18 (Failed with error ticketErr and code 18)
2025-03-18 11:52:15,040+01 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (EE-ManagedThreadFactory-engine-Thread-109671) [eafde1fa-a5ad-4139-8b30-a4ef8935c968] START, DestroyVDSCommand(HostName = ovirt-1.anonimize_domain.net, DestroyVmVDSCommandParameters:{hostId='14ecd63c-13e3-4638-b083-6761ba859b06', vmId='324db7f7-cb34-43a0-b02b-d9bc2c8f8c8a', secondsToWait='30', gracefully='true', reason='', ignoreNoVm='false'}), log id: 72240b52
2025-03-18 11:52:15,041+01 ERROR [org.ovirt.engine.core.bll.SetVmTicketCommand] (default task-55) [4ebd2baf] Transaction rolled-back for command 'org.ovirt.engine.core.bll.SetVmTicketCommand'.
2025-03-18 11:52:15,042+01 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (EE-ManagedThreadFactory-engine-Thread-109671) [eafde1fa-a5ad-4139-8b30-a4ef8935c968] FINISH, DestroyVDSCommand, return: , log id: 72240b52
2025-03-18 11:52:15,045+01 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (EE-ManagedThreadFactory-engine-Thread-109671) [eafde1fa-a5ad-4139-8b30-a4ef8935c968] FINISH, DestroyVmVDSCommand, return: PoweringDown, log id: 5d844a8b
2025-03-18 11:52:15,045+01 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-55) [4ebd2baf] EVENT_ID: VM_SET_TICKET_FAILED(165), User admin@ovirt@internalkeycloak-authz failed to initiate a console session for VM test-vm-prellocated
2025-03-18 11:52:15,052+01 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-109671) [eafde1fa-a5ad-4139-8b30-a4ef8935c968] EVENT_ID: USER_INITIATED_SHUTDOWN_VM(73), VM shutdown initiated by admin@ovirt@internalkeycloak-authz on VM test-vm-prellocated (Host: ovirt-1.anonimize_domain.net).
2025-03-18 11:52:15,403+01 INFO [org.ovirt.engine.core.bll.MergeCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-37) [67182d1b-712e-458c-9646-520d8d602da5] Waiting on merge command to complete (jobId = 89d5c1ff-2d1c-48f2-9e2f-1893201776db)
2025-03-18 11:52:20,406+01 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-11) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshot' (id: '627cdcfb-e028-4579-9d5e-d759bfd119cd') waiting on child command id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1' type:'RemoveSnapshotSingleDiskLive' to complete
2025-03-18 11:52:24,409+01 INFO [org.ovirt.engine.core.bll.snapshots.RemoveSnapshotSingleDiskLiveCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshotSingleDiskLive' (id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1') waiting on child command id: '42f7aa6b-7939-4654-9c7c-5a61eeb35028' type:'Merge' to complete
2025-03-18 11:52:25,410+01 INFO [org.ovirt.engine.core.bll.MergeCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [67182d1b-712e-458c-9646-520d8d602da5] Waiting on merge command to complete (jobId = 89d5c1ff-2d1c-48f2-9e2f-1893201776db)
2025-03-18 11:52:30,412+01 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-64) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshot' (id: '627cdcfb-e028-4579-9d5e-d759bfd119cd') waiting on child command id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1' type:'RemoveSnapshotSingleDiskLive' to complete
2025-03-18 11:52:34,415+01 INFO [org.ovirt.engine.core.bll.snapshots.RemoveSnapshotSingleDiskLiveCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-42) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshotSingleDiskLive' (id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1') waiting on child command id: '42f7aa6b-7939-4654-9c7c-5a61eeb35028' type:'Merge' to complete
2025-03-18 11:52:35,415+01 INFO [org.ovirt.engine.core.bll.MergeCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-12) [67182d1b-712e-458c-9646-520d8d602da5] Waiting on merge command to complete (jobId = 89d5c1ff-2d1c-48f2-9e2f-1893201776db)
2025-03-18 11:52:38,596+01 WARN [org.ovirt.engine.core.vdsbroker.monitoring.VmsMonitoring] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-41) [] skipping VM '324db7f7-cb34-43a0-b02b-d9bc2c8f8c8a' from this monitoring cycle - the VM data has changed since fetching the data
2025-03-18 11:52:40,418+01 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-10) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshot' (id: '627cdcfb-e028-4579-9d5e-d759bfd119cd') waiting on child command id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1' type:'RemoveSnapshotSingleDiskLive' to complete
2025-03-18 11:52:44,421+01 INFO [org.ovirt.engine.core.bll.snapshots.RemoveSnapshotSingleDiskLiveCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-4) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshotSingleDiskLive' (id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1') waiting on child command id: '42f7aa6b-7939-4654-9c7c-5a61eeb35028' type:'Merge' to complete
2025-03-18 11:52:45,422+01 INFO [org.ovirt.engine.core.bll.MergeCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-50) [67182d1b-712e-458c-9646-520d8d602da5] Waiting on merge command to complete (jobId = 89d5c1ff-2d1c-48f2-9e2f-1893201776db)
2025-03-18 11:52:50,425+01 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-31) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshot' (id: '627cdcfb-e028-4579-9d5e-d759bfd119cd') waiting on child command id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1' type:'RemoveSnapshotSingleDiskLive' to complete
2025-03-18 11:52:54,428+01 INFO [org.ovirt.engine.core.bll.snapshots.RemoveSnapshotSingleDiskLiveCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-99) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshotSingleDiskLive' (id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1') waiting on child command id: '42f7aa6b-7939-4654-9c7c-5a61eeb35028' type:'Merge' to complete
2025-03-18 11:52:55,428+01 INFO [org.ovirt.engine.core.bll.MergeCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-69) [67182d1b-712e-458c-9646-520d8d602da5] Waiting on merge command to complete (jobId = 89d5c1ff-2d1c-48f2-9e2f-1893201776db)
2025-03-18 11:53:00,431+01 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-34) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshot' (id: '627cdcfb-e028-4579-9d5e-d759bfd119cd') waiting on child command id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1' type:'RemoveSnapshotSingleDiskLive' to complete
2025-03-18 11:53:04,438+01 INFO [org.ovirt.engine.core.bll.snapshots.RemoveSnapshotSingleDiskLiveCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-78) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshotSingleDiskLive' (id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1') waiting on child command id: '42f7aa6b-7939-4654-9c7c-5a61eeb35028' type:'Merge' to complete
2025-03-18 11:53:05,439+01 INFO [org.ovirt.engine.core.bll.MergeCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-36) [67182d1b-712e-458c-9646-520d8d602da5] Waiting on merge command to complete (jobId = 89d5c1ff-2d1c-48f2-9e2f-1893201776db)
2025-03-18 11:53:10,442+01 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-63) [67182d1b-712e-458c-9646-520d8d602da5] Command 'RemoveSnapshot' (id: '627cdcfb-e028-4579-9d5e-d759bfd119cd') waiting on child command id: 'ac86d7b1-47e0-4ad9-b05d-cef8442d13f1' type:'RemoveSnapshotSingleDiskLive' to complete