[Pkg-xen-devel] Bug#674161: Bug#674161: [Xen-API] Bug#674161: xcp-xapi: 'the device disappeared from xenstore' message during vbd-plug (vm-start)

[George Shuklin]

On 23.05.2012 22:46, Mike McClurg wrote:
> On Wed, May 23, 2012 at 7:10 PM, George Shuklin
> <george.shuklin at gmail.com> wrote:
>> Some more data:
>>
>> Done clean reinstall (now with i386 in dom0), got exactly same error.
> What SR type are you using? If you are using something other than the
> FileSr, can you also attach SMlog from the relevant time period?
>
> Mike

SM is 'file'. SMLog seems be so clean I've thought it is not relevant. 
But anyway here it is:

[11241] 2012-05-23 22:54:56.348313      SUCCESS
[11241] 2012-05-23 22:54:56.349302      vdi_attach {'sr_uuid': 
'357e7a73-ec60-6ae6-fe0d-56ca87d228b2', 'subtask_of': 
'DummyRef:|75bad4bb-a459-1750-e4df-bffd787db49a|VDI.attach', 'vdi_ref': 
'OpaqueRef:e893c151-fca2-c408-81be-e0c06fef0139', 'vdi_on_boot': 
'persist', 'args': ['true'], 'vdi_location': 
'00048fc5-9764-408a-801d-9d9442974c9d', 'host_ref': 
'OpaqueRef:2e64a8c9-81be-f152-503b-200861cff9e6', 'session_ref': 
'OpaqueRef:546b5c70-209a-8a95-1bfe-b3d268256f8e', 'device_config': 
{'SRmaster': 'true', 'location': '/mnt'}, 'command': 'vdi_attach', 
'vdi_allow_caching': 'false', 'sr_ref': 
'OpaqueRef:ee16513a-2dd5-0306-6ad6-73d9ef2a958d', 'vdi_uuid': 
'00048fc5-9764-408a-801d-9d9442974c9d'}
[11241] 2012-05-23 22:54:56.349595      lock: creating lock file 
/var/lock/sm/00048fc5-9764-408a-801d-9d9442974c9d/vdi
[11241] 2012-05-23 22:54:56.349816      lock: closed 
/var/lock/sm/00048fc5-9764-408a-801d-9d9442974c9d/vdi
[11241] 2012-05-23 22:54:56.349934      lock: released 
/var/lock/sm/357e7a73-ec60-6ae6-fe0d-56ca87d228b2/sr
[11241] 2012-05-23 22:54:56.350233      lock: closed 
/var/lock/sm/357e7a73-ec60-6ae6-fe0d-56ca87d228b2/sr
[11247] 2012-05-23 22:54:56.439703      lock: acquired 
/var/lock/sm/357e7a73-ec60-6ae6-fe0d-56ca87d228b2/sr
[11247] 2012-05-23 22:54:56.439853      ['/usr/sbin/td-util', 'query', 
'vhd', '-vpf', '/mnt/00048fc5-9764-408a-801d-9d9442974c9d.vhd']
[11247] 2012-05-23 22:54:56.444958      SUCCESS
[11247] 2012-05-23 22:54:56.445908      lock: released 
/var/lock/sm/357e7a73-ec60-6ae6-fe0d-56ca87d228b2/sr
[11247] 2012-05-23 22:54:56.446066      vdi_activate {'sr_uuid': 
'357e7a73-ec60-6ae6-fe0d-56ca87d228b2', 'subtask_of': 
'DummyRef:|722c4aac-53a1-f480-cb35-4278ae467486|VDI.activate', 
'vdi_ref': 'OpaqueRef:e893c151-fca2-c408-81be-e0c06fef0139', 
'vdi_on_boot': 'persist', 'args': ['true'], 'vdi_location': 
'00048fc5-9764-408a-801d-9d9442974c9d', 'host_ref': 
'OpaqueRef:2e64a8c9-81be-f152-503b-200861cff9e6', 'session_ref': 
'OpaqueRef:87181fc8-c932-7929-240b-7a2595b8b11f', 'device_config': 
{'SRmaster': 'true', 'location': '/mnt'}, 'command': 'vdi_activate', 
'vdi_allow_caching': 'false', 'sr_ref': 
'OpaqueRef:ee16513a-2dd5-0306-6ad6-73d9ef2a958d', 'vdi_uuid': 
'00048fc5-9764-408a-801d-9d9442974c9d'}
[11247] 2012-05-23 22:54:56.446328      blktap2.activate
[11247] 2012-05-23 22:54:56.446418      lock: acquired 
/var/lock/sm/00048fc5-9764-408a-801d-9d9442974c9d/vdi
[11247] 2012-05-23 22:54:56.446512      Adding tag to: 
00048fc5-9764-408a-801d-9d9442974c9d
[11247] 2012-05-23 22:54:56.460867      Activate lock succeeded
[11247] 2012-05-23 22:54:56.494658      ['/usr/sbin/td-util', 'query', 
'vhd', '-vpf', '/mnt/00048fc5-9764-408a-801d-9d9442974c9d.vhd']
[11247] 2012-05-23 22:54:56.499583      SUCCESS
[11247] 2012-05-23 22:54:56.532880 
PhyLink(/dev/sm/phy/357e7a73-ec60-6ae6-fe0d-56ca87d228b2/00048fc5-9764-408a-801d-9d9442974c9d) 
-> /mnt/00048fc5-9764-408a-801d-9d9442974c9d.vhd
[11247] 2012-05-23 22:54:56.537025      ['/usr/sbin/tap-ctl', 'allocate']
[11247] 2012-05-23 22:54:56.540947       = 0
[11247] 2012-05-23 22:54:56.545753      ['/usr/sbin/tap-ctl', 'spawn']
[11247] 2012-05-23 22:54:56.551907       = 0
[11247] 2012-05-23 22:54:56.552088      ['/usr/sbin/tap-ctl', 'attach', 
'-p', '11282', '-m', '0']
[11247] 2012-05-23 22:54:56.556115       = 0
[11247] 2012-05-23 22:54:56.556339      ['/usr/sbin/tap-ctl', 'open', 
'-p', '11282', '-m', '0', '-a', 
'vhd:/mnt/00048fc5-9764-408a-801d-9d9442974c9d.vhd']
[11247] 2012-05-23 22:54:56.577555       = 0
[11247] 2012-05-23 22:54:56.582410      tap.activate: Launched 
Tapdisk(vhd:/mnt/00048fc5-9764-408a-801d-9d9442974c9d.vhd, pid=11282, 
minor=0, state=R)
[11247] 2012-05-23 22:54:56.582743 
DeviceNode(/dev/sm/backend/357e7a73-ec60-6ae6-fe0d-56ca87d228b2/00048fc5-9764-408a-801d-9d9442974c9d) 
-> /dev/xen/blktap-2/tapdev0
[11247] 2012-05-23 22:54:56.583023      lock: released 
/var/lock/sm/00048fc5-9764-408a-801d-9d9442974c9d/vdi
[11247] 2012-05-23 22:54:56.583125      lock: closed 
/var/lock/sm/00048fc5-9764-408a-801d-9d9442974c9d/vdi
[11247] 2012-05-23 22:54:56.583289      lock: closed 
/var/lock/sm/357e7a73-ec60-6ae6-fe0d-56ca87d228b2/sr
[11247] 2012-05-23 22:54:56.583770      lock: closed 
/var/lock/sm/357e7a73-ec60-6ae6-fe0d-56ca87d228b2/sr