whitetiger264 commented on issue #6836:
URL: https://github.com/apache/cloudstack/issues/6836#issuecomment-1283521520
Hi @slavkap here you go:
```
2022-10-17 19:57:03,622 DEBUG [cloud.agent.Agent]
(agentRequest-Handler-2:null) (logid:917cdd39) Request:Seq
1-8475211548757852179: { Cmd , MgmtId: 2619780474, via: 1, Ver: v1, Flags:
100011,
[{"org.apache.cloudstack.storage.command.CreateObjectCommand":{"data":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"volume":{"uuid":"635fd5b5-bf14-4cf4-979c-be8a649d5189","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"81ffca3a-9775-375d-a1c0-9504c0ec3d89","name":"Primary","id":"2","poolType":"NetworkFilesystem","host":"MANAGEMENTHOSTIP","path":"/mnt/primary","port":"2049","url":"NetworkFilesystem://MANAGEMENTHOSTIP/mnt/primary/?ROLE=Primary&STOREUUID=81ffca3a-9775-375d-a1c0-9504c0ec3d89","isManaged":"false"}},"name":"ROOT-19","size":"(50,00
GB)
53687091200","path":"635fd5b5-bf14-4cf4-979c-be8a649d5189","volumeId":"24","vmName":"i-2-19-VM","accountId":"2","format":"QCOW2","provisioningType":"THIN","poolId":"2","id":"24","deviceId":"0",
"cacheMode":"NONE","hypervisorType":"KVM","directDownload":"false","deployAsIs":"false"},"dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"81ffca3a-9775-375d-a1c0-9504c0ec3d89","name":"Primary","id":"2","poolType":"NetworkFilesystem","host":"MANAGEMENTHOSTIP","path":"/mnt/primary","port":"2049","url":"NetworkFilesystem://MANAGEMENTHOSTIP/mnt/primary/?ROLE=Primary&STOREUUID=81ffca3a-9775-375d-a1c0-9504c0ec3d89","isManaged":"false"}},"vmName":"i-2-19-VM","name":"Test006","hypervisorType":"KVM","id":"27","quiescevm":"false","physicalSize":"0"}},"wait":"0","bypassHostMaintenance":"false"}}]
}
2022-10-17 19:57:03,623 DEBUG [cloud.agent.Agent]
(agentRequest-Handler-2:null) (logid:917cdd39) Processing command:
org.apache.cloudstack.storage.command.CreateObjectCommand
2022-10-17 19:57:03,623 DEBUG
[storage.resource.StorageSubsystemCommandHandlerBase]
(agentRequest-Handler-2:null) (logid:917cdd39) Executing command
CreateObjectCommand:
[{"data":{"volume":{"uuid":"635fd5b5-bf14-4cf4-979c-be8a649d5189","volumeType":"ROOT","dataStore":{"uuid":"81ffca3a-9775-375d-a1c0-9504c0ec3d89","name":"Primary","id":2,"poolType":"NetworkFilesystem","host":"MANAGEMENTHOSTIP","path":"/mnt/primary","port":2049,"url":"NetworkFilesystem://MANAGEMENTHOSTIP/mnt/primary/?ROLE\u003dPrimary\u0026STOREUUID\u003d81ffca3a-9775-375d-a1c0-9504c0ec3d89","isManaged":false},"name":"ROOT-19","size":53687091200,"path":"635fd5b5-bf14-4cf4-979c-be8a649d5189","volumeId":24,"vmName":"i-2-19-VM","accountId":2,"format":"QCOW2","provisioningType":"THIN","poolId":2,"id":24,"deviceId":0,"cacheMode":"NONE","hypervisorType":"KVM","directDownload":false,"deployAsIs":false},"dataStore":{"uuid":"81ffca3a-9775-375d-a1c0-9504c0ec3d89","name":"Primary","id":2,"poolType":"NetworkFilesystem","host":"
MANAGEMENTHOSTIP","path":"/mnt/primary","port":2049,"url":"NetworkFilesystem://MANAGEMENTHOSTIP/mnt/primary/?ROLE\u003dPrimary\u0026STOREUUID\u003d81ffca3a-9775-375d-a1c0-9504c0ec3d89","isManaged":false},"vmName":"i-2-19-VM","name":"Test006","hypervisorType":"KVM","id":27,"quiescevm":false,"physicalSize":0},"contextMap":{"logid":"917cdd39","job":"job-955/job-956"},"wait":0,"bypassHostMaintenance":false}].
2022-10-17 19:57:03,623 DEBUG [kvm.resource.LibvirtConnection]
(agentRequest-Handler-2:null) (logid:917cdd39) Looking for libvirtd connection
at: qemu:///system
2022-10-17 19:57:03,636 INFO [kvm.storage.LibvirtStorageAdaptor]
(agentRequest-Handler-2:null) (logid:917cdd39) Trying to fetch storage pool
81ffca3a-9775-375d-a1c0-9504c0ec3d89 from libvirt
2022-10-17 19:57:03,636 DEBUG [kvm.resource.LibvirtConnection]
(agentRequest-Handler-2:null) (logid:917cdd39) Looking for libvirtd connection
at: qemu:///system
2022-10-17 19:57:03,651 DEBUG [kvm.storage.LibvirtStorageAdaptor]
(agentRequest-Handler-2:null) (logid:917cdd39) Successfully refreshed pool
81ffca3a-9775-375d-a1c0-9504c0ec3d89 Capacity: (870,86 GB) 935081279488 Used:
(347,48 GB) 373108506624 Available: (523,38 GB) 561972772864
2022-10-17 19:57:03,651 INFO [kvm.storage.LibvirtStorageAdaptor]
(agentRequest-Handler-2:null) (logid:917cdd39) Trying to fetch storage pool
81ffca3a-9775-375d-a1c0-9504c0ec3d89 from libvirt
2022-10-17 19:57:03,651 DEBUG [kvm.resource.LibvirtConnection]
(agentRequest-Handler-2:null) (logid:917cdd39) Looking for libvirtd connection
at: qemu:///system
2022-10-17 19:57:03,666 DEBUG [kvm.storage.LibvirtStorageAdaptor]
(agentRequest-Handler-2:null) (logid:917cdd39) Successfully refreshed pool
81ffca3a-9775-375d-a1c0-9504c0ec3d89 Capacity: (870,86 GB) 935081279488 Used:
(347,48 GB) 373108506624 Available: (523,38 GB) 561972772864
2022-10-17 19:57:03,694 DEBUG [kvm.storage.KVMStorageProcessor]
(agentRequest-Handler-2:null) (logid:917cdd39) Pool
[{"uuid":"81ffca3a-9775-375d-a1c0-9504c0ec3d89","localPath":"\/mnt\/81ffca3a-9775-375d-a1c0-9504c0ec3d89"}]
has enough available size [561972772864] to take volume
[{"name":"635fd5b5-bf14-4cf4-979c-be8a649d5189","path":"\/mnt\/81ffca3a-9775-375d-a1c0-9504c0ec3d89\/635fd5b5-bf14-4cf4-979c-be8a649d5189","size":12770979840}]
snapshot.
2022-10-17 19:57:03,862 DEBUG [kvm.storage.KVMStorageProcessor]
(agentRequest-Handler-2:null) (logid:917cdd39) Snapshot
[2e77acb7-1be4-4417-858e-a1d0890f44f0] took [0] seconds to finish.
2022-10-17 19:57:16,568 DEBUG [kvm.resource.LibvirtConnection]
(Thread-1:null) (logid:) Looking for libvirtd connection at: qemu:///system
2022-10-17 19:57:16,581 DEBUG [kvm.resource.KVMHAMonitor] (Thread-1:null)
(logid:) Found NFS storage pool [81ffca3a-9775-375d-a1c0-9504c0ec3d89] in
libvirt, continuing.
2022-10-17 19:57:16,581 DEBUG [kvm.resource.KVMHAMonitor] (Thread-1:null)
(logid:) Executing:
/usr/share/cloudstack-common/scripts/vm/hypervisor/kvm/kvmheartbeat.sh -i
MANAGEMENTHOSTIP -p /mnt/primary -m /mnt/81ffca3a-9775-375d-a1c0-9504c0ec3d89
-h KVMHOSTIP
2022-10-17 19:57:16,582 DEBUG [kvm.resource.KVMHAMonitor] (Thread-1:null)
(logid:) Executing while with timeout : 60000
2022-10-17 19:57:16,609 DEBUG [kvm.resource.KVMHAMonitor] (Thread-1:null)
(logid:) Execution is successful.
2022-10-17 20:59:18,347 DEBUG [kvm.storage.KVMStorageProcessor]
(agentRequest-Handler-2:null) (logid:917cdd39) Copied
volumeTO[uuid=635fd5b5-bf14-4cf4-979c-be8a649d5189|path=635fd5b5-bf14-4cf4-979c-be8a649d5189|datastore=PrimaryDataStoreTO[uuid=81ffca3a-9775-375d-a1c0-9504c0ec3d89|name=Primary|id=2|pooltype=NetworkFilesystem]]
snapshot from
[/mnt/81ffca3a-9775-375d-a1c0-9504c0ec3d89/635fd5b5-bf14-4cf4-979c-be8a649d5189]
to
[/mnt/81ffca3a-9775-375d-a1c0-9504c0ec3d89/snapshots/2e77acb7-1be4-4417-858e-a1d0890f44f0].
2022-10-17 20:59:18,347 DEBUG [resource.wrapper.LibvirtUtilitiesHelper]
(agentRequest-Handler-2:null) (logid:917cdd39) The current Libvirt's version
[8000000] supports the flag '--delete' on command 'virsh blockcommit'.
2022-10-17 20:59:18,347 DEBUG [utils.script.Script]
(agentRequest-Handler-2:null) (logid:917cdd39) Executing: /bin/bash -c virsh
blockcommit i-2-19-VM vda --base
/mnt/81ffca3a-9775-375d-a1c0-9504c0ec3d89/635fd5b5-bf14-4cf4-979c-be8a649d5189
--active --wait --delete --pivot
2022-10-17 20:59:18,348 DEBUG [utils.script.Script]
(agentRequest-Handler-2:null) (logid:917cdd39) Executing while with timeout :
3600000
2022-10-17 20:59:19,250 DEBUG [kvm.resource.LibvirtConnection]
(Thread-1:null) (logid:) Looking for libvirtd connection at: qemu:///system
2022-10-17 20:59:19,263 DEBUG [kvm.resource.KVMHAMonitor] (Thread-1:null)
(logid:) Found NFS storage pool [81ffca3a-9775-375d-a1c0-9504c0ec3d89] in
libvirt, continuing.
2022-10-17 20:59:19,263 DEBUG [kvm.resource.KVMHAMonitor] (Thread-1:null)
(logid:) Executing:
/usr/share/cloudstack-common/scripts/vm/hypervisor/kvm/kvmheartbeat.sh -i
MANAGEMENTHOSTIP -p /mnt/primary -m /mnt/81ffca3a-9775-375d-a1c0-9504c0ec3d89
-h KVMHOSTIP
2022-10-17 20:59:19,264 DEBUG [kvm.resource.KVMHAMonitor] (Thread-1:null)
(logid:) Executing while with timeout : 60000
2022-10-17 20:59:19,786 DEBUG [kvm.resource.KVMHAMonitor] (Thread-1:null)
(logid:) Execution is successful.
2022-10-17 20:59:19,786 DEBUG [kvm.resource.KVMHAMonitor] (Thread-1:null)
(logid:) The command
(/usr/share/cloudstack-common/scripts/vm/hypervisor/kvm/kvmheartbeat.sh -i
MANAGEMENTHOSTIP -p /mnt/primary -m /mnt/81ffca3a-9775-375d-a1c0-9504c0ec3d89
-h KVMHOSTIP ), to the pool [81ffca3a-9775-375d-a1c0-9504c0ec3d89], has the
result [null].
2022-10-17 20:59:25,449 DEBUG [utils.script.Script]
(agentRequest-Handler-2:null) (logid:917cdd39) Execution is successful.
2022-10-17 20:59:25,449 DEBUG [kvm.storage.KVMStorageProcessor]
(agentRequest-Handler-2:null) (logid:917cdd39) Successfully merged snapshot
[2e77acb7-1be4-4417-858e-a1d0890f44f0] into VM [i-2-19-VM]
volumeTO[uuid=635fd5b5-bf14-4cf4-979c-be8a649d5189|path=635fd5b5-bf14-4cf4-979c-be8a649d5189|datastore=PrimaryDataStoreTO[uuid=81ffca3a-9775-375d-a1c0-9504c0ec3d89|name=Primary|id=2|pooltype=NetworkFilesystem]]
base file.
2022-10-17 20:59:25,449 DEBUG [kvm.storage.KVMStorageProcessor]
(agentRequest-Handler-2:null) (logid:917cdd39) The current Libvirt's version
supports the flag '--delete' on command 'virsh blockcommit', we will skip the
manually deletion of the unused snapshot file
[/mnt/81ffca3a-9775-375d-a1c0-9504c0ec3d89/2e77acb7-1be4-4417-858e-a1d0890f44f0]
as it already was automatically deleted.
2022-10-17 20:59:25,450 DEBUG [cloud.agent.Agent]
(agentRequest-Handler-2:null) (logid:917cdd39) Seq 1-8475211548757852179: {
Ans: , MgmtId: 2619780474, via: 1, Ver: v1, Flags: 10,
[{"org.apache.cloudstack.storage.command.CreateObjectAnswer":{"data":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"/mnt/81ffca3a-9775-375d-a1c0-9504c0ec3d89/snapshots/2e77acb7-1be4-4417-858e-a1d0890f44f0","id":"0","quiescevm":"false","physicalSize":"0"}},"result":"true","wait":"0","bypassHostMaintenance":"false"}}]
}
2022-10-17 20:59:28,398 DEBUG [cloud.agent.Agent]
(agentRequest-Handler-4:null) (logid:c4d38962) Processing command:
com.cloud.agent.api.GetStorageStatsCommand
2022-10-17 20:59:28,398 INFO [kvm.storage.LibvirtStorageAdaptor]
(agentRequest-Handler-4:null) (logid:c4d38962) Trying to fetch storage pool
ff9a58d8-fb77-49c8-92d1-7c82fc456297 from libvirt
2022-10-17 20:59:28,398 DEBUG [kvm.resource.LibvirtConnection]
(agentRequest-Handler-4:null) (logid:c4d38962) Looking for libvirtd connection
at: qemu:///system
2022-10-17 20:59:28,413 INFO [kvm.storage.LibvirtStorageAdaptor]
(agentRequest-Handler-4:null) (logid:c4d38962) Asking libvirt to refresh
storage pool ff9a58d8-fb77-49c8-92d1-7c82fc456297
2022-10-17 20:59:28,415 DEBUG [kvm.storage.LibvirtStorageAdaptor]
(agentRequest-Handler-4:null) (logid:c4d38962) Successfully refreshed pool
ff9a58d8-fb77-49c8-92d1-7c82fc456297 Capacity: (1,6853 TB) 1853047648256 Used:
(5,88 GB) 6316314624 Available: (1,6796 TB) 1846731333632
2022-10-17 20:59:28,464 DEBUG [cloud.agent.Agent]
(agentRequest-Handler-3:null) (logid:c4d38962) Processing command:
com.cloud.agent.api.GetStorageStatsCommand
2022-10-17 20:59:28,464 INFO [kvm.storage.LibvirtStorageAdaptor]
(agentRequest-Handler-3:null) (logid:c4d38962) Trying to fetch storage pool
81ffca3a-9775-375d-a1c0-9504c0ec3d89 from libvirt
2022-10-17 20:59:28,464 DEBUG [kvm.resource.LibvirtConnection]
(agentRequest-Handler-3:null) (logid:c4d38962) Looking for libvirtd connection
at: qemu:///system
2022-10-17 20:59:28,478 INFO [kvm.storage.LibvirtStorageAdaptor]
(agentRequest-Handler-3:null) (logid:c4d38962) Asking libvirt to refresh
storage pool 81ffca3a-9775-375d-a1c0-9504c0ec3d89
2022-10-17 20:59:30,012 DEBUG [kvm.storage.LibvirtStorageAdaptor]
(agentRequest-Handler-3:null) (logid:c4d38962) Successfully refreshed pool
81ffca3a-9775-375d-a1c0-9504c0ec3d89 Capacity: (870,86 GB) 935081279488 Used:
(359,39 GB) 385887502336 Available: (511,48 GB) 549193777152
2022-10-17 20:59:37,235 DEBUG [kvm.resource.LibvirtComputingResource]
(UgentTask-5:null) (logid:) Executing:
/usr/share/cloudstack-common/scripts/vm/network/security_group.py
get_rule_logs_for_vms
2022-10-17 20:59:37,236 DEBUG [kvm.resource.LibvirtComputingResource]
(UgentTask-5:null) (logid:) Executing while with timeout : 1800000
2022-10-17 20:59:37,361 DEBUG [kvm.resource.LibvirtComputingResource]
(UgentTask-5:null) (logid:) Execution is successful.
```
This is from two different attempts on 2022-10-17.
--
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.
To unsubscribe, e-mail: [email protected]
For queries about this service, please contact Infrastructure at:
[email protected]
