Attachment 1477371 Details for Bug 1413707 – VMM migrate logs

VMM migrate logs

migrate-logs.txt (text/plain), 11.21 KB, created by phatfish on 2018-08-20 21:21:22 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: phatfish

Created: 2018-08-20 21:21:22 UTC

Size: 11.21 KB

patch

obsolete

>Migrate with temporary=False
>
>[Mon, 20 Aug 2018 19:33:06 virt-manager 2818] DEBUG (migrate:75) Showing migrate wizard
>[Mon, 20 Aug 2018 19:33:14 virt-manager 2818] DEBUG (migrate:431) Migrating vm=lab-hq-desktop2 from qemu+ssh://user@mylo-kvm2/system to qemu+ssh://user@mylo-kvm1/system
>[Mon, 20 Aug 2018 19:33:14 virt-manager 2818] DEBUG (domain:1593) Migrating: conn=<connection.vmmConnection object at 0x7f15bdae7d20 (virtManager+connection+vmmConnection at 0x5653c2a5f5a0)> flags=25 uri=tcp:mylo-kvm1:49152 tunnel=False unsafe=False temporary=False
>[Mon, 20 Aug 2018 19:33:14 virt-manager 2818] DEBUG (connection:847) node device lifecycle event: device=net_vnet0_fe_54_00_de_3c_a0 event=0 reason=0
>[Mon, 20 Aug 2018 19:33:15 virt-manager 2818] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-desktop2 event=2 reason=1
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-desktop2 event=3 reason=1
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-desktop2 event=0 reason=0
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-desktop2 event=4 reason=0
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-desktop2 event=4 reason=1
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:847) node device lifecycle event: device=net_vnet4_fe_54_00_de_3c_a0 event=1 reason=0
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:1196) domain=lab-hq-desktop2 status=Paused added
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:1190) New domain=lab-hq-desktop2 requested, but it's already tracked.
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:1190) New domain=lab-hq-desktop2 requested, but it's already tracked.
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:1157) nodedev=net_vnet4_fe_54_00_de_3c_a0 removed
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:1190) New domain=lab-hq-desktop2 requested, but it's already tracked.
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (console:832) Viewer disconnected
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-desktop2 event=5 reason=3
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (libvirtobject:284) Error refreshing <vmmDomain name=lab-hq-desktop2 id=0x7f15ac0b5730> from events: Domain not found: no domain with matching uuid '7d302415-d695-40ef-8546-431148bfc929' (lab-hq-desktop2)
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (libvirtobject:288) Scheduling priority tick with: {'pollvm': True, 'force': True}
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (connection:1157) domain=lab-hq-desktop2 removed
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (details:682) Closing VM details: <vmmDomain name=lab-hq-desktop2 id=0x7f15ac0b5730>
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (engine:406) window counter decremented to 3
>[Mon, 20 Aug 2018 19:33:23 virt-manager 2818] DEBUG (migrate:83) Closing migrate wizard
>
>Migrate to another host and back with temporary=True
>
>[Mon, 20 Aug 2018 21:22:30 virt-manager 2880] DEBUG (migrate:75) Showing migrate wizard
>[Mon, 20 Aug 2018 21:22:38 virt-manager 2880] DEBUG (migrate:431) Migrating vm=lab-hq-dc1 from qemu+ssh://user@mylo-kvm2/system to qemu+ssh://user@mylo-kvm1/system
>[Mon, 20 Aug 2018 21:22:38 virt-manager 2880] DEBUG (domain:1593) Migrating: conn=<connection.vmmConnection object at 0x7fa121672d20 (virtManager+connection+vmmConnection at 0x560f4fc666e0)> flags=1 uri=tcp:mylo-kvm1:49152 tunnel=False unsafe=False temporary=True
>[Mon, 20 Aug 2018 21:22:38 virt-manager 2880] DEBUG (connection:847) node device lifecycle event: device=net_vnet0_fe_54_00_a2_02_cb event=0 reason=0
>[Mon, 20 Aug 2018 21:22:38 virt-manager 2880] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-dc1 event=2 reason=1
>[Mon, 20 Aug 2018 21:22:56 virt-manager 2880] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-dc1 event=3 reason=1
>[Mon, 20 Aug 2018 21:22:56 virt-manager 2880] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-dc1 event=4 reason=0
>[Mon, 20 Aug 2018 21:22:56 virt-manager 2880] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-dc1 event=4 reason=1
>[Mon, 20 Aug 2018 21:22:56 virt-manager 2880] DEBUG (connection:847) node device lifecycle event: device=net_vnet3_fe_54_00_a2_02_cb event=1 reason=0
>[Mon, 20 Aug 2018 21:22:56 virt-manager 2880] DEBUG (connection:1196) domain=lab-hq-dc1 status=Paused added
>[Mon, 20 Aug 2018 21:22:56 virt-manager 2880] DEBUG (connection:1190) New domain=lab-hq-dc1 requested, but it's already tracked.
>[Mon, 20 Aug 2018 21:22:56 virt-manager 2880] DEBUG (connection:1157) nodedev=net_vnet3_fe_54_00_a2_02_cb removed
>[Mon, 20 Aug 2018 21:22:56 virt-manager 2880] DEBUG (connection:1190) New domain=lab-hq-dc1 requested, but it's already tracked.
>[Mon, 20 Aug 2018 21:22:56 virt-manager 2880] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-dc1 event=5 reason=3
>[Mon, 20 Aug 2018 21:22:56 virt-manager 2880] ERROR (domain:138) Error calling jobinfo
>Traceback (most recent call last):
>  File "/usr/share/virt-manager/virtManager/domain.py", line 122, in jobinfo_cb
>    jobinfo = vm.job_info()
>  File "/usr/share/virt-manager/virtManager/domain.py", line 1187, in job_info
>    return self._backend.jobInfo()
>  File "/usr/lib64/python2.7/site-packages/libvirt.py", line 1391, in jobInfo
>    if ret is None: raise libvirtError ('virDomainGetJobInfo() failed', dom=self)
>libvirtError: Requested operation is not valid: domain is not running
>[Mon, 20 Aug 2018 21:22:56 virt-manager 2880] DEBUG (migrate:83) Closing migrate wizard
>[Mon, 20 Aug 2018 21:23:09 virt-manager 2880] DEBUG (migrate:75) Showing migrate wizard
>[Mon, 20 Aug 2018 21:23:31 virt-manager 2880] DEBUG (migrate:431) Migrating vm=lab-hq-dc1 from qemu+ssh://user@mylo-kvm1/system to qemu+ssh://user@mylo-kvm2/system
>[Mon, 20 Aug 2018 21:23:31 virt-manager 2880] DEBUG (domain:1593) Migrating: conn=<connection.vmmConnection object at 0x7fa12160b1e0 (virtManager+connection+vmmConnection at 0x560f4fc67140)> flags=1 uri=tcp:mylo-kvm2:49152 tunnel=False unsafe=False temporary=True
>[Mon, 20 Aug 2018 21:23:31 virt-manager 2880] DEBUG (connection:847) node device lifecycle event: device=net_vnet3_fe_54_00_a2_02_cb event=0 reason=0
>[Mon, 20 Aug 2018 21:23:31 virt-manager 2880] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-dc1 event=2 reason=1
>[Mon, 20 Aug 2018 21:23:48 virt-manager 2880] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-dc1 event=3 reason=1
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-dc1 event=4 reason=0
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-dc1 event=4 reason=1
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] DEBUG (connection:847) node device lifecycle event: device=net_vnet0_fe_54_00_a2_02_cb event=1 reason=0
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] DEBUG (connection:788) domain lifecycle event: domain=lab-hq-dc1 event=5 reason=3
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] ERROR (connection:1354) Tick for <vmmDomain name=lab-hq-dc1 id=0x7fa1200ad1e0> failed
>Traceback (most recent call last):
>  File "/usr/share/virt-manager/virtManager/connection.py", line 1352, in _tick
>    obj.tick(stats_update=stats_update)
>  File "/usr/share/virt-manager/virtManager/domain.py", line 2006, in tick
>    self._tick_stats(info)
>  File "/usr/share/virt-manager/virtManager/domain.py", line 2020, in _tick_stats
>    pcentHostCpu, pcentGuestCpu) = self._sample_cpu_stats(info, now)
>  File "/usr/share/virt-manager/virtManager/domain.py", line 1618, in _sample_cpu_stats
>    info = self._backend.info()
>  File "/usr/lib64/python2.7/site-packages/libvirt.py", line 1320, in info
>    if ret is None: raise libvirtError ('virDomainGetInfo() failed', dom=self)
>libvirtError: Domain not found: no domain with matching uuid '558d99cd-8404-4eff-adea-b606801a64b9' (lab-hq-dc1)
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] ERROR (domain:138) Error calling jobinfo
>Traceback (most recent call last):
>  File "/usr/share/virt-manager/virtManager/domain.py", line 122, in jobinfo_cb
>    jobinfo = vm.job_info()
>  File "/usr/share/virt-manager/virtManager/domain.py", line 1187, in job_info
>    return self._backend.jobInfo()
>  File "/usr/lib64/python2.7/site-packages/libvirt.py", line 1391, in jobInfo
>    if ret is None: raise libvirtError ('virDomainGetJobInfo() failed', dom=self)
>libvirtError: Domain not found: no domain with matching uuid '558d99cd-8404-4eff-adea-b606801a64b9' (lab-hq-dc1)
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] DEBUG (libvirtobject:284) Error refreshing <vmmDomain name=lab-hq-dc1 id=0x7fa1200ad1e0> from events: Domain not found: no domain with matching uuid '558d99cd-8404-4eff-adea-b606801a64b9' (lab-hq-dc1)
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] DEBUG (libvirtobject:288) Scheduling priority tick with: {'pollvm': True, 'force': True}
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] ERROR (connection:1354) Tick for <vmmDomain name=lab-hq-dc1 id=0x7fa1200ad1e0> failed
>Traceback (most recent call last):
>  File "/usr/share/virt-manager/virtManager/connection.py", line 1352, in _tick
>    obj.tick(stats_update=stats_update)
>  File "/usr/share/virt-manager/virtManager/domain.py", line 2006, in tick
>    self._tick_stats(info)
>  File "/usr/share/virt-manager/virtManager/domain.py", line 2020, in _tick_stats
>    pcentHostCpu, pcentGuestCpu) = self._sample_cpu_stats(info, now)
>  File "/usr/share/virt-manager/virtManager/domain.py", line 1618, in _sample_cpu_stats
>    info = self._backend.info()
>  File "/usr/lib64/python2.7/site-packages/libvirt.py", line 1320, in info
>    if ret is None: raise libvirtError ('virDomainGetInfo() failed', dom=self)
>libvirtError: Domain not found: no domain with matching uuid '558d99cd-8404-4eff-adea-b606801a64b9' (lab-hq-dc1)
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] DEBUG (migrate:83) Closing migrate wizard
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] DEBUG (connection:1157) nodedev=net_vnet0_fe_54_00_a2_02_cb removed
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] DEBUG (connection:1157) domain=lab-hq-dc1 removed
>[Mon, 20 Aug 2018 21:23:49 virt-manager 2880] ERROR (connection:1354) Tick for <vmmDomain name=lab-hq-dc1 id=0x7fa1200ad1e0> failed
>Traceback (most recent call last):
>  File "/usr/share/virt-manager/virtManager/connection.py", line 1352, in _tick
>    obj.tick(stats_update=stats_update)
>  File "/usr/share/virt-manager/virtManager/domain.py", line 2006, in tick
>    self._tick_stats(info)
>  File "/usr/share/virt-manager/virtManager/domain.py", line 2020, in _tick_stats
>    pcentHostCpu, pcentGuestCpu) = self._sample_cpu_stats(info, now)
>  File "/usr/share/virt-manager/virtManager/domain.py", line 1618, in _sample_cpu_stats
>    info = self._backend.info()
>  File "/usr/lib64/python2.7/site-packages/libvirt.py", line 1320, in info
>    if ret is None: raise libvirtError ('virDomainGetInfo() failed', dom=self)
>libvirtError: Domain not found: no domain with matching uuid '558d99cd-8404-4eff-adea-b606801a64b9' (lab-hq-dc1)

Actions: View

Attachments on bug 1413707: 1311854 | 1477371