You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Rohit Yadav (JIRA)" <ji...@apache.org> on 2015/01/18 13:30:48 UTC
[jira] [Commented] (CLOUDSTACK-8097) Failed to create snapshot from
volume after vm live migration across clusters
[ https://issues.apache.org/jira/browse/CLOUDSTACK-8097?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14281780#comment-14281780 ]
Rohit Yadav commented on CLOUDSTACK-8097:
-----------------------------------------
Looks like a necessary bugfix, any reason why this should not be on 4.5?
> Failed to create snapshot from volume after vm live migration across clusters
> -----------------------------------------------------------------------------
>
> Key: CLOUDSTACK-8097
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-8097
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the default.)
> Components: Snapshot, Storage Controller
> Affects Versions: 4.5.0
> Reporter: Sanjay Tripathi
> Assignee: Sanjay Tripathi
> Priority: Critical
> Fix For: 4.5.0, 4.6.0
>
>
> Failed to create volume snapshot after vm live migration across clusters
> Steps to reproduce:
> ================
> 1.Bring up cs in advanced zone with two xen clusters and at-least one host in each cluster
> 2.Deploy one guest vm using default cent os template
> 3.Take snapshot of the root disk
> 4.Now live migrate vm to another cluster so that it will be migrated along with storage
> 5.Again take snapshot on the root volume
> Expected Behavior:
> ===============
> Snapshot creation shall succeed and shall backup to secondary storage
> Actual Behavior:
> ==============
> Snapshot is created on primary but failed to copy to secondary. So snapshot creation failed with exceptions.
> Observations:
> ============
> After live migrating vm with storage we are not changing the volume id and uuid in volumes table. During vm live migration volume state remains in "Migrating" state and after completion it comes to "Ready" state.
> 1.When wee created snapshot on the root disk vm was in cluster1 so snapshot was created in cluster1's primary storage.
> 2.After that we live migrated the vm to another cluster and created snapshot another snapshot on the root disk. This time snapshot was created on cluster2's primary storage.
> May be this vhd file on this primary storage did not find any parent vhd file so vhd scan might have deleted this vhd.
> I could found the following lines from xenserver SMlog file:
> Nov 21 04:58:13 localhost SMGC: [21457] Got sm-config for *837da6ea(20.000G/112.262M): {'vhd-parent': '745072fa-345a-4a7b-99a3-0c3789831146', 'vhd-blocks': 'eJxrYAAB0QcMKECEEUKzMAxNIAClWdD4AwUotV9EQFCCgVGRQQSHUSI47MNlL7o8vcJHVFBQqlFQkJFO1pEM6BUO9E6PtM7HA52/yAcAa1ED4w=='}
> Nov 21 04:58:13 localhost SMGC: [21457] Found 1 VDIs for deletion:
> Nov 21 04:58:13 localhost SMGC: [21457] *49341e2f(20.000G/34.110M)
> Nov 21 04:58:13 localhost SMGC: [21457] Deleting unlinked VDI *49341e2f(20.000G/34.110M)
> Nov 21 04:58:13 localhost SMGC: [21457] Checking with slave: ('OpaqueRef:17fa526d-4f74-6d9
> I guess when CS tried to copy the snapshot to secondary storage it could not found the vhd hence it failed with NPE.
> Please look for async job-35 in the attached MS log file.
> Following is the log snippet from xenserver SMlog file:
> ==========================================
> Nov 21 04:57:07 localhost SM: [20944] vdi_snapshot {'sr_uuid': '1f273ffb-3583-4a91-0c30-faa4951e7df8', 'subtask_of': 'DummyRef:|6bb04f6c-1a9a-950e-77cb-067eb7a29e3f|VDI.snapshot', 'vdi_ref': 'OpaqueRef:9583c77d-e9c5-144a-823c-599db4ba59f4', 'vdi_on_boot': 'persist', 'args': [], 'o_direct': False, 'vdi_location': '1cf9e9f7-862e-4426-9416-584987b9cfbd', 'host_ref': 'OpaqueRef:4366ea2d-224b-fa03-65d6-a53c18b0c77f', 'session_ref': 'OpaqueRef:45e50d1a-37cf-aa1d-30ec-e77927acfab5', 'device_config': {'SRmaster': 'true', 'serverpath': '/vol/export/902534-aauDvm', 'server': '10.220.160.33'}, 'command': 'vdi_snapshot', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:c32edd0d-b0d7-7e28-9385-a4a6100f116b', 'driver_params': {}, 'vdi_uuid': '1cf9e9f7-862e-4426-9416-584987b9cfbd'}
> Nov 21 04:57:07 localhost SM: [20944] Pause request for 1cf9e9f7-862e-4426-9416-584987b9cfbd
> Nov 21 04:57:07 localhost SM: [20944] Calling tap-pause on host OpaqueRef:4366ea2d-224b-fa03-65d6-a53c18b0c77f
> Nov 21 04:57:07 localhost SM: [20955] lock: opening lock file /var/lock/sm/1cf9e9f7-862e-4426-9416-584987b9cfbd/vdi
> Nov 21 04:57:07 localhost SM: [20955] lock: acquired /var/lock/sm/1cf9e9f7-862e-4426-9416-584987b9cfbd/vdi
> Nov 21 04:57:07 localhost SM: [20955] Pause for 1cf9e9f7-862e-4426-9416-584987b9cfbd
> Nov 21 04:57:07 localhost SM: [20955] Calling tap pause with minor 1
> Nov 21 04:57:07 localhost SM: [20955] ['/usr/sbin/tap-ctl', 'pause', '-p', '18621', '-m', '1']
> Nov 21 04:57:07 localhost SM: [20955] = 0
> Nov 21 04:57:07 localhost SM: [20955] lock: released /var/lock/sm/1cf9e9f7-862e-4426-9416-584987b9cfbd/vdi
> Nov 21 04:57:07 localhost SM: [20955] lock: closed /var/lock/sm/1cf9e9f7-862e-4426-9416-584987b9cfbd/vdi
> Nov 21 04:57:07 localhost SM: [20944] FileVDI._snapshot for 1cf9e9f7-862e-4426-9416-584987b9cfbd (type 2)
> Nov 21 04:57:07 localhost SM: [20944] ['uuidgen', '-r']
> Nov 21 04:57:07 localhost SM: [20944] pread SUCCESS
> Nov 21 04:57:07 localhost SM: [20944] ['/usr/bin/vhd-util', 'query', '--debug', '-d', '-n', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/1cf9e9f7-862e-4426-9416-584987b9cfbd.vhd']
> Nov 21 04:57:07 localhost SM: [20944] pread SUCCESS
> Nov 21 04:57:07 localhost SM: [20944] ['uuidgen', '-r']
> Nov 21 04:57:07 localhost SM: [20944] pread SUCCESS
> Nov 21 04:57:07 localhost SM: [20944] ['/usr/sbin/td-util', 'snapshot', 'vhd', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/1cf9e9f7-862e-4426-9416-584987b9cfbd.vhd', '49341e2f-2b34-4f40-aa8a-0486a6cd14f8.vhd']
> Nov 21 04:57:07 localhost SM: [20944] pread SUCCESS
> Nov 21 04:57:07 localhost SM: [20944] ['/usr/sbin/td-util', 'snapshot', 'vhd', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/2caf017c-a653-4b05-92fb-b8b29285fbb5.vhd', '49341e2f-2b34-4f40-aa8a-0486a6cd14f8.vhd']
> Nov 21 04:57:07 localhost SM: [20944] pread SUCCESS
> Nov 21 04:57:07 localhost SM: [20944] ['/usr/bin/vhd-util', 'set', '--debug', '-n', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/49341e2f-2b34-4f40-aa8a-0486a6cd14f8.vhd', '-f', 'hidden', '-v', '1']
> Nov 21 04:57:07 localhost SM: [20944] pread SUCCESS
> Nov 21 04:57:07 localhost SM: [20944] ['/usr/sbin/td-util', 'query', 'vhd', '-p', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/1cf9e9f7-862e-4426-9416-584987b9cfbd.vhd']
> Nov 21 04:57:07 localhost SM: [20944] pread SUCCESS
> Nov 21 04:57:07 localhost SM: [20944] ['/usr/sbin/td-util', 'query', 'vhd', '-p', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/2caf017c-a653-4b05-92fb-b8b29285fbb5.vhd']
> Nov 21 04:57:07 localhost SM: [20944] pread SUCCESS
> Nov 21 04:57:07 localhost SM: [20944] ['/usr/sbin/td-util', 'query', 'vhd', '-p', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/49341e2f-2b34-4f40-aa8a-0486a6cd14f8.vhd']
> Nov 21 04:57:07 localhost SM: [20944] pread SUCCESS
> Nov 21 04:57:07 localhost SM: [20944] vdi_clone: introduced VDI: OpaqueRef:5f81e622-efd1-b8fa-5a62-26f30336bed5 (2caf017c-a653-4b05-92fb-b8b29285fbb5)
> Nov 21 04:57:07 localhost SM: [20944] vdi_clone: introduced VDI: OpaqueRef:6cf2dd9e-efe6-ef1b-493c-0b3e06e682da (49341e2f-2b34-4f40-aa8a-0486a6cd14f8)
> Nov 21 04:57:07 localhost SM: [20944] Unpause request for 1cf9e9f7-862e-4426-9416-584987b9cfbd secondary=None
> Nov 21 04:57:07 localhost SM: [20944] Calling tap-unpause on host OpaqueRef:4366ea2d-224b-fa03-65d6-a53c18b0c77f
> Nov 21 04:57:07 localhost SM: [20991] lock: opening lock file /var/lock/sm/1cf9e9f7-862e-4426-9416-584987b9cfbd/vdi
> Nov 21 04:57:07 localhost SM: [20991] lock: acquired /var/lock/sm/1cf9e9f7-862e-4426-9416-584987b9cfbd/vdi
> Nov 21 04:57:07 localhost SM: [20991] Unpause for 1cf9e9f7-862e-4426-9416-584987b9cfbd
> Nov 21 04:57:07 localhost SM: [20991] Realpath: /var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/1cf9e9f7-862e-4426-9416-584987b9cfbd.vhd
> Nov 21 04:57:07 localhost SM: [20991] Calling tap unpause with minor 1
> Nov 21 04:57:07 localhost SM: [20991] ['/usr/sbin/tap-ctl', 'unpause', '-p', '18621', '-m', '1', '-a', 'vhd:/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/1cf9e9f7-862e-4426-9416-584987b9cfbd.vhd']
> Nov 21 04:57:08 localhost SM: [21001] ['/usr/sbin/td-util', 'query', 'vhd', '-vpfb', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/2caf017c-a653-4b05-92fb-b8b29285fbb5.vhd']
> Nov 21 04:57:08 localhost SM: [21001] pread SUCCESS
> Nov 21 04:57:08 localhost SM: [21001] lock: closed /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:57:08 localhost SM: [21022] lock: opening lock file /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:57:08 localhost SM: [21022] lock: acquired /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:57:08 localhost SM: [21022] ['/usr/sbin/td-util', 'query', 'vhd', '-vpfb', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/2caf017c-a653-4b05-92fb-b8b29285fbb5.vhd']
> Nov 21 04:57:08 localhost SM: [21022] pread SUCCESS
> Nov 21 04:57:08 localhost SM: [21022] lock: released /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:57:08 localhost SM: [21022] vdi_update {'sr_uuid': '1f273ffb-3583-4a91-0c30-faa4951e7df8', 'subtask_of': 'OpaqueRef:801b873e-525e-79b1-ed9e-59174ba805a1', 'vdi_ref': 'OpaqueRef:5f81e622-efd1-b8fa-5a62-26f30336bed5', 'vdi_on_boot': 'persist', 'args': [], 'o_direct': False, 'vdi_location': '2caf017c-a653-4b05-92fb-b8b29285fbb5', 'host_ref': 'OpaqueRef:4366ea2d-224b-fa03-65d6-a53c18b0c77f', 'session_ref': 'OpaqueRef:818180cb-850c-2bbd-f66a-befeaf00c8f2', 'device_config': {'SRmaster': 'true', 'serverpath': '/vol/export/902534-aauDvm', 'server': '10.220.160.33'}, 'command': 'vdi_update', 'vdi_allow_caching': 'false', 'sr_ref': 'OpaqueRef:c32edd0d-b0d7-7e28-9385-a4a6100f116b', 'vdi_uuid': '2caf017c-a653-4b05-92fb-b8b29285fbb5'}
> Nov 21 04:57:08 localhost SM: [21022] ['/usr/sbin/td-util', 'query', 'vhd', '-vpfb', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/2caf017c-a653-4b05-92fb-b8b29285fbb5.vhd']
> Nov 21 04:57:08 localhost SM: [21022] pread SUCCESS
> Nov 21 04:57:08 localhost SM: [21022] lock: closed /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:02 localhost SM: [21450] lock: opening lock file /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:02 localhost SM: [21450] lock: acquired /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:02 localhost SM: [21450] sr_scan {'sr_uuid': '1f273ffb-3583-4a91-0c30-faa4951e7df8', 'subtask_of': 'DummyRef:|184be5c5-dd11-de09-44a4-9c35491d9d39|SR.scan', 'args': [], 'host_ref': 'OpaqueRef:4366ea2d-224b-fa03-65d6-a53c18b0c77f', 'session_ref': 'OpaqueRef:b8af9168-34f8-7b83-c5f4-9787635da3eb', 'device_config': {'SRmaster': 'true', 'serverpath': '/vol/export/902534-aauDvm', 'server': '10.220.160.33'}, 'command': 'sr_scan', 'sr_ref': 'OpaqueRef:c32edd0d-b0d7-7e28-9385-a4a6100f116b'}
> Nov 21 04:58:02 localhost SM: [21450] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/*.vhd']
> Nov 21 04:58:02 localhost SM: [21450] pread SUCCESS
> Nov 21 04:58:02 localhost SM: [21450] ['ls', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8', '-1', '--color=never']
> Nov 21 04:58:02 localhost SM: [21450] pread SUCCESS
> Nov 21 04:58:02 localhost SM: [21450] lock: opening lock file /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/running
> Nov 21 04:58:02 localhost SM: [21450] lock: tried lock /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/running, acquired: True (exists: True)
> Nov 21 04:58:02 localhost SM: [21450] lock: released /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/running
> Nov 21 04:58:02 localhost SM: [21450] Kicking GC
> Nov 21 04:58:03 localhost SM: [21450] lock: closed /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/running
> Nov 21 04:58:03 localhost SM: [21457] lock: opening lock file /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:03 localhost SM: [21450] utilisation 17082880 <> 33847808
> Nov 21 04:58:03 localhost SM: [21450] utilisation 35766784 <> 33847808
> Nov 21 04:58:03 localhost SM: [21450] utilisation 78790656 <> 78749696
> Nov 21 04:58:03 localhost SM: [21450] VDIs changed on disk: ['1cf9e9f7-862e-4426-9416-584987b9cfbd', '49341e2f-2b34-4f40-aa8a-0486a6cd14f8', '943c7084-923a-4472-8205-59a49ea207c1']
> Nov 21 04:58:03 localhost SM: [21450] Updating VDI with location=1cf9e9f7-862e-4426-9416-584987b9cfbd uuid=1cf9e9f7-862e-4426-9416-584987b9cfbd
> Nov 21 04:58:03 localhost SMGC: [21457] Found 0 cache files
> Nov 21 04:58:03 localhost SM: [21457] lock: tried lock /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr, acquired: False (exists: True)
> Nov 21 04:58:03 localhost SM: [21450] Updating VDI with location=49341e2f-2b34-4f40-aa8a-0486a6cd14f8 uuid=49341e2f-2b34-4f40-aa8a-0486a6cd14f8
> Nov 21 04:58:03 localhost SM: [21450] Updating VDI with location=943c7084-923a-4472-8205-59a49ea207c1 uuid=943c7084-923a-4472-8205-59a49ea207c1
> Nov 21 04:58:03 localhost SM: [21450] lock: released /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:03 localhost SM: [21450] lock: closed /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:06 localhost SM: [21457] lock: tried lock /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr, acquired: True (exists: True)
> Nov 21 04:58:06 localhost SM: [21457] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/*.vhd']
> Nov 21 04:58:06 localhost SM: [21457] pread SUCCESS
> Nov 21 04:58:06 localhost SMGC: [21457] SR 1f27 ('9c0576b9-d3e8-3023-bed9-1e9a46a40d54') (11 VDIs in 3 VHD trees):
> Nov 21 04:58:06 localhost SMGC: [21457] *5c212631(2.441G/2.446G)
> Nov 21 04:58:06 localhost SMGC: [21457] 943c7084(2.441G/75.141M)
> Nov 21 04:58:06 localhost SMGC: [21457] 3b8d1b52(2.441G/9.500K)
> Nov 21 04:58:06 localhost SMGC: [21457] *745072fa(20.000G/1.638G)
> Nov 21 04:58:06 localhost SMGC: [21457] 80df1dcc(20.000G/45.500K)
> Nov 21 04:58:06 localhost SMGC: [21457] *837da6ea(20.000G/112.262M)
> Nov 21 04:58:06 localhost SMGC: [21457] 4c71ec2e(20.000G/45.500K)
> Nov 21 04:58:06 localhost SMGC: [21457] *1ba82e64(20.000G/1.646G)
> Nov 21 04:58:06 localhost SMGC: [21457] *49341e2f(20.000G/34.110M)
> Nov 21 04:58:06 localhost SMGC: [21457] 1cf9e9f7(20.000G/16.292M)
> Nov 21 04:58:06 localhost SMGC: [21457] 2caf017c(20.000G/45.500K)
> Nov 21 04:58:06 localhost SMGC: [21457]
> Nov 21 04:58:06 localhost SM: [21457] lock: released /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:06 localhost SMGC: [21457] Removed vhd-blocks from *49341e2f(20.000G/34.110M)
> Nov 21 04:58:06 localhost SM: [21457] lock: released /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:06 localhost SMGC: [21457] Removed vhd-blocks from *49341e2f(20.000G/34.110M)
> Nov 21 04:58:06 localhost SMGC: [21457] Got sm-config for *49341e2f(20.000G/34.110M): {'vhd-parent': '1ba82e64-3a86-4cb0-b708-d6ddffe02214'}
> Nov 21 04:58:06 localhost SM: [21457] ['/usr/bin/vhd-util', 'read', '--debug', '-B', '-n', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/49341e2f-2b34-4f40-aa8a-0486a6cd14f8.vhd']
> Nov 21 04:58:06 localhost SM: [21457] pread SUCCESS
> Nov 21 04:58:06 localhost SMGC: [21457] Set vhd-blocks = eJxjYAABAQGGUUBDQGnwEtI/VKJPREBAgINRgHGg3YEL0Csc6R1fLHS2b+gAAHQGANM= for *49341e2f(20.000G/34.110M)
> Nov 21 04:58:06 localhost SMGC: [21457] Got sm-config for *49341e2f(20.000G/34.110M): {'vhd-parent': '1ba82e64-3a86-4cb0-b708-d6ddffe02214', 'vhd-blocks': 'eJxjYAABAQGGUUBDQGnwEtI/VKJPREBAgINRgHGg3YEL0Csc6R1fLHS2b+gAAHQGANM='}
> Nov 21 04:58:06 localhost SMGC: [21457] Got sm-config for *1ba82e64(20.000G/1.646G): {'base_mirror': 'a847ec21-9d04-4a8c-5e7f-cf52b2f56b75/3b790b0e-6216-4277-a4ce-62aee1d03a35', 'vhd-blocks': 'eJz7398BBHv/A8HX0NDQAAYwEIBQDCIMDPIgqe//P8D4MFoAmY+kHkVeAE2eWBqPfrzmItkPdDeU/ofgE9JPKxpovxyR6rD6U1RQEOj+j4KiAoKSHxsFBND1iV8QhMYTBKDTKOHx/wAjhP4Ai99/IPqHAAF34ZEnlhaVnCj/V1BQkIj4E2BgpGo8EBXvoHD4Rx378NJioHD/Qdf0hyu/kpTPRKAscsMXlYbxBhAwAgAY6lZ1'}
> Nov 21 04:58:06 localhost SMGC: [21457] Num combined blocks = 841
> Nov 21 04:58:06 localhost SMGC: [21457] Coalesced size = 1.646G
> Nov 21 04:58:06 localhost SMGC: [21457] Coalesce candidate: *49341e2f(20.000G/34.110M) (tree height 3)
> Nov 21 04:58:06 localhost SM: [21457] lock: tried lock /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/running, acquired: True (exists: True)
> Nov 21 04:58:06 localhost SM: [21457] lock: tried lock /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr, acquired: True (exists: True)
> Nov 21 04:58:06 localhost SM: [21457] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/*.vhd']
> Nov 21 04:58:06 localhost SM: [21457] pread SUCCESS
> Nov 21 04:58:06 localhost SMGC: [21457] SR 1f27 ('9c0576b9-d3e8-3023-bed9-1e9a46a40d54') (11 VDIs in 3 VHD trees): no changes
> Nov 21 04:58:06 localhost SM: [21457] lock: released /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:06 localhost SMGC: [21457] Got sm-config for *5c212631(2.441G/2.446G): {'vhd-blocks': 'eJz7/3/wggMARbucJQ=='}
> Nov 21 04:58:06 localhost SMGC: [21457] Got sm-config for *745072fa(20.000G/1.638G): {'vhd-blocks': 'eJz7398BBHv/A8HX0NDQAAYwEIBQDCIMDPIgqe//P8D4MFoAmY+kHkVeAE2eWBqPfrzmItkPdDeU/ofgE9JPKxpovxyR6rD6U1RQEOj+j4KiAgKSHxsFBND1iV8QhMYTBKDTKOHx/wAjhP4Ai99/IPqHAAF34ZEnlhaVnCD/V1BQkIj4E2BgpGo8EBXvoHD4Rx378NJioHD/Qdf0hyu/kpXPqKAeiTeAgBEAAsJWXw=='}
> Nov 21 04:58:06 localhost SMGC: [21457] Got sm-config for *1ba82e64(20.000G/1.646G): {'base_mirror': 'a847ec21-9d04-4a8c-5e7f-cf52b2f56b75/3b790b0e-6216-4277-a4ce-62aee1d03a35', 'vhd-blocks': 'eJz7398BBHv/A8HX0NDQAAYwEIBQDCIMDPIgqe//P8D4MFoAmY+kHkVeAE2eWBqPfrzmItkPdDeU/ofgE9JPKxpovxyR6rD6U1RQEOj+j4KiAoKSHxsFBND1iV8QhMYTBKDTKOHx/wAjhP4Ai99/IPqHAAF34ZEnlhaVnCj/V1BQkIj4E2BgpGo8EBXvoHD4Rx378NJioHD/Qdf0hyu/kpTPRKAscsMXlYbxBhAwAgAY6lZ1'}
> Nov 21 04:58:06 localhost SMGC: [21457] Got sm-config for *49341e2f(20.000G/34.110M): {'vhd-parent': '1ba82e64-3a86-4cb0-b708-d6ddffe02214', 'vhd-blocks': 'eJxjYAABAQGGUUBDQGnwEtI/VKJPREBAgINRgHGg3YEL0Csc6R1fLHS2b+gAAHQGANM='}
> Nov 21 04:58:06 localhost SMGC: [21457] Got sm-config for *837da6ea(20.000G/112.262M): {'vhd-parent': '745072fa-345a-4a7b-99a3-0c3789831146', 'vhd-blocks': 'eJxrYAAB0QcMKECEEUKzMAxNIAClWdD4AwUotV9EQFCCgVGRQQSHUSI47MNlL7o8vcJHVFBQqlFQkJFO1pEM6BUO9E6PtM7HA52/yAcAa1ED4w=='}
> Nov 21 04:58:06 localhost SMGC: [21457] Removed vhd-blocks from *49341e2f(20.000G/34.110M)
> Nov 21 04:58:06 localhost SMGC: [21457] Got sm-config for *49341e2f(20.000G/34.110M): {'vhd-parent': '1ba82e64-3a86-4cb0-b708-d6ddffe02214'}
> Nov 21 04:58:06 localhost SM: [21457] ['/usr/bin/vhd-util', 'read', '--debug', '-B', '-n', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/49341e2f-2b34-4f40-aa8a-0486a6cd14f8.vhd']
> Nov 21 04:58:06 localhost SM: [21457] pread SUCCESS
> Nov 21 04:58:06 localhost SMGC: [21457] Set vhd-blocks = eJxjYAABAQGGUUBDQGnwEtI/VKJPREBAgINRgHGg3YEL0Csc6R1fLHS2b+gAAHQGANM= for *49341e2f(20.000G/34.110M)
> Nov 21 04:58:06 localhost SMGC: [21457] Got sm-config for *49341e2f(20.000G/34.110M): {'vhd-parent': '1ba82e64-3a86-4cb0-b708-d6ddffe02214', 'vhd-blocks': 'eJxjYAABAQGGUUBDQGnwEtI/VKJPREBAgINRgHGg3YEL0Csc6R1fLHS2b+gAAHQGANM='}
> Nov 21 04:58:06 localhost SMGC: [21457] Got sm-config for *1ba82e64(20.000G/1.646G): {'base_mirror': 'a847ec21-9d04-4a8c-5e7f-cf52b2f56b75/3b790b0e-6216-4277-a4ce-62aee1d03a35', 'vhd-blocks': 'eJz7398BBHv/A8HX0NDQAAYwEIBQDCIMDPIgqe//P8D4MFoAmY+kHkVeAE2eWBqPfrzmItkPdDeU/ofgE9JPKxpovxyR6rD6U1RQEOj+j4KiAoKSHxsFBND1iV8QhMYTBKDTKOHx/wAjhP4Ai99/IPqHAAF34ZEnlhaVnCj/V1BQkIj4E2BgpGo8EBXvoHD4Rx378NJioHD/Qdf0hyu/kpTPRKAscsMXlYbxBhAwAgAY6lZ1'}
> Nov 21 04:58:06 localhost SMGC: [21457] Num combined blocks = 841
> Nov 21 04:58:06 localhost SMGC: [21457] Coalesced size = 1.646G
> Nov 21 04:58:06 localhost SMGC: [21457] Coalesce candidate: *49341e2f(20.000G/34.110M) (tree height 3)
> Nov 21 04:58:06 localhost SMGC: [21457] Coalescing *49341e2f(20.000G/34.110M) -> *1ba82e64(20.000G/1.646G)
> Nov 21 04:58:06 localhost SM: [21457] ['/usr/bin/vhd-util', 'check', '--debug', '-n', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/49341e2f-2b34-4f40-aa8a-0486a6cd14f8.vhd']
> Nov 21 04:58:06 localhost SM: [21457] pread SUCCESS
> Nov 21 04:58:06 localhost SM: [21457] ['/usr/bin/vhd-util', 'check', '--debug', '-n', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/1ba82e64-3a86-4cb0-b708-d6ddffe02214.vhd', '-B']
> Nov 21 04:58:06 localhost SM: [21457] pread SUCCESS
> Nov 21 04:58:06 localhost SMGC: [21457] Running VHD coalesce on *49341e2f(20.000G/34.110M)
> Nov 21 04:58:06 localhost SM: [21526] ['/usr/bin/vhd-util', 'coalesce', '--debug', '-n', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/49341e2f-2b34-4f40-aa8a-0486a6cd14f8.vhd']
> Nov 21 04:58:11 localhost SM: [21526] pread SUCCESS
> Nov 21 04:58:11 localhost SM: [21526] IPCFlag: set 1f273ffb-3583-4a91-0c30-faa4951e7df8:success
> Nov 21 04:58:11 localhost SMGC: [21457] Child process completed successfully
> Nov 21 04:58:11 localhost SM: [21457] IPCFlag: clear 1f273ffb-3583-4a91-0c30-faa4951e7df8:success
> Nov 21 04:58:11 localhost SM: [21457] ['/usr/bin/vhd-util', 'check', '--debug', '-n', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/1ba82e64-3a86-4cb0-b708-d6ddffe02214.vhd', '-B']
> Nov 21 04:58:11 localhost SM: [21457] pread SUCCESS
> @
> Nov 21 04:58:11 localhost SM: [21457] lock: tried lock /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr, acquired: True (exists: True)
> Nov 21 04:58:11 localhost SM: [21457] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/*.vhd']
> Nov 21 04:58:11 localhost SM: [21457] pread SUCCESS
> Nov 21 04:58:11 localhost SMGC: [21457] SR 1f27 ('9c0576b9-d3e8-3023-bed9-1e9a46a40d54') (11 VDIs in 3 VHD trees): no changes
> Nov 21 04:58:11 localhost SMGC: [21457] Relinking 1cf9e9f7(20.000G/16.292M) from *49341e2f(20.000G/34.110M) to *1ba82e64(20.000G/1.646G)
> Nov 21 04:58:11 localhost SM: [21457] ['/usr/bin/vhd-util', 'modify', '--debug', '-p', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/1ba82e64-3a86-4cb0-b708-d6ddffe02214.vhd', '-n', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/1cf9e9f7-862e-4426-9416-584987b9cfbd.vhd']
> Nov 21 04:58:12 localhost SM: [21457] pread SUCCESS
> Nov 21 04:58:12 localhost SMGC: [21457] Set vhd-parent = 1ba82e64-3a86-4cb0-b708-d6ddffe02214 for 1cf9e9f7(20.000G/16.292M)
> Nov 21 04:58:12 localhost SMGC: [21457] Updated the vhd-parent field for child 1cf9e9f7-862e-4426-9416-584987b9cfbd with 1ba82e64-3a86-4cb0-b708-d6ddffe02214
> Nov 21 04:58:12 localhost SMGC: [21457] Relinking 2caf017c(20.000G/45.500K) from *49341e2f(20.000G/34.110M) to *1ba82e64(20.000G/1.646G)
> Nov 21 04:58:12 localhost SM: [21457] ['/usr/bin/vhd-util', 'modify', '--debug', '-p', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/1ba82e64-3a86-4cb0-b708-d6ddffe02214.vhd', '-n', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/2caf017c-a653-4b05-92fb-b8b29285fbb5.vhd']
> Nov 21 04:58:12 localhost SM: [21457] pread SUCCESS
> Nov 21 04:58:12 localhost SMGC: [21457] Set vhd-parent = 1ba82e64-3a86-4cb0-b708-d6ddffe02214 for 2caf017c(20.000G/45.500K)
> Nov 21 04:58:12 localhost SMGC: [21457] Updated the vhd-parent field for child 2caf017c-a653-4b05-92fb-b8b29285fbb5 with 1ba82e64-3a86-4cb0-b708-d6ddffe02214
> Nov 21 04:58:12 localhost SM: [21457] lock: released /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:12 localhost SMGC: [21457] Reloading VDI 1cf9e9f7(20.000G/16.292M)
> Nov 21 04:58:12 localhost SM: [21457] lock: tried lock /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr, acquired: True (exists: True)
> Nov 21 04:58:12 localhost SM: [21457] Refresh request for 1cf9e9f7-862e-4426-9416-584987b9cfbd
> Nov 21 04:58:12 localhost SM: [21457] Calling tap-refresh on host OpaqueRef:4366ea2d-224b-fa03-65d6-a53c18b0c77f
> Nov 21 04:58:12 localhost SM: [21570] lock: opening lock file /var/lock/sm/1cf9e9f7-862e-4426-9416-584987b9cfbd/vdi
> Nov 21 04:58:12 localhost SM: [21570] lock: acquired /var/lock/sm/1cf9e9f7-862e-4426-9416-584987b9cfbd/vdi
> Nov 21 04:58:12 localhost SM: [21570] Pause for 1cf9e9f7-862e-4426-9416-584987b9cfbd
> Nov 21 04:58:12 localhost SM: [21570] Calling tap pause with minor 1
> Nov 21 04:58:12 localhost SM: [21570] ['/usr/sbin/tap-ctl', 'pause', '-p', '18621', '-m', '1']
> Nov 21 04:58:12 localhost SM: [21570] = 0
> Nov 21 04:58:12 localhost SM: [21570] lock: released /var/lock/sm/1cf9e9f7-862e-4426-9416-584987b9cfbd/vdi
> Nov 21 04:58:12 localhost SM: [21570] lock: acquired /var/lock/sm/1cf9e9f7-862e-4426-9416-584987b9cfbd/vdi
> Nov 21 04:58:12 localhost SM: [21570] Unpause for 1cf9e9f7-862e-4426-9416-584987b9cfbd
> Nov 21 04:58:12 localhost SM: [21570] Realpath: /var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/1cf9e9f7-862e-4426-9416-584987b9cfbd.vhd
> Nov 21 04:58:12 localhost SM: [21570] Calling tap unpause with minor 1
> Nov 21 04:58:13 localhost SM: [21457] lock: released /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/running
> Nov 21 04:58:13 localhost SM: [21457] lock: tried lock /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr, acquired: True (exists: True)
> Nov 21 04:58:13 localhost SM: [21457] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/*.vhd']
> Nov 21 04:58:13 localhost SM: [21457] pread SUCCESS
> Nov 21 04:58:13 localhost SMGC: [21457] SR 1f27 ('9c0576b9-d3e8-3023-bed9-1e9a46a40d54') (11 VDIs in 3 VHD trees): showing only VHD trees that changed:
> Nov 21 04:58:13 localhost SMGC: [21457] *1ba82e64(20.000G/1.646G)
> Nov 21 04:58:13 localhost SMGC: [21457] 1cf9e9f7(20.000G/18.079M)
> Nov 21 04:58:13 localhost SMGC: [21457] 2caf017c(20.000G/45.500K)
> Nov 21 04:58:13 localhost SMGC: [21457] *49341e2f(20.000G/34.110M)
> Nov 21 04:58:13 localhost SMGC: [21457]
> Nov 21 04:58:13 localhost SM: [21457] lock: released /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:13 localhost SM: [21457] lock: tried lock /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/running, acquired: True (exists: True)
> Nov 21 04:58:13 localhost SM: [21457] lock: tried lock /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr, acquired: True (exists: True)
> Nov 21 04:58:13 localhost SM: [21457] ['/usr/bin/vhd-util', 'scan', '-f', '-c', '-m', '/var/run/sr-mount/1f273ffb-3583-4a91-0c30-faa4951e7df8/*.vhd']
> Nov 21 04:58:13 localhost SM: [21457] pread SUCCESS
> Nov 21 04:58:13 localhost SMGC: [21457] SR 1f27 ('9c0576b9-d3e8-3023-bed9-1e9a46a40d54') (11 VDIs in 3 VHD trees): no changes
> Nov 21 04:58:13 localhost SM: [21457] lock: released /var/lock/sm/1f273ffb-3583-4a91-0c30-faa4951e7df8/sr
> Nov 21 04:58:13 localhost SMGC: [21457] Got sm-config for *5c212631(2.441G/2.446G): {'vhd-blocks': 'eJz7/3/wggMARbucJQ=='}
> Nov 21 04:58:13 localhost SMGC: [21457] Got sm-config for *745072fa(20.000G/1.638G): {'vhd-blocks': 'eJz7398BBHv/A8HX0NDQAAYwEIBQDCIMDPIgqe//P8D4MFoAmY+kHkVeAE2eWBqPfrzmItkPdDeU/ofgE9JPKxpovxyR6rD6U1RQEOj+j4KiAgKSHxsFBND1iV8QhMYTBKDTKOHx/wAjhP4Ai99/IPqHAAF34ZEnlhaVnCD/V1BQkIj4E2BgpGo8EBXvoHD4Rx378NJioHD/Qdf0hyu/kpXPqKAeiTeAgBEAAsJWXw=='}
> Nov 21 04:58:13 localhost SMGC: [21457] Got sm-config for *1ba82e64(20.000G/1.646G): {'base_mirror': 'a847ec21-9d04-4a8c-5e7f-cf52b2f56b75/3b790b0e-6216-4277-a4ce-62aee1d03a35', 'vhd-blocks': 'eJz7398BBHv/A8HX0NDQAAYwEIBQDCIMDPIgqe//P8D4MFoAmY+kHkVeAE2eWBqPfrzmItkPdDeU/ofgE9JPKxpovxyR6rD6U1RQEOj+j4KiAoKSHxsFBND1iV8QhMYTBKDTKOHx/wAjhP4Ai99/IPqHAAF34ZEnlhaVnCj/V1BQkIj4E2BgpGo8EBXvoHD4Rx378NJioHD/Qdf0hyu/kpTPRKAscsMXlYbxBhAwAgAY6lZ1'}
> Nov 21 04:58:13 localhost SMGC: [21457] Got sm-config for *837da6ea(20.000G/112.262M): {'vhd-parent': '745072fa-345a-4a7b-99a3-0c3789831146', 'vhd-blocks': 'eJxrYAAB0QcMKECEEUKzMAxNIAClWdD4AwUotV9EQFCCgVGRQQSHUSI47MNlL7o8vcJHVFBQqlFQkJFO1pEM6BUO9E6PtM7HA52/yAcAa1ED4w=='}
> Nov 21 04:58:13 localhost SMGC: [21457] Found 1 VDIs for deletion:
> Nov 21 04:58:13 localhost SMGC: [21457] *49341e2f(20.000G/34.110M)
> Nov 21 04:58:13 localhost SMGC: [21457] Deleting unlinked VDI *49341e2f(20.000G/34.110M)
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)