Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 1474763 Details for
Bug 1614466
Ovirt guest agent reported as not running while taking a snapshot
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
Engine log
engine.log (text/plain), 154.02 KB, created by
Alex Kaouris
on 2018-08-09 16:34:12 UTC
(
hide
)
Description:
Engine log
Filename:
MIME Type:
Creator:
Alex Kaouris
Created:
2018-08-09 16:34:12 UTC
Size:
154.02 KB
patch
obsolete
>2018-08-09 19:12:13,734+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [720e6bdd] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 33d0a711 >2018-08-09 19:12:13,948+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeSnapshotInfoVDSCommand] (DefaultQuartzScheduler5) [2dadbc4f] START, GetGlusterVolumeSnapshotInfoVDSCommand(HostName = v0.setel-group.com, GlusterVolumeSnapshotVDSParameters:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f', volumeName='null'}), log id: 487fd596 >2018-08-09 19:12:14,184+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeSnapshotInfoVDSCommand] (DefaultQuartzScheduler5) [2dadbc4f] FINISH, GetGlusterVolumeSnapshotInfoVDSCommand, return: [], log id: 487fd596 >2018-08-09 19:12:14,246+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeSnapshotConfigInfoVDSCommand] (DefaultQuartzScheduler5) [2dadbc4f] START, GetGlusterVolumeSnapshotConfigInfoVDSCommand(HostName = v0.setel-group.com, GlusterVolumeSnapshotVDSParameters:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f', volumeName='null'}), log id: 97731ce >2018-08-09 19:12:14,475+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeSnapshotConfigInfoVDSCommand] (DefaultQuartzScheduler5) [2dadbc4f] FINISH, GetGlusterVolumeSnapshotConfigInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.gluster.GlusterSnapshotConfigInfo@932f61f9, log id: 97731ce >2018-08-09 19:12:18,765+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler3) [6d920ae4] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 33a6c315 >2018-08-09 19:12:19,331+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler3) [6d920ae4] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 33a6c315 >2018-08-09 19:12:19,710+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler3) [6d920ae4] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 741b63d9 >2018-08-09 19:12:20,022+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler3) [6d920ae4] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 741b63d9 >2018-08-09 19:12:25,052+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler10) [58b956a2] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 5f00bc95 >2018-08-09 19:12:27,031+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler10) [58b956a2] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 5f00bc95 >2018-08-09 19:12:27,039+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler10) [58b956a2] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 7309d577 >2018-08-09 19:12:27,393+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler10) [58b956a2] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 7309d577 >2018-08-09 19:12:27,461+03 INFO [org.ovirt.engine.core.bll.RunVmCommand] (default task-8) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] Lock Acquired to object 'EngineLock:{exclusiveLocks='[8897f42d-0360-4129-abcf-f5697d0b84e8=VM]', sharedLocks=''}' >2018-08-09 19:12:27,573+03 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-8) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] START, IsVmDuringInitiatingVDSCommand( IsVmDuringInitiatingVDSCommandParameters:{vmId='8897f42d-0360-4129-abcf-f5697d0b84e8'}), log id: 37df139d >2018-08-09 19:12:27,573+03 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-8) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 37df139d >2018-08-09 19:12:27,708+03 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] Running command: RunVmCommand internal: false. Entities affected : ID: 8897f42d-0360-4129-abcf-f5697d0b84e8 Type: VMAction group RUN_VM with role type USER >2018-08-09 19:12:27,750+03 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] Penalizing host 'v1.setel-group.com' because it is not preferred. >2018-08-09 19:12:27,750+03 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] Penalizing host 'v0.setel-group.com' because it is not preferred. >2018-08-09 19:12:27,770+03 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] START, UpdateVmDynamicDataVDSCommand( UpdateVmDynamicDataVDSCommandParameters:{hostId='null', vmId='8897f42d-0360-4129-abcf-f5697d0b84e8', vmDynamic='org.ovirt.engine.core.common.businessentities.VmDynamic@13506d3b'}), log id: 64a703d8 >2018-08-09 19:12:28,744+03 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] FINISH, UpdateVmDynamicDataVDSCommand, log id: 64a703d8 >2018-08-09 19:12:28,749+03 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] START, CreateVDSCommand( CreateVDSCommandParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', vmId='8897f42d-0360-4129-abcf-f5697d0b84e8', vm='VM [CompanyTS-W2K16]'}), log id: 73f9cda1 >2018-08-09 19:12:28,752+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] START, CreateBrokerVDSCommand(HostName = v1.setel-group.com, CreateVDSCommandParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', vmId='8897f42d-0360-4129-abcf-f5697d0b84e8', vm='VM [CompanyTS-W2K16]'}), log id: 4938d1b4 >2018-08-09 19:12:28,779+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] VM <?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"> > <name>CompanyTS-W2K16</name> > <uuid>8897f42d-0360-4129-abcf-f5697d0b84e8</uuid> > <memory>4194304</memory> > <currentMemory>4194304</currentMemory> > <vcpu current="2">16</vcpu> > <sysinfo type="smbios"> > <system> > <entry name="manufacturer">oVirt</entry> > <entry name="product">OS-NAME:</entry> > <entry name="version">OS-VERSION:</entry> > <entry name="serial">HOST-SERIAL:</entry> > <entry name="uuid">8897f42d-0360-4129-abcf-f5697d0b84e8</entry> > </system> > </sysinfo> > <clock offset="variable" adjustment="10800"> > <timer name="hypervclock" present="yes"/> > <timer name="rtc" tickpolicy="catchup"/> > <timer name="pit" tickpolicy="delay"/> > <timer name="hpet" present="no"/> > </clock> > <features> > <acpi/> > <hyperv> > <relaxed state="on"/> > <vapic state="on"/> > <spinlocks state="on" retries="8191"/> > </hyperv> > </features> > <cpu match="exact"> > <model>Westmere</model> > <topology cores="1" threads="1" sockets="16"/> > <numa> > <cell id="0" cpus="0,1" memory="4194304"/> > </numa> > </cpu> > <cputune/> > <devices> > <input type="mouse" bus="ps2"/> > <channel type="unix"> > <target type="virtio" name="ovirt-guest-agent.0"/> > <source mode="bind" path="/var/lib/libvirt/qemu/channels/8897f42d-0360-4129-abcf-f5697d0b84e8.ovirt-guest-agent.0"/> > </channel> > <channel type="unix"> > <target type="virtio" name="org.qemu.guest_agent.0"/> > <source mode="bind" path="/var/lib/libvirt/qemu/channels/8897f42d-0360-4129-abcf-f5697d0b84e8.org.qemu.guest_agent.0"/> > </channel> > <controller type="ide"> > <address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"/> > </controller> > <controller type="virtio-serial" index="0" ports="16"> > <alias name="ua-1e589616-a662-4d14-8923-845252a69dd1"/> > <address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"/> > </controller> > <graphics type="spice" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" tlsPort="-1"> > <channel name="main" mode="secure"/> > <channel name="inputs" mode="secure"/> > <channel name="cursor" mode="secure"/> > <channel name="playback" mode="secure"/> > <channel name="record" mode="secure"/> > <channel name="display" mode="secure"/> > <channel name="smartcard" mode="secure"/> > <channel name="usbredir" mode="secure"/> > <listen type="network" network="vdsm-ovirtmgmt"/> > </graphics> > <video> > <model type="qxl" vram="8192" heads="1" ram="65536" vgamem="16384"/> > <alias name="ua-ef04c8b5-9056-4fe1-86fc-db579682b982"/> > <address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"/> > </video> > <controller type="usb" model="piix3-uhci" index="0"> > <address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"/> > </controller> > <memballoon model="none"/> > <channel type="spicevmc"> > <target type="virtio" name="com.redhat.spice.0"/> > </channel> > <interface type="bridge"> > <model type="virtio"/> > <link state="up"/> > <source bridge="ovirtmgmt"/> > <alias name="ua-aba33e9d-2d9f-4b41-9ead-b9620c3e6fbd"/> > <address bus="0x00" domain="0x0000" function="0x0" slot="0x05" type="pci"/> > <mac address="02:ae:ec:b5:8a:ee"/> > <filterref filter="vdsm-no-mac-spoofing"/> > <bandwidth/> > </interface> > <disk type="file" device="cdrom" snapshot="no"> > <driver name="qemu" type="raw" error_policy="report"/> > <source file="" startupPolicy="optional"/> > <target dev="hdc" bus="ide"/> > <readonly/> > <alias name="ua-e0766cb3-d248-4bb2-86e1-a308d70cf7b1"/> > <address bus="1" controller="0" unit="0" type="drive" target="0"/> > </disk> > <disk snapshot="no" type="file" device="disk"> > <target dev="vda" bus="virtio"/> > <source file="/rhev/data-center/00000001-0001-0001-0001-000000000311/142bbde6-ef9d-4a52-b9da-2de533c1f1bd/images/6a35e7d2-f62f-4e8e-b82a-f6d706ccda25/369dc8d7-2cf5-45f5-ae1f-7410a4208cb9"/> > <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> > <alias name="ua-6a35e7d2-f62f-4e8e-b82a-f6d706ccda25"/> > <address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"/> > <boot order="1"/> > <serial>6a35e7d2-f62f-4e8e-b82a-f6d706ccda25</serial> > </disk> > </devices> > <pm> > <suspend-to-disk enabled="no"/> > <suspend-to-mem enabled="no"/> > </pm> > <os> > <type arch="x86_64" machine="pc-i440fx-rhel7.3.0">hvm</type> > <smbios mode="sysinfo"/> > </os> > <metadata> > <ovirt-tune:qos/> > <ovirt-vm:vm> > <minGuaranteedMemoryMb type="int">2730</minGuaranteedMemoryMb> > <clusterVersion>4.2</clusterVersion> > <ovirt-vm:custom/> > <ovirt-vm:device mac_address="02:ae:ec:b5:8a:ee"> > <ovirt-vm:custom/> > </ovirt-vm:device> > <ovirt-vm:device devtype="disk" name="vda"> > <ovirt-vm:poolID>00000001-0001-0001-0001-000000000311</ovirt-vm:poolID> > <ovirt-vm:volumeID>369dc8d7-2cf5-45f5-ae1f-7410a4208cb9</ovirt-vm:volumeID> > <ovirt-vm:imageID>6a35e7d2-f62f-4e8e-b82a-f6d706ccda25</ovirt-vm:imageID> > <ovirt-vm:domainID>142bbde6-ef9d-4a52-b9da-2de533c1f1bd</ovirt-vm:domainID> > </ovirt-vm:device> > <launchPaused>false</launchPaused> > <resumeBehavior>auto_resume</resumeBehavior> > </ovirt-vm:vm> > </metadata> ></domain> > >2018-08-09 19:12:28,807+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] FINISH, CreateBrokerVDSCommand, log id: 4938d1b4 >2018-08-09 19:12:28,822+03 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] FINISH, CreateVDSCommand, return: WaitForLaunch, log id: 73f9cda1 >2018-08-09 19:12:28,823+03 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] Lock freed to object 'EngineLock:{exclusiveLocks='[8897f42d-0360-4129-abcf-f5697d0b84e8=VM]', sharedLocks=''}' >2018-08-09 19:12:28,843+03 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-337646) [ef8dd6e1-5fd1-4edb-a7cd-85dfa796bffe] EVENT_ID: USER_STARTED_VM(153), VM CompanyTS-W2K16 was started by admin@internal-authz (Host: v1.setel-group.com). >2018-08-09 19:12:32,423+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler7) [d391cc] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 6b42a3ef >2018-08-09 19:12:33,005+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler7) [d391cc] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 6b42a3ef >2018-08-09 19:12:33,014+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler7) [d391cc] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 72c792a7 >2018-08-09 19:12:33,097+03 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-12) [] VM '8897f42d-0360-4129-abcf-f5697d0b84e8'(CompanyTS-W2K16) moved from 'WaitForLaunch' --> 'PoweringUp' >2018-08-09 19:12:33,332+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler7) [d391cc] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 72c792a7 >2018-08-09 19:12:34,299+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-12) [] START, DumpXmlsVDSCommand(HostName = v1.setel-group.com, Params:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', vmIds='[8897f42d-0360-4129-abcf-f5697d0b84e8]'}), log id: 47b69e96 >2018-08-09 19:12:34,324+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-12) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=8897f42d-0360-4129-abcf-f5697d0b84e8, devices=[Ljava.util.Map;@7c871ac3, guestDiskMapping={}}], log id: 47b69e96 >2018-08-09 19:12:34,325+03 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.VmDevicesMonitoring] (ForkJoinPool-1-worker-12) [] VM '8897f42d-0360-4129-abcf-f5697d0b84e8' managed non pluggable device was removed unexpectedly from libvirt: 'VmDevice:{id='VmDeviceId:{deviceId='6b16deae-6d89-4559-a414-9ebbb605dc67', vmId='8897f42d-0360-4129-abcf-f5697d0b84e8'}', device='usb_2_1_2', type='HOSTDEV', specParams='[iommuPlaceholder=false]', address='', managed='true', plugged='false', readOnly='false', deviceAlias='', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}' >2018-08-09 19:12:38,362+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [3a8e747b] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 9d85feb >2018-08-09 19:12:38,945+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [3a8e747b] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 9d85feb >2018-08-09 19:12:40,347+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [3a8e747b] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 1091c9bd >2018-08-09 19:12:40,672+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [3a8e747b] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 1091c9bd >2018-08-09 19:12:43,864+03 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmsStatisticsFetcher] (EE-ManagedThreadFactory-engineScheduled-Thread-8) [] Fetched 10 VMs from VDS '12dfea4a-8142-484e-b912-0cbd5f281aba' >2018-08-09 19:12:45,701+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler3) [12b9273f] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 65aa175d >2018-08-09 19:12:46,289+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler3) [12b9273f] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 65aa175d >2018-08-09 19:12:46,297+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler3) [12b9273f] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 7ce16538 >2018-08-09 19:12:46,715+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler3) [12b9273f] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 7ce16538 >2018-08-09 19:12:51,744+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [32c1090e] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 4627df2e >2018-08-09 19:12:53,109+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [32c1090e] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 4627df2e >2018-08-09 19:12:53,117+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [32c1090e] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 1e9bc56 >2018-08-09 19:12:53,435+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [32c1090e] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 1e9bc56 >2018-08-09 19:12:58,463+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler2) [413743d9] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 3c6da0be >2018-08-09 19:12:59,049+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler2) [413743d9] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 3c6da0be >2018-08-09 19:12:59,058+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler2) [413743d9] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 53dc4a92 >2018-08-09 19:12:59,368+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler2) [413743d9] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 53dc4a92 >2018-08-09 19:13:04,396+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler9) [f80d88] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 48584fdd >2018-08-09 19:13:04,976+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler9) [f80d88] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 48584fdd >2018-08-09 19:13:04,984+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler9) [f80d88] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 60505495 >2018-08-09 19:13:05,305+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler9) [f80d88] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 60505495 >2018-08-09 19:13:10,375+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler1) [3f870cb7] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 78f45d04 >2018-08-09 19:13:10,981+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler1) [3f870cb7] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 78f45d04 >2018-08-09 19:13:11,000+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler1) [3f870cb7] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 67fbf322 >2018-08-09 19:13:11,327+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler1) [3f870cb7] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 67fbf322 >2018-08-09 19:13:13,114+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler3) [3b93cd3f] START, GlusterTasksListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 6958be00 >2018-08-09 19:13:13,424+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler3) [3b93cd3f] FINISH, GlusterTasksListVDSCommand, return: [], log id: 6958be00 >2018-08-09 19:13:15,817+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-27) [] START, DumpXmlsVDSCommand(HostName = v1.setel-group.com, Params:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', vmIds='[8897f42d-0360-4129-abcf-f5697d0b84e8]'}), log id: 442aef90 >2018-08-09 19:13:15,858+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-27) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=8897f42d-0360-4129-abcf-f5697d0b84e8, devices=[Ljava.util.Map;@356fcf35, guestDiskMapping={6a35e7d2-f62f-4e8e-b82a-f6d706ccda25={name=\\.\PHYSICALDRIVE0}}}], log id: 442aef90 >2018-08-09 19:13:15,859+03 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.VmDevicesMonitoring] (EE-ManagedThreadFactory-engineScheduled-Thread-27) [] VM '8897f42d-0360-4129-abcf-f5697d0b84e8' managed non pluggable device was removed unexpectedly from libvirt: 'VmDevice:{id='VmDeviceId:{deviceId='6b16deae-6d89-4559-a414-9ebbb605dc67', vmId='8897f42d-0360-4129-abcf-f5697d0b84e8'}', device='usb_2_1_2', type='HOSTDEV', specParams='[iommuPlaceholder=false]', address='', managed='true', plugged='false', readOnly='false', deviceAlias='', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}' >2018-08-09 19:13:16,356+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [5daf03c0] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 5c5454dd >2018-08-09 19:13:16,939+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [5daf03c0] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 5c5454dd >2018-08-09 19:13:16,948+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [5daf03c0] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 148d34e8 >2018-08-09 19:13:17,273+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [5daf03c0] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 148d34e8 >2018-08-09 19:13:22,302+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler2) [6c4ebfef] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 544466cf >2018-08-09 19:13:22,899+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler2) [6c4ebfef] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 544466cf >2018-08-09 19:13:22,907+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler2) [6c4ebfef] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 528566e0 >2018-08-09 19:13:23,255+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler2) [6c4ebfef] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 528566e0 >2018-08-09 19:13:28,318+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler5) [146adb94] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 29a8d287 >2018-08-09 19:13:28,900+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler5) [146adb94] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 29a8d287 >2018-08-09 19:13:28,918+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler5) [146adb94] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 5cbdc794 >2018-08-09 19:13:29,243+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler5) [146adb94] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 5cbdc794 >2018-08-09 19:13:30,990+03 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (EE-ManagedThreadFactory-engineScheduled-Thread-60) [] VM '8897f42d-0360-4129-abcf-f5697d0b84e8'(CompanyTS-W2K16) moved from 'PoweringUp' --> 'Up' >2018-08-09 19:13:31,063+03 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engineScheduled-Thread-60) [] EVENT_ID: USER_RUN_VM(32), VM CompanyTS-W2K16 started on Host v1.setel-group.com >2018-08-09 19:13:31,075+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-60) [] START, DumpXmlsVDSCommand(HostName = v1.setel-group.com, Params:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', vmIds='[8897f42d-0360-4129-abcf-f5697d0b84e8]'}), log id: 3d7a7a94 >2018-08-09 19:13:31,082+03 INFO [org.ovirt.engine.core.bll.network.host.RefreshHostCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [5d5b6f4d] Running command: RefreshHostCommand internal: true. Entities affected : ID: 12dfea4a-8142-484e-b912-0cbd5f281aba Type: VDSAction group MANIPULATE_HOST with role type ADMIN >2018-08-09 19:13:31,087+03 INFO [org.ovirt.engine.core.bll.RefreshHostCapabilitiesCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [61a6cf8e] Before acquiring and wait lock 'EngineLock:{exclusiveLocks='[HOST_NETWORK12dfea4a-8142-484e-b912-0cbd5f281aba=HOST_NETWORK, 12dfea4a-8142-484e-b912-0cbd5f281aba=VDS]', sharedLocks=''}' >2018-08-09 19:13:31,087+03 INFO [org.ovirt.engine.core.bll.RefreshHostCapabilitiesCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [61a6cf8e] Lock-wait acquired to object 'EngineLock:{exclusiveLocks='[HOST_NETWORK12dfea4a-8142-484e-b912-0cbd5f281aba=HOST_NETWORK, 12dfea4a-8142-484e-b912-0cbd5f281aba=VDS]', sharedLocks=''}' >2018-08-09 19:13:31,108+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-60) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=8897f42d-0360-4129-abcf-f5697d0b84e8, devices=[Ljava.util.Map;@60994446, guestDiskMapping={6a35e7d2-f62f-4e8e-b82a-f6d706ccda25={name=\\.\PHYSICALDRIVE0}}}], log id: 3d7a7a94 >2018-08-09 19:13:31,113+03 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.VmDevicesMonitoring] (EE-ManagedThreadFactory-engineScheduled-Thread-60) [] VM '8897f42d-0360-4129-abcf-f5697d0b84e8' managed non pluggable device was removed unexpectedly from libvirt: 'VmDevice:{id='VmDeviceId:{deviceId='6b16deae-6d89-4559-a414-9ebbb605dc67', vmId='8897f42d-0360-4129-abcf-f5697d0b84e8'}', device='usb_2_1_2', type='HOSTDEV', specParams='[iommuPlaceholder=false]', address='', managed='true', plugged='false', readOnly='false', deviceAlias='', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}' >2018-08-09 19:13:31,118+03 INFO [org.ovirt.engine.core.bll.RefreshHostCapabilitiesCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [61a6cf8e] Running command: RefreshHostCapabilitiesCommand internal: true. Entities affected : ID: 12dfea4a-8142-484e-b912-0cbd5f281aba Type: VDSAction group MANIPULATE_HOST with role type ADMIN >2018-08-09 19:13:31,119+03 INFO [org.ovirt.engine.core.bll.RefreshHostCapabilitiesCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [61a6cf8e] Before acquiring lock in order to prevent monitoring for host 'v1.setel-group.com' from data-center 'Setel' >2018-08-09 19:13:31,119+03 INFO [org.ovirt.engine.core.bll.RefreshHostCapabilitiesCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [61a6cf8e] Lock acquired, from now a monitoring of host will be skipped for host 'v1.setel-group.com' from data-center 'Setel' >2018-08-09 19:13:32,642+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoAsyncVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [61a6cf8e] START, GetHardwareInfoAsyncVDSCommand(HostName = v1.setel-group.com, VdsIdAndVdsVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', vds='Host[v1.setel-group.com,12dfea4a-8142-484e-b912-0cbd5f281aba]'}), log id: 4d916235 >2018-08-09 19:13:32,642+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoAsyncVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [61a6cf8e] FINISH, GetHardwareInfoAsyncVDSCommand, log id: 4d916235 >2018-08-09 19:13:32,642+03 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (EE-ManagedThreadFactory-engine-Thread-337666) [61a6cf8e] Host 'v1.setel-group.com' is running with SELinux in 'DISABLED' mode >2018-08-09 19:13:32,729+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [61a6cf8e] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 381702e2 >2018-08-09 19:13:33,313+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [61a6cf8e] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 381702e2 >2018-08-09 19:13:33,404+03 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [24286ca0] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: 12dfea4a-8142-484e-b912-0cbd5f281aba Type: VDS >2018-08-09 19:13:33,416+03 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [da261ee] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: 12dfea4a-8142-484e-b912-0cbd5f281aba Type: VDS >2018-08-09 19:13:33,420+03 INFO [org.ovirt.engine.core.bll.RefreshHostCapabilitiesCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [da261ee] Refresh host capabilities finished. Lock released. Monitoring can run now for host 'v1.setel-group.com' from data-center 'Setel' >2018-08-09 19:13:33,452+03 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-337666) [da261ee] EVENT_ID: HOST_REFRESHED_CAPABILITIES(606), Successfully refreshed the capabilities of host v1.setel-group.com. >2018-08-09 19:13:33,454+03 INFO [org.ovirt.engine.core.bll.RefreshHostCapabilitiesCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [da261ee] Lock freed to object 'EngineLock:{exclusiveLocks='[HOST_NETWORK12dfea4a-8142-484e-b912-0cbd5f281aba=HOST_NETWORK, 12dfea4a-8142-484e-b912-0cbd5f281aba=VDS]', sharedLocks=''}' >2018-08-09 19:13:33,464+03 INFO [org.ovirt.engine.core.bll.hostdev.RefreshHostDevicesCommand] (EE-ManagedThreadFactory-engine-Thread-337666) [61a6cf8e] Running command: RefreshHostDevicesCommand internal: true. Entities affected : ID: 12dfea4a-8142-484e-b912-0cbd5f281aba Type: VDSAction group MANIPULATE_HOST with role type ADMIN >2018-08-09 19:13:34,302+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [1e16b84d] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 6b7b3a7c >2018-08-09 19:13:34,876+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [1e16b84d] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 6b7b3a7c >2018-08-09 19:13:34,884+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [1e16b84d] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 32e2877b >2018-08-09 19:13:35,200+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [1e16b84d] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 32e2877b >2018-08-09 19:13:40,259+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler3) [21658657] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 40b1dd71 >2018-08-09 19:13:44,096+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler3) [21658657] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 40b1dd71 >2018-08-09 19:13:44,104+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler3) [21658657] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 70184cf6 >2018-08-09 19:13:44,423+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler3) [21658657] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 70184cf6 >2018-08-09 19:13:49,481+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler10) [2e64fac4] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 13438837 >2018-08-09 19:13:50,057+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler10) [2e64fac4] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 13438837 >2018-08-09 19:13:50,066+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler10) [2e64fac4] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 42d40db9 >2018-08-09 19:13:50,385+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler10) [2e64fac4] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 42d40db9 >2018-08-09 19:13:55,445+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler5) [7951de6b] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 1ea6151e >2018-08-09 19:13:56,019+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler5) [7951de6b] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 1ea6151e >2018-08-09 19:13:56,028+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler5) [7951de6b] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: a496641 >2018-08-09 19:13:56,340+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler5) [7951de6b] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: a496641 >2018-08-09 19:14:01,399+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [406d3c8] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 3d653789 >2018-08-09 19:14:07,053+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [406d3c8] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 3d653789 >2018-08-09 19:14:07,061+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [406d3c8] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 4bb9eb8a >2018-08-09 19:14:07,378+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [406d3c8] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 4bb9eb8a >2018-08-09 19:14:12,407+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler3) [400d5621] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 78ff4724 >2018-08-09 19:14:12,989+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler3) [400d5621] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 78ff4724 >2018-08-09 19:14:12,998+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler3) [400d5621] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 59c5034 >2018-08-09 19:14:13,314+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler3) [400d5621] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 59c5034 >2018-08-09 19:14:13,433+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler6) [3e377889] START, GlusterTasksListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 21ccdfa0 >2018-08-09 19:14:13,731+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler6) [3e377889] FINISH, GlusterTasksListVDSCommand, return: [], log id: 21ccdfa0 >2018-08-09 19:14:14,347+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeGeoRepSessionListVDSCommand] (DefaultQuartzScheduler2) [420ada69] START, GetGlusterVolumeGeoRepSessionListVDSCommand(HostName = v0.setel-group.com, GlusterVolumeGeoRepSessionVDSParameters:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f', volumeName='null'}), log id: c09eea8 >2018-08-09 19:14:14,645+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeGeoRepSessionListVDSCommand] (DefaultQuartzScheduler2) [420ada69] FINISH, GetGlusterVolumeGeoRepSessionListVDSCommand, return: [], log id: c09eea8 >2018-08-09 19:14:17,065+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-60) [] SPMAsyncTask::PollTask: Polling task 'a84fe969-f127-418c-a07d-804be3512398' (Parent Command 'AddVmFromSnapshot', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status 'finished', result 'success'. >2018-08-09 19:14:17,075+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-60) [] BaseAsyncTask::onTaskEndSuccess: Task 'a84fe969-f127-418c-a07d-804be3512398' (Parent Command 'AddVmFromSnapshot', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended successfully. >2018-08-09 19:14:17,076+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-60) [] CommandAsyncTask::endActionIfNecessary: All tasks of command 'a98762e5-584f-46ec-8350-de55f4fdb26c' has ended -> executing 'endAction' >2018-08-09 19:14:17,076+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-60) [] CommandAsyncTask::endAction: Ending action for '1' tasks (command ID: 'a98762e5-584f-46ec-8350-de55f4fdb26c'): calling endAction '. >2018-08-09 19:14:17,077+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337675) [] CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction 'AddVmFromSnapshot', >2018-08-09 19:14:17,249+03 INFO [org.ovirt.engine.core.bll.AddVmFromSnapshotCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [14192049-4256-4598-8910-518fabdec640] Ending command 'org.ovirt.engine.core.bll.AddVmFromSnapshotCommand' successfully. >2018-08-09 19:14:17,275+03 INFO [org.ovirt.engine.core.bll.storage.disk.image.CopyImageGroupCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [14192049-4256-4598-8910-518fabdec640] Ending command 'org.ovirt.engine.core.bll.storage.disk.image.CopyImageGroupCommand' successfully. >2018-08-09 19:14:17,280+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [14192049-4256-4598-8910-518fabdec640] START, GetImageInfoVDSCommand( GetImageInfoVDSCommandParameters:{storagePoolId='00000001-0001-0001-0001-000000000311', ignoreFailoverLimit='false', storageDomainId='142bbde6-ef9d-4a52-b9da-2de533c1f1bd', imageGroupId='1b6549d3-8f8b-46de-af42-d81fc35a8a0b', imageId='d5c34975-26e7-409e-a284-8690e1dd2487'}), log id: 7ca21fb0 >2018-08-09 19:14:17,283+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [14192049-4256-4598-8910-518fabdec640] START, GetVolumeInfoVDSCommand(HostName = v0.setel-group.com, GetVolumeInfoVDSCommandParameters:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f', storagePoolId='00000001-0001-0001-0001-000000000311', storageDomainId='142bbde6-ef9d-4a52-b9da-2de533c1f1bd', imageGroupId='1b6549d3-8f8b-46de-af42-d81fc35a8a0b', imageId='d5c34975-26e7-409e-a284-8690e1dd2487'}), log id: 6f6b5978 >2018-08-09 19:14:17,309+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [14192049-4256-4598-8910-518fabdec640] FINISH, GetVolumeInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@76812a33, log id: 6f6b5978 >2018-08-09 19:14:17,309+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [14192049-4256-4598-8910-518fabdec640] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@76812a33, log id: 7ca21fb0 >2018-08-09 19:14:17,330+03 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [] START, SetVmStatusVDSCommand( SetVmStatusVDSCommandParameters:{vmId='73a8539b-4c08-42fd-a0c6-629cf0ee48b2', status='Down', exitStatus='Normal'}), log id: 746d0f1d >2018-08-09 19:14:17,334+03 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [] FINISH, SetVmStatusVDSCommand, log id: 746d0f1d >2018-08-09 19:14:17,381+03 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-337675) [] EVENT_ID: USER_ADD_VM_FINISHED_SUCCESS(53), VM Equinox-SeeMBox-Debian9 creation has been completed. >2018-08-09 19:14:17,382+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337675) [] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'AddVmFromSnapshot' completed, handling the result. >2018-08-09 19:14:17,382+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337675) [] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'AddVmFromSnapshot' succeeded, clearing tasks. >2018-08-09 19:14:17,382+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337675) [] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 'a84fe969-f127-418c-a07d-804be3512398' >2018-08-09 19:14:17,384+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [] START, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='00000001-0001-0001-0001-000000000311', ignoreFailoverLimit='false', taskId='a84fe969-f127-418c-a07d-804be3512398'}), log id: 14aecf4c >2018-08-09 19:14:17,387+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [] START, HSMClearTaskVDSCommand(HostName = v0.setel-group.com, HSMTaskGuidBaseVDSCommandParameters:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f', taskId='a84fe969-f127-418c-a07d-804be3512398'}), log id: f65cbb1 >2018-08-09 19:14:17,410+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [] FINISH, HSMClearTaskVDSCommand, log id: f65cbb1 >2018-08-09 19:14:17,410+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337675) [] FINISH, SPMClearTaskVDSCommand, log id: 14aecf4c >2018-08-09 19:14:17,424+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337675) [] BaseAsyncTask::removeTaskFromDB: Removed task 'a84fe969-f127-418c-a07d-804be3512398' from DataBase >2018-08-09 19:14:17,424+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337675) [] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 'a98762e5-584f-46ec-8350-de55f4fdb26c' >2018-08-09 19:14:18,344+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler7) [26117a3c] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 9d4100d >2018-08-09 19:14:18,912+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler7) [26117a3c] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 9d4100d >2018-08-09 19:14:18,920+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler7) [26117a3c] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 6ed66909 >2018-08-09 19:14:19,229+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler7) [26117a3c] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 6ed66909 >2018-08-09 19:14:24,258+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler9) [71d35c86] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 6f4093f5 >2018-08-09 19:14:24,822+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler9) [71d35c86] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 6f4093f5 >2018-08-09 19:14:24,830+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler9) [71d35c86] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: f7b7144 >2018-08-09 19:14:25,137+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler9) [71d35c86] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: f7b7144 >2018-08-09 19:14:30,166+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [4350987c] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 342674db >2018-08-09 19:14:30,738+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [4350987c] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 342674db >2018-08-09 19:14:30,747+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [4350987c] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 4e19fe5e >2018-08-09 19:14:31,065+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [4350987c] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 4e19fe5e >2018-08-09 19:14:31,581+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.OvfDataUpdater] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [] Attempting to update VMs/Templates Ovf. >2018-08-09 19:14:31,585+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [3d2de49e] Before acquiring and wait lock 'EngineLock:{exclusiveLocks='[00000001-0001-0001-0001-000000000311=OVF_UPDATE]', sharedLocks=''}' >2018-08-09 19:14:31,585+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [3d2de49e] Lock-wait acquired to object 'EngineLock:{exclusiveLocks='[00000001-0001-0001-0001-000000000311=OVF_UPDATE]', sharedLocks=''}' >2018-08-09 19:14:31,587+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [3d2de49e] Running command: ProcessOvfUpdateForStoragePoolCommand internal: true. Entities affected : ID: 00000001-0001-0001-0001-000000000311 Type: StoragePool >2018-08-09 19:14:31,595+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [3d2de49e] Attempting to update VM OVFs in Data Center 'Setel' >2018-08-09 19:14:31,750+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [3d2de49e] Successfully updated VM OVFs in Data Center 'Setel' >2018-08-09 19:14:31,750+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [3d2de49e] Attempting to update template OVFs in Data Center 'Setel' >2018-08-09 19:14:31,752+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [3d2de49e] Successfully updated templates OVFs in Data Center 'Setel' >2018-08-09 19:14:31,752+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [3d2de49e] Attempting to remove unneeded template/vm OVFs in Data Center 'Setel' >2018-08-09 19:14:31,775+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [3d2de49e] Successfully removed unneeded template/vm OVFs in Data Center 'Setel' >2018-08-09 19:14:31,777+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [3d2de49e] Lock freed to object 'EngineLock:{exclusiveLocks='[00000001-0001-0001-0001-000000000311=OVF_UPDATE]', sharedLocks=''}' >2018-08-09 19:14:31,805+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStorageDomainCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] Lock Acquired to object 'EngineLock:{exclusiveLocks='[142bbde6-ef9d-4a52-b9da-2de533c1f1bd=STORAGE]', sharedLocks='[00000001-0001-0001-0001-000000000311=OVF_UPDATE]'}' >2018-08-09 19:14:31,845+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStorageDomainCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] Running command: ProcessOvfUpdateForStorageDomainCommand internal: true. Entities affected : ID: 142bbde6-ef9d-4a52-b9da-2de533c1f1bd Type: StorageAction group MANIPULATE_STORAGE_DOMAIN with role type ADMIN >2018-08-09 19:14:31,944+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] START, SetVolumeDescriptionVDSCommand( SetVolumeDescriptionVDSCommandParameters:{storagePoolId='00000001-0001-0001-0001-000000000311', ignoreFailoverLimit='false', storageDomainId='142bbde6-ef9d-4a52-b9da-2de533c1f1bd', imageGroupId='27a186b9-29a6-49bb-bd72-87af352d708e', imageId='90b7af32-036f-4cb6-a210-179440464f05'}), log id: 22559206 >2018-08-09 19:14:31,944+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] -- executeIrsBrokerCommand: calling 'setVolumeDescription', parameters: >2018-08-09 19:14:31,944+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ spUUID=00000001-0001-0001-0001-000000000311 >2018-08-09 19:14:31,944+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ sdUUID=142bbde6-ef9d-4a52-b9da-2de533c1f1bd >2018-08-09 19:14:31,944+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ imageGroupGUID=27a186b9-29a6-49bb-bd72-87af352d708e >2018-08-09 19:14:31,944+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ volUUID=90b7af32-036f-4cb6-a210-179440464f05 >2018-08-09 19:14:31,944+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ description={"Updated":false,"Last Updated":"Thu Aug 09 18:14:27 EEST 2018","Storage Domains":[{"uuid":"142bbde6-ef9d-4a52-b9da-2de533c1f1bd"}],"Disk Description":"OVF_STORE"} >2018-08-09 19:14:31,982+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] FINISH, SetVolumeDescriptionVDSCommand, log id: 22559206 >2018-08-09 19:14:32,020+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] Lock Acquired to object 'EngineLock:{exclusiveLocks='', sharedLocks='[d5a96118-ca49-411f-86cb-280c7f9c421f=VDS_EXECUTION]'}' >2018-08-09 19:14:32,054+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] Running command: UploadStreamCommand internal: true. Entities affected : ID: 142bbde6-ef9d-4a52-b9da-2de533c1f1bd Type: Storage >2018-08-09 19:14:32,058+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] START, UploadStreamVDSCommand(HostName = v0.setel-group.com, UploadStreamVDSCommandParameters:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 6d2c2597 >2018-08-09 19:14:32,058+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] -- executeVdsBrokerCommand, parameters: >2018-08-09 19:14:32,058+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ spUUID=00000001-0001-0001-0001-000000000311 >2018-08-09 19:14:32,058+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ sdUUID=142bbde6-ef9d-4a52-b9da-2de533c1f1bd >2018-08-09 19:14:32,058+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ imageGUID=27a186b9-29a6-49bb-bd72-87af352d708e >2018-08-09 19:14:32,058+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ volUUID=90b7af32-036f-4cb6-a210-179440464f05 >2018-08-09 19:14:32,058+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ size=972800 >2018-08-09 19:14:33,702+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] FINISH, UploadStreamVDSCommand, log id: 6d2c2597 >2018-08-09 19:14:34,182+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command '13bc5605-f74d-44f1-b021-c4b6ebcc08f4' >2018-08-09 19:14:34,182+03 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] CommandMultiAsyncTasks::attachTask: Attaching task 'c2cfe892-0c1b-4e9c-b0d0-d26e36198557' to command '13bc5605-f74d-44f1-b021-c4b6ebcc08f4'. >2018-08-09 19:14:36,093+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [6cc6920c] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 115d1e17 >2018-08-09 19:14:36,711+03 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] Adding task 'c2cfe892-0c1b-4e9c-b0d0-d26e36198557' (Parent Command 'UploadStream', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't started yet.. >2018-08-09 19:14:36,713+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [6cc6920c] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 115d1e17 >2018-08-09 19:14:36,721+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [6cc6920c] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 3bed92c8 >2018-08-09 19:14:36,762+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] BaseAsyncTask::startPollingTask: Starting to poll task 'c2cfe892-0c1b-4e9c-b0d0-d26e36198557'. >2018-08-09 19:14:36,774+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] Lock freed to object 'EngineLock:{exclusiveLocks='', sharedLocks='[d5a96118-ca49-411f-86cb-280c7f9c421f=VDS_EXECUTION]'}' >2018-08-09 19:14:36,779+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] START, SetVolumeDescriptionVDSCommand( SetVolumeDescriptionVDSCommandParameters:{storagePoolId='00000001-0001-0001-0001-000000000311', ignoreFailoverLimit='false', storageDomainId='142bbde6-ef9d-4a52-b9da-2de533c1f1bd', imageGroupId='27a186b9-29a6-49bb-bd72-87af352d708e', imageId='90b7af32-036f-4cb6-a210-179440464f05'}), log id: 45743d31 >2018-08-09 19:14:36,779+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] -- executeIrsBrokerCommand: calling 'setVolumeDescription', parameters: >2018-08-09 19:14:36,779+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ spUUID=00000001-0001-0001-0001-000000000311 >2018-08-09 19:14:36,779+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ sdUUID=142bbde6-ef9d-4a52-b9da-2de533c1f1bd >2018-08-09 19:14:36,779+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ imageGroupGUID=27a186b9-29a6-49bb-bd72-87af352d708e >2018-08-09 19:14:36,779+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ volUUID=90b7af32-036f-4cb6-a210-179440464f05 >2018-08-09 19:14:36,779+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ description={"Updated":true,"Size":972800,"Last Updated":"Thu Aug 09 19:14:31 EEST 2018","Storage Domains":[{"uuid":"142bbde6-ef9d-4a52-b9da-2de533c1f1bd"}],"Disk Description":"OVF_STORE"} >2018-08-09 19:14:36,844+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] FINISH, SetVolumeDescriptionVDSCommand, log id: 45743d31 >2018-08-09 19:14:36,866+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] START, SetVolumeDescriptionVDSCommand( SetVolumeDescriptionVDSCommandParameters:{storagePoolId='00000001-0001-0001-0001-000000000311', ignoreFailoverLimit='false', storageDomainId='142bbde6-ef9d-4a52-b9da-2de533c1f1bd', imageGroupId='5362699f-5d45-4631-b650-df292aa5f8de', imageId='44f57924-8424-4fcd-a707-2d3f63587f21'}), log id: 36aef9b0 >2018-08-09 19:14:36,866+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] -- executeIrsBrokerCommand: calling 'setVolumeDescription', parameters: >2018-08-09 19:14:36,866+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ spUUID=00000001-0001-0001-0001-000000000311 >2018-08-09 19:14:36,866+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ sdUUID=142bbde6-ef9d-4a52-b9da-2de533c1f1bd >2018-08-09 19:14:36,866+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ imageGroupGUID=5362699f-5d45-4631-b650-df292aa5f8de >2018-08-09 19:14:36,866+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ volUUID=44f57924-8424-4fcd-a707-2d3f63587f21 >2018-08-09 19:14:36,867+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ description={"Updated":false,"Last Updated":"Thu Aug 09 18:14:27 EEST 2018","Storage Domains":[{"uuid":"142bbde6-ef9d-4a52-b9da-2de533c1f1bd"}],"Disk Description":"OVF_STORE"} >2018-08-09 19:14:36,914+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] FINISH, SetVolumeDescriptionVDSCommand, log id: 36aef9b0 >2018-08-09 19:14:36,966+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] Lock Acquired to object 'EngineLock:{exclusiveLocks='', sharedLocks='[d5a96118-ca49-411f-86cb-280c7f9c421f=VDS_EXECUTION]'}' >2018-08-09 19:14:37,001+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] Running command: UploadStreamCommand internal: true. Entities affected : ID: 142bbde6-ef9d-4a52-b9da-2de533c1f1bd Type: Storage >2018-08-09 19:14:37,004+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] START, UploadStreamVDSCommand(HostName = v0.setel-group.com, UploadStreamVDSCommandParameters:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 3f1ab1db >2018-08-09 19:14:37,004+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] -- executeVdsBrokerCommand, parameters: >2018-08-09 19:14:37,004+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ spUUID=00000001-0001-0001-0001-000000000311 >2018-08-09 19:14:37,004+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ sdUUID=142bbde6-ef9d-4a52-b9da-2de533c1f1bd >2018-08-09 19:14:37,005+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ imageGUID=5362699f-5d45-4631-b650-df292aa5f8de >2018-08-09 19:14:37,005+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ volUUID=44f57924-8424-4fcd-a707-2d3f63587f21 >2018-08-09 19:14:37,005+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ size=972800 >2018-08-09 19:14:37,042+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [6cc6920c] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 3bed92c8 >2018-08-09 19:14:37,077+03 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engineScheduled-Thread-8) [] Polling and updating Async Tasks: 2 tasks, 1 tasks to poll now >2018-08-09 19:14:37,086+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-8) [] SPMAsyncTask::PollTask: Polling task 'c2cfe892-0c1b-4e9c-b0d0-d26e36198557' (Parent Command 'UploadStream', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status 'finished', result 'success'. >2018-08-09 19:14:37,086+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-8) [] BaseAsyncTask::onTaskEndSuccess: Task 'c2cfe892-0c1b-4e9c-b0d0-d26e36198557' (Parent Command 'UploadStream', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended successfully. >2018-08-09 19:14:37,086+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-8) [] CommandAsyncTask::endActionIfNecessary: All tasks of command '13bc5605-f74d-44f1-b021-c4b6ebcc08f4' has ended -> executing 'endAction' >2018-08-09 19:14:37,086+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-8) [] CommandAsyncTask::endAction: Ending action for '1' tasks (command ID: '13bc5605-f74d-44f1-b021-c4b6ebcc08f4'): calling endAction '. >2018-08-09 19:14:37,087+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337686) [] CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction 'UploadStream', >2018-08-09 19:14:37,113+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand] (EE-ManagedThreadFactory-engine-Thread-337686) [5d66712e] Ending command 'org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand' successfully. >2018-08-09 19:14:37,123+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337686) [5d66712e] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'UploadStream' completed, handling the result. >2018-08-09 19:14:37,123+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337686) [5d66712e] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'UploadStream' succeeded, clearing tasks. >2018-08-09 19:14:37,123+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337686) [5d66712e] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 'c2cfe892-0c1b-4e9c-b0d0-d26e36198557' >2018-08-09 19:14:37,126+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337686) [5d66712e] START, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='00000001-0001-0001-0001-000000000311', ignoreFailoverLimit='false', taskId='c2cfe892-0c1b-4e9c-b0d0-d26e36198557'}), log id: 1fdb6713 >2018-08-09 19:14:37,128+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337686) [5d66712e] START, HSMClearTaskVDSCommand(HostName = v0.setel-group.com, HSMTaskGuidBaseVDSCommandParameters:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f', taskId='c2cfe892-0c1b-4e9c-b0d0-d26e36198557'}), log id: 171000c4 >2018-08-09 19:14:37,146+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337686) [5d66712e] FINISH, HSMClearTaskVDSCommand, log id: 171000c4 >2018-08-09 19:14:37,146+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337686) [5d66712e] FINISH, SPMClearTaskVDSCommand, log id: 1fdb6713 >2018-08-09 19:14:37,154+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337686) [5d66712e] BaseAsyncTask::removeTaskFromDB: Removed task 'c2cfe892-0c1b-4e9c-b0d0-d26e36198557' from DataBase >2018-08-09 19:14:37,154+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337686) [5d66712e] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity '13bc5605-f74d-44f1-b021-c4b6ebcc08f4' >2018-08-09 19:14:38,755+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] FINISH, UploadStreamVDSCommand, log id: 3f1ab1db >2018-08-09 19:14:38,863+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command '1e1fab11-0653-490f-bc38-c33ed77761ee' >2018-08-09 19:14:38,863+03 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] CommandMultiAsyncTasks::attachTask: Attaching task 'e925dd3a-565a-4b6b-944f-8a47cb8d89cd' to command '1e1fab11-0653-490f-bc38-c33ed77761ee'. >2018-08-09 19:14:38,953+03 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] Adding task 'e925dd3a-565a-4b6b-944f-8a47cb8d89cd' (Parent Command 'UploadStream', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't started yet.. >2018-08-09 19:14:38,969+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] BaseAsyncTask::startPollingTask: Starting to poll task 'e925dd3a-565a-4b6b-944f-8a47cb8d89cd'. >2018-08-09 19:14:38,981+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] Lock freed to object 'EngineLock:{exclusiveLocks='', sharedLocks='[d5a96118-ca49-411f-86cb-280c7f9c421f=VDS_EXECUTION]'}' >2018-08-09 19:14:38,985+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] START, SetVolumeDescriptionVDSCommand( SetVolumeDescriptionVDSCommandParameters:{storagePoolId='00000001-0001-0001-0001-000000000311', ignoreFailoverLimit='false', storageDomainId='142bbde6-ef9d-4a52-b9da-2de533c1f1bd', imageGroupId='5362699f-5d45-4631-b650-df292aa5f8de', imageId='44f57924-8424-4fcd-a707-2d3f63587f21'}), log id: 325c6b7e >2018-08-09 19:14:38,986+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] -- executeIrsBrokerCommand: calling 'setVolumeDescription', parameters: >2018-08-09 19:14:38,986+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ spUUID=00000001-0001-0001-0001-000000000311 >2018-08-09 19:14:38,986+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ sdUUID=142bbde6-ef9d-4a52-b9da-2de533c1f1bd >2018-08-09 19:14:38,986+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ imageGroupGUID=5362699f-5d45-4631-b650-df292aa5f8de >2018-08-09 19:14:38,986+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ volUUID=44f57924-8424-4fcd-a707-2d3f63587f21 >2018-08-09 19:14:38,986+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] ++ description={"Updated":true,"Size":972800,"Last Updated":"Thu Aug 09 19:14:31 EEST 2018","Storage Domains":[{"uuid":"142bbde6-ef9d-4a52-b9da-2de533c1f1bd"}],"Disk Description":"OVF_STORE"} >2018-08-09 19:14:39,019+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] FINISH, SetVolumeDescriptionVDSCommand, log id: 325c6b7e >2018-08-09 19:14:39,053+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStorageDomainCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-83) [5d66712e] Lock freed to object 'EngineLock:{exclusiveLocks='[142bbde6-ef9d-4a52-b9da-2de533c1f1bd=STORAGE]', sharedLocks='[00000001-0001-0001-0001-000000000311=OVF_UPDATE]'}' >2018-08-09 19:14:42,073+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler10) [58eb185] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 124d640d >2018-08-09 19:14:42,659+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler10) [58eb185] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 124d640d >2018-08-09 19:14:42,667+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler10) [58eb185] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 57c5ecbc >2018-08-09 19:14:42,985+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler10) [58eb185] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 57c5ecbc >2018-08-09 19:14:46,487+03 INFO [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-64) [5d66712e] Command 'ProcessOvfUpdateForStorageDomain' (id: '1b0a3dba-4d28-4745-b257-56378e08758f') waiting on child command id: '1e1fab11-0653-490f-bc38-c33ed77761ee' type:'UploadStream' to complete >2018-08-09 19:14:47,087+03 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engineScheduled-Thread-42) [] Polling and updating Async Tasks: 3 tasks, 1 tasks to poll now >2018-08-09 19:14:47,096+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-42) [] SPMAsyncTask::PollTask: Polling task 'e925dd3a-565a-4b6b-944f-8a47cb8d89cd' (Parent Command 'UploadStream', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status 'finished', result 'success'. >2018-08-09 19:14:47,096+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-42) [] BaseAsyncTask::onTaskEndSuccess: Task 'e925dd3a-565a-4b6b-944f-8a47cb8d89cd' (Parent Command 'UploadStream', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended successfully. >2018-08-09 19:14:47,098+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-42) [] CommandAsyncTask::endActionIfNecessary: All tasks of command '1e1fab11-0653-490f-bc38-c33ed77761ee' has ended -> executing 'endAction' >2018-08-09 19:14:47,098+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-42) [] CommandAsyncTask::endAction: Ending action for '1' tasks (command ID: '1e1fab11-0653-490f-bc38-c33ed77761ee'): calling endAction '. >2018-08-09 19:14:47,098+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337687) [] CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction 'UploadStream', >2018-08-09 19:14:47,125+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand] (EE-ManagedThreadFactory-engine-Thread-337687) [5d66712e] Ending command 'org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand' successfully. >2018-08-09 19:14:47,133+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337687) [5d66712e] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'UploadStream' completed, handling the result. >2018-08-09 19:14:47,133+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337687) [5d66712e] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'UploadStream' succeeded, clearing tasks. >2018-08-09 19:14:47,133+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337687) [5d66712e] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 'e925dd3a-565a-4b6b-944f-8a47cb8d89cd' >2018-08-09 19:14:47,136+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337687) [5d66712e] START, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='00000001-0001-0001-0001-000000000311', ignoreFailoverLimit='false', taskId='e925dd3a-565a-4b6b-944f-8a47cb8d89cd'}), log id: 200518c6 >2018-08-09 19:14:47,138+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337687) [5d66712e] START, HSMClearTaskVDSCommand(HostName = v0.setel-group.com, HSMTaskGuidBaseVDSCommandParameters:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f', taskId='e925dd3a-565a-4b6b-944f-8a47cb8d89cd'}), log id: 4197e63d >2018-08-09 19:14:47,157+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337687) [5d66712e] FINISH, HSMClearTaskVDSCommand, log id: 4197e63d >2018-08-09 19:14:47,157+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337687) [5d66712e] FINISH, SPMClearTaskVDSCommand, log id: 200518c6 >2018-08-09 19:14:47,164+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337687) [5d66712e] BaseAsyncTask::removeTaskFromDB: Removed task 'e925dd3a-565a-4b6b-944f-8a47cb8d89cd' from DataBase >2018-08-09 19:14:47,164+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337687) [5d66712e] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity '1e1fab11-0653-490f-bc38-c33ed77761ee' >2018-08-09 19:14:48,015+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler5) [4c587562] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 87ee846 >2018-08-09 19:14:48,576+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler5) [4c587562] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 87ee846 >2018-08-09 19:14:48,584+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler5) [4c587562] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 7f700cdd >2018-08-09 19:14:48,895+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler5) [4c587562] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 7f700cdd >2018-08-09 19:14:53,922+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler1) [652a1d3a] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 348f55fc >2018-08-09 19:14:54,486+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler1) [652a1d3a] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 348f55fc >2018-08-09 19:14:54,494+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler1) [652a1d3a] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 37901702 >2018-08-09 19:14:54,804+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler1) [652a1d3a] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 37901702 >2018-08-09 19:14:56,522+03 INFO [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-55) [5d66712e] Command 'ProcessOvfUpdateForStorageDomain' id: '1b0a3dba-4d28-4745-b257-56378e08758f' child commands '[13bc5605-f74d-44f1-b021-c4b6ebcc08f4, 1e1fab11-0653-490f-bc38-c33ed77761ee]' executions were completed, status 'SUCCEEDED' >2018-08-09 19:14:57,614+03 INFO [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStorageDomainCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-46) [5d66712e] Ending command 'org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStorageDomainCommand' successfully. >2018-08-09 19:14:59,832+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [32f9e93e] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 33700d8c >2018-08-09 19:15:00,395+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [32f9e93e] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 33700d8c >2018-08-09 19:15:00,403+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [32f9e93e] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 55c83d0d >2018-08-09 19:15:00,716+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [32f9e93e] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 55c83d0d >2018-08-09 19:15:00,800+03 INFO [org.ovirt.engine.core.bll.snapshots.CreateSnapshotForVmCommand] (default task-4) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Lock Acquired to object 'EngineLock:{exclusiveLocks='[8897f42d-0360-4129-abcf-f5697d0b84e8=VM]', sharedLocks=''}' >2018-08-09 19:15:01,552+03 INFO [org.ovirt.engine.core.bll.snapshots.CreateSnapshotForVmCommand] (EE-ManagedThreadFactory-engine-Thread-337692) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Running command: CreateSnapshotForVmCommand internal: false. Entities affected : ID: 8897f42d-0360-4129-abcf-f5697d0b84e8 Type: VMAction group MANIPULATE_VM_SNAPSHOTS with role type USER >2018-08-09 19:15:01,726+03 INFO [org.ovirt.engine.core.bll.snapshots.CreateSnapshotDiskCommand] (EE-ManagedThreadFactory-engine-Thread-337692) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Running command: CreateSnapshotDiskCommand internal: true. Entities affected : ID: 8897f42d-0360-4129-abcf-f5697d0b84e8 Type: VMAction group MANIPULATE_VM_SNAPSHOTS with role type USER >2018-08-09 19:15:01,820+03 INFO [org.ovirt.engine.core.bll.snapshots.CreateSnapshotCommand] (EE-ManagedThreadFactory-engine-Thread-337692) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Running command: CreateSnapshotCommand internal: true. Entities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage >2018-08-09 19:15:01,917+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337692) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] START, CreateVolumeVDSCommand( CreateVolumeVDSCommandParameters:{storagePoolId='00000001-0001-0001-0001-000000000311', ignoreFailoverLimit='false', storageDomainId='142bbde6-ef9d-4a52-b9da-2de533c1f1bd', imageGroupId='6a35e7d2-f62f-4e8e-b82a-f6d706ccda25', imageSizeInBytes='53687091200', volumeFormat='COW', newImageId='4db39dac-00f7-4328-aadd-f7cd77cc3c7f', imageType='Sparse', newImageDescription='', imageInitialSizeInBytes='0', imageId='369dc8d7-2cf5-45f5-ae1f-7410a4208cb9', sourceImageGroupId='6a35e7d2-f62f-4e8e-b82a-f6d706ccda25'}), log id: 1f447aa >2018-08-09 19:15:02,405+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337692) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] FINISH, CreateVolumeVDSCommand, return: 4db39dac-00f7-4328-aadd-f7cd77cc3c7f, log id: 1f447aa >2018-08-09 19:15:02,429+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337692) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command '48aa9155-7e32-4474-b93b-f7ecb34a7a38' >2018-08-09 19:15:02,429+03 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (EE-ManagedThreadFactory-engine-Thread-337692) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] CommandMultiAsyncTasks::attachTask: Attaching task '664b2f81-4355-4c96-ac07-595d9e9dd875' to command '48aa9155-7e32-4474-b93b-f7ecb34a7a38'. >2018-08-09 19:15:02,471+03 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engine-Thread-337692) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Adding task '664b2f81-4355-4c96-ac07-595d9e9dd875' (Parent Command 'CreateSnapshot', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't started yet.. >2018-08-09 19:15:02,607+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337692) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] BaseAsyncTask::startPollingTask: Starting to poll task '664b2f81-4355-4c96-ac07-595d9e9dd875'. >2018-08-09 19:15:02,672+03 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-60) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Command 'CreateSnapshotDisk' (id: '9695f787-4846-428d-91a1-cfe1d44399de') waiting on child command id: '48aa9155-7e32-4474-b93b-f7ecb34a7a38' type:'CreateSnapshot' to complete >2018-08-09 19:15:02,822+03 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-337692) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] EVENT_ID: USER_CREATE_SNAPSHOT(45), Snapshot 'test' creation for VM 'CompanyTS-W2K16' was initiated by admin@internal-authz. >2018-08-09 19:15:03,707+03 INFO [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-23) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Command 'CreateSnapshotForVm' (id: '06bea887-114a-4c3d-8933-75cd65096025') waiting on child command id: '9695f787-4846-428d-91a1-cfe1d44399de' type:'CreateSnapshotDisk' to complete >2018-08-09 19:15:04,712+03 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-84) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Command 'CreateSnapshotDisk' (id: '9695f787-4846-428d-91a1-cfe1d44399de') waiting on child command id: '48aa9155-7e32-4474-b93b-f7ecb34a7a38' type:'CreateSnapshot' to complete >2018-08-09 19:15:05,751+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler2) [4912a620] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 39391a69 >2018-08-09 19:15:06,318+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler2) [4912a620] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 39391a69 >2018-08-09 19:15:06,327+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler2) [4912a620] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 6a519c05 >2018-08-09 19:15:06,641+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler2) [4912a620] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 6a519c05 >2018-08-09 19:15:07,098+03 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engineScheduled-Thread-48) [] Polling and updating Async Tasks: 4 tasks, 1 tasks to poll now >2018-08-09 19:15:07,108+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-48) [] SPMAsyncTask::PollTask: Polling task '664b2f81-4355-4c96-ac07-595d9e9dd875' (Parent Command 'CreateSnapshot', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status 'finished', result 'success'. >2018-08-09 19:15:07,562+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-48) [] BaseAsyncTask::onTaskEndSuccess: Task '664b2f81-4355-4c96-ac07-595d9e9dd875' (Parent Command 'CreateSnapshot', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended successfully. >2018-08-09 19:15:07,563+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-48) [] CommandAsyncTask::endActionIfNecessary: All tasks of command '48aa9155-7e32-4474-b93b-f7ecb34a7a38' has ended -> executing 'endAction' >2018-08-09 19:15:07,563+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-48) [] CommandAsyncTask::endAction: Ending action for '1' tasks (command ID: '48aa9155-7e32-4474-b93b-f7ecb34a7a38'): calling endAction '. >2018-08-09 19:15:07,563+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337697) [] CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction 'CreateSnapshot', >2018-08-09 19:15:07,577+03 INFO [org.ovirt.engine.core.bll.snapshots.CreateSnapshotCommand] (EE-ManagedThreadFactory-engine-Thread-337697) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Command [id=48aa9155-7e32-4474-b93b-f7ecb34a7a38]: Updating status to 'SUCCEEDED', The command end method logic will be executed by one of its parent commands. >2018-08-09 19:15:07,577+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337697) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'CreateSnapshot' completed, handling the result. >2018-08-09 19:15:07,577+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337697) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'CreateSnapshot' succeeded, clearing tasks. >2018-08-09 19:15:07,577+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337697) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] SPMAsyncTask::ClearAsyncTask: Attempting to clear task '664b2f81-4355-4c96-ac07-595d9e9dd875' >2018-08-09 19:15:07,580+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337697) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] START, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='00000001-0001-0001-0001-000000000311', ignoreFailoverLimit='false', taskId='664b2f81-4355-4c96-ac07-595d9e9dd875'}), log id: b383b72 >2018-08-09 19:15:07,582+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337697) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] START, HSMClearTaskVDSCommand(HostName = v0.setel-group.com, HSMTaskGuidBaseVDSCommandParameters:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f', taskId='664b2f81-4355-4c96-ac07-595d9e9dd875'}), log id: 59959a6b >2018-08-09 19:15:07,605+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337697) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] FINISH, HSMClearTaskVDSCommand, log id: 59959a6b >2018-08-09 19:15:07,605+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-337697) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] FINISH, SPMClearTaskVDSCommand, log id: b383b72 >2018-08-09 19:15:07,619+03 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337697) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] BaseAsyncTask::removeTaskFromDB: Removed task '664b2f81-4355-4c96-ac07-595d9e9dd875' from DataBase >2018-08-09 19:15:07,619+03 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-337697) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity '48aa9155-7e32-4474-b93b-f7ecb34a7a38' >2018-08-09 19:15:07,748+03 INFO [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-42) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Command 'CreateSnapshotForVm' (id: '06bea887-114a-4c3d-8933-75cd65096025') waiting on child command id: '9695f787-4846-428d-91a1-cfe1d44399de' type:'CreateSnapshotDisk' to complete >2018-08-09 19:15:08,754+03 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-71) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Command 'CreateSnapshotDisk' id: '9695f787-4846-428d-91a1-cfe1d44399de' child commands '[48aa9155-7e32-4474-b93b-f7ecb34a7a38]' executions were completed, status 'SUCCEEDED' >2018-08-09 19:15:08,754+03 INFO [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-71) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Command 'CreateSnapshotDisk' id: '9695f787-4846-428d-91a1-cfe1d44399de' Updating status to 'SUCCEEDED', The command end method logic will be executed by one of its parent commands. >2018-08-09 19:15:09,994+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SnapshotVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-24) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] START, SnapshotVDSCommand(HostName = v1.setel-group.com, SnapshotVDSCommandParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', vmId='8897f42d-0360-4129-abcf-f5697d0b84e8'}), log id: 2bc88664 >2018-08-09 19:15:11,671+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler7) [46da838f] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 29dc1242 >2018-08-09 19:15:12,246+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler7) [46da838f] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 29dc1242 >2018-08-09 19:15:12,255+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler7) [46da838f] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 598638b9 >2018-08-09 19:15:12,580+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler7) [46da838f] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 598638b9 >2018-08-09 19:15:13,740+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler5) [10346b9c] START, GlusterTasksListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 272822e5 >2018-08-09 19:15:13,977+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler5) [10346b9c] FINISH, GlusterTasksListVDSCommand, return: [], log id: 272822e5 >2018-08-09 19:15:17,638+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler1) [6f324cb0] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 59d2e80c >2018-08-09 19:15:18,208+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler1) [6f324cb0] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 59d2e80c >2018-08-09 19:15:18,216+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler1) [6f324cb0] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 2d90749e >2018-08-09 19:15:18,526+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler1) [6f324cb0] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 2d90749e >2018-08-09 19:15:19,291+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SnapshotVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-24) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] FINISH, SnapshotVDSCommand, log id: 2bc88664 >2018-08-09 19:15:21,327+03 INFO [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Command 'CreateSnapshotForVm' id: '06bea887-114a-4c3d-8933-75cd65096025' child commands '[9695f787-4846-428d-91a1-cfe1d44399de]' executions were completed, status 'SUCCEEDED' >2018-08-09 19:15:22,405+03 INFO [org.ovirt.engine.core.bll.snapshots.CreateSnapshotForVmCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Ending command 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotForVmCommand' successfully. >2018-08-09 19:15:22,412+03 INFO [org.ovirt.engine.core.bll.snapshots.CreateSnapshotDiskCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Ending command 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotDiskCommand' successfully. >2018-08-09 19:15:22,445+03 INFO [org.ovirt.engine.core.bll.snapshots.CreateSnapshotCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] Ending command 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotCommand' successfully. >2018-08-09 19:15:22,456+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] START, GetImageInfoVDSCommand( GetImageInfoVDSCommandParameters:{storagePoolId='00000001-0001-0001-0001-000000000311', ignoreFailoverLimit='false', storageDomainId='142bbde6-ef9d-4a52-b9da-2de533c1f1bd', imageGroupId='6a35e7d2-f62f-4e8e-b82a-f6d706ccda25', imageId='4db39dac-00f7-4328-aadd-f7cd77cc3c7f'}), log id: 7a5babc9 >2018-08-09 19:15:22,460+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] START, GetVolumeInfoVDSCommand(HostName = v0.setel-group.com, GetVolumeInfoVDSCommandParameters:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f', storagePoolId='00000001-0001-0001-0001-000000000311', storageDomainId='142bbde6-ef9d-4a52-b9da-2de533c1f1bd', imageGroupId='6a35e7d2-f62f-4e8e-b82a-f6d706ccda25', imageId='4db39dac-00f7-4328-aadd-f7cd77cc3c7f'}), log id: 20466280 >2018-08-09 19:15:22,485+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] FINISH, GetVolumeInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@5f9049cc, log id: 20466280 >2018-08-09 19:15:22,486+03 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@5f9049cc, log id: 7a5babc9 >2018-08-09 19:15:22,583+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetQemuImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] START, GetQemuImageInfoVDSCommand(HostName = v1.setel-group.com, GetVolumeInfoVDSCommandParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', storagePoolId='00000001-0001-0001-0001-000000000311', storageDomainId='142bbde6-ef9d-4a52-b9da-2de533c1f1bd', imageGroupId='6a35e7d2-f62f-4e8e-b82a-f6d706ccda25', imageId='4db39dac-00f7-4328-aadd-f7cd77cc3c7f'}), log id: 3e179b4c >2018-08-09 19:15:22,886+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetQemuImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [a94b7384-4c8d-4a8e-9ca9-f0b06d0653d7] FINISH, GetQemuImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.QemuImageInfo@407ce77c, log id: 3e179b4c >2018-08-09 19:15:22,945+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-6) [] START, DumpXmlsVDSCommand(HostName = v1.setel-group.com, Params:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', vmIds='[8897f42d-0360-4129-abcf-f5697d0b84e8]'}), log id: 6c0c4989 >2018-08-09 19:15:22,983+03 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-6) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=8897f42d-0360-4129-abcf-f5697d0b84e8, devices=[Ljava.util.Map;@48f222dc, guestDiskMapping={6a35e7d2-f62f-4e8e-b82a-f6d706ccda25={name=\\.\PHYSICALDRIVE0}}}], log id: 6c0c4989 >2018-08-09 19:15:22,987+03 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.VmDevicesMonitoring] (EE-ManagedThreadFactory-engineScheduled-Thread-6) [] VM '8897f42d-0360-4129-abcf-f5697d0b84e8' managed non pluggable device was removed unexpectedly from libvirt: 'VmDevice:{id='VmDeviceId:{deviceId='6b16deae-6d89-4559-a414-9ebbb605dc67', vmId='8897f42d-0360-4129-abcf-f5697d0b84e8'}', device='usb_2_1_2', type='HOSTDEV', specParams='[iommuPlaceholder=false]', address='', managed='true', plugged='false', readOnly='false', deviceAlias='', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}' >2018-08-09 19:15:23,108+03 INFO [org.ovirt.engine.core.bll.snapshots.CreateSnapshotForVmCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [] Lock freed to object 'EngineLock:{exclusiveLocks='[8897f42d-0360-4129-abcf-f5697d0b84e8=VM]', sharedLocks=''}' >2018-08-09 19:15:23,210+03 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [] EVENT_ID: USER_CREATE_SNAPSHOT_FINISHED_SUCCESS(68), Snapshot 'test' creation for VM 'CompanyTS-W2K16' has been completed. >2018-08-09 19:15:23,557+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler3) [74f6146d] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 6210ce18 >2018-08-09 19:15:24,174+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler3) [74f6146d] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 6210ce18 >2018-08-09 19:15:24,182+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler3) [74f6146d] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 56da9825 >2018-08-09 19:15:24,495+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler3) [74f6146d] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 56da9825 >2018-08-09 19:15:29,524+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [1c75a4d9] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 9d5dc3e >2018-08-09 19:15:30,091+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [1c75a4d9] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 9d5dc3e >2018-08-09 19:15:30,099+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [1c75a4d9] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 4498d97c >2018-08-09 19:15:30,404+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [1c75a4d9] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 4498d97c >2018-08-09 19:15:34,135+03 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engineScheduled-Thread-42) [] Setting new tasks map. The map contains now 3 tasks >2018-08-09 19:15:35,434+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler7) [56562ed3] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 3d2a16a5 >2018-08-09 19:15:35,995+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler7) [56562ed3] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 3d2a16a5 >2018-08-09 19:15:36,003+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler7) [56562ed3] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 5638e312 >2018-08-09 19:15:36,311+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler7) [56562ed3] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 5638e312 >2018-08-09 19:15:41,341+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler9) [15e2c58c] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 450fa534 >2018-08-09 19:15:41,907+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler9) [15e2c58c] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 450fa534 >2018-08-09 19:15:41,915+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler9) [15e2c58c] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 7892b113 >2018-08-09 19:15:42,219+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler9) [15e2c58c] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 7892b113 >2018-08-09 19:15:47,248+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler8) [63c31b07] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 76c01df8 >2018-08-09 19:15:47,812+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler8) [63c31b07] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 76c01df8 >2018-08-09 19:15:47,820+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler8) [63c31b07] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 7984a763 >2018-08-09 19:15:48,129+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler8) [63c31b07] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 7984a763 >2018-08-09 19:15:53,158+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [1fea2fa2] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 79d75829 >2018-08-09 19:15:53,764+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [1fea2fa2] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 79d75829 >2018-08-09 19:15:53,772+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [1fea2fa2] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 42422f27 >2018-08-09 19:15:54,087+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [1fea2fa2] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 42422f27 >2018-08-09 19:15:59,116+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [1bf32830] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 3c6589a1 >2018-08-09 19:15:59,679+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [1bf32830] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 3c6589a1 >2018-08-09 19:15:59,687+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [1bf32830] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 342691da >2018-08-09 19:16:00,002+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [1bf32830] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 342691da >2018-08-09 19:16:04,136+03 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engineScheduled-Thread-28) [] Setting new tasks map. The map contains now 1 tasks >2018-08-09 19:16:04,834+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalLogicalVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 668abe01 >2018-08-09 19:16:05,028+03 INFO [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler5) [12259869] Failed to acquire lock and wait lock 'EngineLock:{exclusiveLocks='[00000002-0002-0002-0002-00000000017a=GLUSTER]', sharedLocks=''}' >2018-08-09 19:16:05,538+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalLogicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@5bdf8a8a, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@5b7686e7, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@7be6cae6, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@482f192d, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@6f034178], log id: 668abe01 >2018-08-09 19:16:05,541+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalPhysicalVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 2da3c1 >2018-08-09 19:16:05,568+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalPhysicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@4d51f8d8, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@7d87662e], log id: 2da3c1 >2018-08-09 19:16:05,571+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterVDOVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 87ae067 >2018-08-09 19:16:05,807+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Failed in 'GetGlusterVDOVolumeListVDS' method, for vds: 'v0.setel-group.com'; host: 'v0': null >2018-08-09 19:16:05,808+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Command 'GetGlusterVDOVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'})' execution failed: null >2018-08-09 19:16:05,808+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterVDOVolumeListVDSCommand, log id: 87ae067 >2018-08-09 19:16:05,810+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalLogicalVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 65a46902 >2018-08-09 19:16:05,840+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalLogicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@27f1ca56, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@61017e90, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@3e27c30f, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@788d41b0, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@5107878d], log id: 65a46902 >2018-08-09 19:16:05,843+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalPhysicalVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 3b1896c3 >2018-08-09 19:16:05,870+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalPhysicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@3ba71514, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@29b54311], log id: 3b1896c3 >2018-08-09 19:16:05,872+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterVDOVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 44639245 >2018-08-09 19:16:06,143+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Failed in 'GetGlusterVDOVolumeListVDS' method, for vds: 'v1.setel-group.com'; host: 'v1': null >2018-08-09 19:16:06,144+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Command 'GetGlusterVDOVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'})' execution failed: null >2018-08-09 19:16:06,144+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterVDOVolumeListVDSCommand, log id: 44639245 >2018-08-09 19:16:06,146+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeAdvancedDetailsVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterVolumeAdvancedDetailsVDSCommand(HostName = v1.setel-group.com, GlusterVolumeAdvancedDetailsVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', volumeName='iso'}), log id: 3b32aa84 >2018-08-09 19:16:06,743+03 INFO [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler5) [12259869] Failed to acquire lock and wait lock 'EngineLock:{exclusiveLocks='[00000002-0002-0002-0002-00000000017a=GLUSTER]', sharedLocks=''}' >2018-08-09 19:16:07,782+03 INFO [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler5) [12259869] Failed to acquire lock and wait lock 'EngineLock:{exclusiveLocks='[00000002-0002-0002-0002-00000000017a=GLUSTER]', sharedLocks=''}' >2018-08-09 19:16:07,892+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeAdvancedDetailsVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterVolumeAdvancedDetailsVDSCommand, return: org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeAdvancedDetails@25051785, log id: 3b32aa84 >2018-08-09 19:16:07,986+03 INFO [org.ovirt.engine.core.bll.gluster.GlusterSyncJob] (DefaultQuartzScheduler7) [75060572] Volume iso have non-thin bricks, skipping confirmed free size calculation >2018-08-09 19:16:07,998+03 INFO [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler5) [12259869] Failed to acquire lock and wait lock 'EngineLock:{exclusiveLocks='[00000002-0002-0002-0002-00000000017a=GLUSTER]', sharedLocks=''}' >2018-08-09 19:16:08,004+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalLogicalVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 2657da91 >2018-08-09 19:16:08,033+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalLogicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@753e67c9, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@57ff68ac, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@5d995ec, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@1dc24494, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@1b4478ca], log id: 2657da91 >2018-08-09 19:16:08,036+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalPhysicalVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 37d2c906 >2018-08-09 19:16:08,062+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalPhysicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@7e6d4ce5, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@71045158], log id: 37d2c906 >2018-08-09 19:16:08,064+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterVDOVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 49bef59a >2018-08-09 19:16:08,299+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Failed in 'GetGlusterVDOVolumeListVDS' method, for vds: 'v0.setel-group.com'; host: 'v0': null >2018-08-09 19:16:08,299+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Command 'GetGlusterVDOVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'})' execution failed: null >2018-08-09 19:16:08,299+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterVDOVolumeListVDSCommand, log id: 49bef59a >2018-08-09 19:16:08,302+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalLogicalVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 68859f52 >2018-08-09 19:16:08,332+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalLogicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@7c1becc3, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@2fc6fc89, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@116acece, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@55d1acbe, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@760f2f33], log id: 68859f52 >2018-08-09 19:16:08,335+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalPhysicalVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 6e35a7cf >2018-08-09 19:16:08,362+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalPhysicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@29ee1879, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@7182f5a6], log id: 6e35a7cf >2018-08-09 19:16:08,364+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterVDOVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 7e7b2a12 >2018-08-09 19:16:08,636+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Failed in 'GetGlusterVDOVolumeListVDS' method, for vds: 'v1.setel-group.com'; host: 'v1': null >2018-08-09 19:16:08,636+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Command 'GetGlusterVDOVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'})' execution failed: null >2018-08-09 19:16:08,636+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterVDOVolumeListVDSCommand, log id: 7e7b2a12 >2018-08-09 19:16:08,639+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeAdvancedDetailsVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterVolumeAdvancedDetailsVDSCommand(HostName = v1.setel-group.com, GlusterVolumeAdvancedDetailsVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', volumeName='vms'}), log id: 3d33f1cd >2018-08-09 19:16:09,627+03 INFO [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler5) [12259869] Failed to acquire lock and wait lock 'EngineLock:{exclusiveLocks='[00000002-0002-0002-0002-00000000017a=GLUSTER]', sharedLocks=''}' >2018-08-09 19:16:10,397+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeAdvancedDetailsVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterVolumeAdvancedDetailsVDSCommand, return: org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeAdvancedDetails@c6398d, log id: 3d33f1cd >2018-08-09 19:16:10,431+03 INFO [org.ovirt.engine.core.bll.gluster.GlusterSyncJob] (DefaultQuartzScheduler7) [75060572] Volume vms have non-thin bricks, skipping confirmed free size calculation >2018-08-09 19:16:10,455+03 INFO [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler5) [12259869] Failed to acquire lock and wait lock 'EngineLock:{exclusiveLocks='[00000002-0002-0002-0002-00000000017a=GLUSTER]', sharedLocks=''}' >2018-08-09 19:16:10,462+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalLogicalVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 5e888362 >2018-08-09 19:16:10,490+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalLogicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@229f1135, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@56dba69f, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@7598b466, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@6f11aa24, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@35dd184a], log id: 5e888362 >2018-08-09 19:16:10,492+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalPhysicalVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 3eb9af30 >2018-08-09 19:16:10,518+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalPhysicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@eb20705, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@6c90dc7d], log id: 3eb9af30 >2018-08-09 19:16:10,521+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterVDOVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 315296ed >2018-08-09 19:16:10,762+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Failed in 'GetGlusterVDOVolumeListVDS' method, for vds: 'v0.setel-group.com'; host: 'v0': null >2018-08-09 19:16:10,763+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Command 'GetGlusterVDOVolumeListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'})' execution failed: null >2018-08-09 19:16:10,763+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterVDOVolumeListVDSCommand, log id: 315296ed >2018-08-09 19:16:10,765+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalLogicalVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 42e17fd8 >2018-08-09 19:16:10,785+03 INFO [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler5) [12259869] Failed to acquire lock and wait lock 'EngineLock:{exclusiveLocks='[00000002-0002-0002-0002-00000000017a=GLUSTER]', sharedLocks=''}' >2018-08-09 19:16:10,798+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalLogicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalLogicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@5c75d2ae, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@366c9e69, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@132c83b7, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@5a7a31b5, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalLogicalVolume@286868f6], log id: 42e17fd8 >2018-08-09 19:16:10,800+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterLocalPhysicalVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 7aa52444 >2018-08-09 19:16:10,825+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterLocalPhysicalVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterLocalPhysicalVolumeListVDSCommand, return: [org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@d63219c, org.ovirt.engine.core.common.businessentities.gluster.GlusterLocalPhysicalVolume@69f5e1c], log id: 7aa52444 >2018-08-09 19:16:10,827+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterVDOVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 6ddbb9e1 >2018-08-09 19:16:11,101+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Failed in 'GetGlusterVDOVolumeListVDS' method, for vds: 'v1.setel-group.com'; host: 'v1': null >2018-08-09 19:16:11,101+03 ERROR [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] Command 'GetGlusterVDOVolumeListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'})' execution failed: null >2018-08-09 19:16:11,101+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVDOVolumeListVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterVDOVolumeListVDSCommand, log id: 6ddbb9e1 >2018-08-09 19:16:11,104+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeAdvancedDetailsVDSCommand] (DefaultQuartzScheduler7) [75060572] START, GetGlusterVolumeAdvancedDetailsVDSCommand(HostName = v1.setel-group.com, GlusterVolumeAdvancedDetailsVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba', volumeName='engine'}), log id: 3e0f271a >2018-08-09 19:16:12,631+03 INFO [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler5) [12259869] Failed to acquire lock and wait lock 'EngineLock:{exclusiveLocks='[00000002-0002-0002-0002-00000000017a=GLUSTER]', sharedLocks=''}' >2018-08-09 19:16:12,846+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GetGlusterVolumeAdvancedDetailsVDSCommand] (DefaultQuartzScheduler7) [75060572] FINISH, GetGlusterVolumeAdvancedDetailsVDSCommand, return: org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeAdvancedDetails@1664bcd2, log id: 3e0f271a >2018-08-09 19:16:12,888+03 INFO [org.ovirt.engine.core.bll.gluster.GlusterSyncJob] (DefaultQuartzScheduler7) [75060572] Volume engine have non-thin bricks, skipping confirmed free size calculation >2018-08-09 19:16:12,915+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler5) [12259869] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 74402b59 >2018-08-09 19:16:13,484+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler5) [12259869] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 74402b59 >2018-08-09 19:16:13,492+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler5) [12259869] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 69648b67 >2018-08-09 19:16:13,801+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler5) [12259869] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 69648b67 >2018-08-09 19:16:13,986+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler8) [35f81a35] START, GlusterTasksListVDSCommand(HostName = v0.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='d5a96118-ca49-411f-86cb-280c7f9c421f'}), log id: 3d8ab0b0 >2018-08-09 19:16:14,223+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterTasksListVDSCommand] (DefaultQuartzScheduler8) [35f81a35] FINISH, GlusterTasksListVDSCommand, return: [], log id: 3d8ab0b0 >2018-08-09 19:16:18,828+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [4668e6c] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 6bdcad29 >2018-08-09 19:16:19,401+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler4) [4668e6c] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 6bdcad29 >2018-08-09 19:16:19,409+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [4668e6c] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 5014fae5 >2018-08-09 19:16:19,724+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler4) [4668e6c] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 5014fae5 >2018-08-09 19:16:24,782+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [19a90a86] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 2d3b6c1a >2018-08-09 19:16:25,351+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler6) [19a90a86] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 2d3b6c1a >2018-08-09 19:16:25,369+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [19a90a86] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 277ee723 >2018-08-09 19:16:25,675+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler6) [19a90a86] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 277ee723 >2018-08-09 19:16:30,707+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler1) [4c5828b8] START, GlusterServersListVDSCommand(HostName = v1.setel-group.com, VdsIdVDSCommandParametersBase:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 7d07a82d >2018-08-09 19:16:31,294+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterServersListVDSCommand] (DefaultQuartzScheduler1) [4c5828b8] FINISH, GlusterServersListVDSCommand, return: [172.30.0.12/24:CONNECTED, gluster0:CONNECTED], log id: 7d07a82d >2018-08-09 19:16:31,303+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler1) [4c5828b8] START, GlusterVolumesListVDSCommand(HostName = v1.setel-group.com, GlusterVolumesListVDSParameters:{hostId='12dfea4a-8142-484e-b912-0cbd5f281aba'}), log id: 60491e0b >2018-08-09 19:16:31,615+03 INFO [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand] (DefaultQuartzScheduler1) [4c5828b8] FINISH, GlusterVolumesListVDSCommand, return: {20814fd8-87ca-433b-bd99-532ed8ac179a=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@6cd739a0, 736bdf22-e35e-4aa0-9e1b-8d76e1579240=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@9c14e4e4, e5a66579-74b3-42a8-971d-bfba94eac526=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@4aa6821}, log id: 60491e0b
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1614466
: 1474763 |
1474764