[Xen-API] NFS exported from ZFS on Ubuntu 14.04

[David <dkadds2@xxxxxxxxx>]

Hi Guys,

I'm looking for your help as I'm facing issue with Xen and NFS Storage 
Repository.
The problem I'm facing is that vm-import fails due to blktap2 issue 
(seems like).
Last call is about vdi_activate failure (full dump below):

generic exception: vdi_activate: EXCEPTION <type 
'exceptions.AttributeError'>, 'CommandFailure' object has no attribute 
'status'
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 1560, in _activate
Dec 30 06:51:38 g6xentest SM: [5266] 
self._get_pool_config(sr_uuid).get("mem-pool-size"))
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate
Dec 30 06:51:38 g6xentest SM: [5266]     tapdisk = 
Tapdisk.find_by_path(phy_path)

On backend side NFS is exported from ZFS (localhost) encrypted with 
dedup on if it would matter.
Xenserver is compiled from sources from buildroot.

I've tried to use FileSR but it failed miserably too.
To get to the stage where I am now I've had to fix some static paths 
within SR adapters.
Any help and hints is more than appreciated.

btw. why do I use ZFS exported via NFS? I need compression and 
deduplication. Host itself has plenty of resources and will be running 
couple of VMs with almost same configuration and won't be highly loaded. 
Therefore I'm not after performance but more concerned about storage space.
Any hints on how to put SR on ZFS on Linux (Ubuntu preferred) would be 
great.
I've tested so far FileSR, tried to expose ZFS block device and loopback 
but it didn't work.
Creating file on ZFS mounted via loopback to create vg/pv/lv seems like 
an overkill.
The last option which I've figured out would be to expose ZFS via iSCSI, 
but this seems to be an overkill too.
The easiest for me and fully acceptable would be to use VHD (VDI) files 
directly on ZFS but it didn't work neither.
Thanks,
Dawid


Dec 30 06:51:38 g6xentest SM: [5266] ['/usr/sbin/td-util', 'query', 
'vhd', '-vpf', 
'/var/run/sr-mount/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/cae30e9b-ebd4-4450-8ce0-9f4035ca62f1.vhd']
Dec 30 06:51:38 g6xentest SM: [5266]   pread SUCCESS
Dec 30 06:51:38 g6xentest SM: [5266] 
PhyLink(/dev/sm/phy/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/cae30e9b-ebd4-4450-8ce0-9f4035ca62f1) 
-> 
/var/run/sr-mount/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/cae30e9b-ebd4-4450-8ce0-9f4035ca62f1.vhd
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4363 UNIX 
/var/lib/xcp/xapi|session.login_with_password D:96522b0bba4b|xapi] 
Session.create trackid=b40ee0f70df6669d28d67cd077003734 pool=false 
uname=root originator= is_local_superuser=true auth_user_sid= 
parent=trackid=9834f5af41c964e225f24279aefe4e49
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4363 UNIX 
/var/lib/xcp/xapi|session.login_with_password D:96522b0bba4b|mscgen] 
xapi=>xapi [label="session.get_uuid"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4364 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid 
D:ca81a60b135e created by task D:96522b0bba4b
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4371 UNIX 
/var/lib/xcp/xapi|session.logout D:d45fe29cc472|xapi] Session.destroy 
trackid=b40ee0f70df6669d28d67cd077003734
Dec 30 06:51:38 g6xentest SM: [5266] Exception in activate/attach
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4372 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_by_uuid 
D:816bae444f6c created by task D:7b03301c184c
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4373 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_by_uuid 
D:9c05de3c6f24 created by task D:7b03301c184c
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4374 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_sm_config 
D:0a4f152ffe26 created by task D:7b03301c184c
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4375 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task 
dispatch:VDI.remove_from_sm_config D:cb677dfe97f0 created by task 
D:7b03301c184c
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4375 UNIX 
/var/lib/xcp/xapi|dispatch:VDI.remove_from_sm_config 
D:cb677dfe97f0|api_effect] VDI.remove_from_sm_config
Dec 30 06:51:38 g6xentest xapi: 
[20141230T05:51:38.396Z|audit|g6xentest|4375 UNIX 
/var/lib/xcp/xapi|VDI.remove_from_sm_config D:550b87e7d87f|audit] 
('trackid=be2e0d0044616f90c8b73f608309541e' 'LOCAL_SUPERUSER' 
'__sm__backend' 'ALLOWED' 'OK' 'API' 'VDI.remove_from_sm_config' 
(('self' 'XOA System' 'cae30e9b-ebd4-4450-8ce0-9f4035ca62f1' 
'OpaqueRef:0b132ca6-b8e7-c11f-6fff-dec08432cafe')))
Dec 30 06:51:38 g6xentest SM: [5266] Removed host key 
host_OpaqueRef:bc001d57-65ce-8b2b-74b4-cd71976272c0 for 
cae30e9b-ebd4-4450-8ce0-9f4035ca62f1
Dec 30 06:51:38 g6xentest SM: [5266] ***** BLKTAP2:<function 
_activate_locked at 0x7f611a801ed8>: EXCEPTION <type 
'exceptions.AttributeError'>, 'CommandFailure' object has no attribute 
'status'
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper
Dec 30 06:51:38 g6xentest SM: [5266]     ret = op(self, *args)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 1522, in _activate_locked
Dec 30 06:51:38 g6xentest SM: [5266]     dev_path = 
self._activate(sr_uuid, vdi_uuid, options)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 1560, in _activate
Dec 30 06:51:38 g6xentest SM: [5266] 
self._get_pool_config(sr_uuid).get("mem-pool-size"))
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate
Dec 30 06:51:38 g6xentest SM: [5266]     tapdisk = 
Tapdisk.find_by_path(phy_path)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path
Dec 30 06:51:38 g6xentest SM: [5266]     return cls.find(path=path)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 678, in find
Dec 30 06:51:38 g6xentest SM: [5266]     found = list(cls.list(**args))
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list
Dec 30 06:51:38 g6xentest SM: [5266]     for row in TapCtl.list(**args):
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 118, in loop
Dec 30 06:51:38 g6xentest SM: [5266]     return f(*__t, **__d)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list
Dec 30 06:51:38 g6xentest SM: [5266]     if e.status in transient:
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 162, in __getattr__
Dec 30 06:51:38 g6xentest SM: [5266]     return 
object.__getattribute__(self, key)
Dec 30 06:51:38 g6xentest SM: [5266]
Dec 30 06:51:38 g6xentest SM: [5266] lock: released 
/var/lock/sm/cae30e9b-ebd4-4450-8ce0-9f4035ca62f1/vdi
Dec 30 06:51:38 g6xentest SM: [5266] ***** generic exception: 
vdi_activate: EXCEPTION <type 'exceptions.AttributeError'>, 
'CommandFailure' object has no attribute 'status'
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/SRCommand.py", line 110, in run
Dec 30 06:51:38 g6xentest SM: [5266]     return self._run_locked(sr)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/SRCommand.py", line 159, in _run_locked
Dec 30 06:51:38 g6xentest SM: [5266]     rv = self._run(sr, target)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/SRCommand.py", line 263, in _run
Dec 30 06:51:38 g6xentest SM: [5266]     writable, caching_params)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 1486, in activate
Dec 30 06:51:38 g6xentest SM: [5266]     if 
self._activate_locked(sr_uuid, vdi_uuid, options):
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper
Dec 30 06:51:38 g6xentest SM: [5266]     ret = op(self, *args)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 1522, in _activate_locked
Dec 30 06:51:38 g6xentest SM: [5266]     dev_path = 
self._activate(sr_uuid, vdi_uuid, options)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 1560, in _activate
Dec 30 06:51:38 g6xentest SM: [5266] 
self._get_pool_config(sr_uuid).get("mem-pool-size"))
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate
Dec 30 06:51:38 g6xentest SM: [5266]     tapdisk = 
Tapdisk.find_by_path(phy_path)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path
Dec 30 06:51:38 g6xentest SM: [5266]     return cls.find(path=path)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 678, in find
Dec 30 06:51:38 g6xentest SM: [5266]     found = list(cls.list(**args))
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list
Dec 30 06:51:38 g6xentest SM: [5266]     for row in TapCtl.list(**args):
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 118, in loop
Dec 30 06:51:38 g6xentest SM: [5266]     return f(*__t, **__d)
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list
Dec 30 06:51:38 g6xentest SM: [5266]     if e.status in transient:
Dec 30 06:51:38 g6xentest SM: [5266]   File 
"/usr/lib/xapi/sm/blktap2.py", line 162, in __getattr__
Dec 30 06:51:38 g6xentest SM: [5266]     return 
object.__getattribute__(self, key)
Dec 30 06:51:38 g6xentest SM: [5266]
Dec 30 06:51:38 g6xentest SM: [5266] lock: closed 
/var/lock/sm/cae30e9b-ebd4-4450-8ce0-9f4035ca62f1/vdi
Dec 30 06:51:38 g6xentest SM: [5266] lock: closed 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:38 g6xentest forkexecd: [error|g6xentest|0||forkexecd] 5266 
(/opt/xensource/sm/NFSSR 
<methodCall><methodName>vdi_activate</methodName><par...) exitted with 
code 1
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|sm_exec 
D:74a592aeddb3|xapi] Raised at file \"lib/forkhelpers.ml\", line 181, 
characters 31-75\nCalled from file \"lib/pervasiveext.ml\", line 22, 
characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|sm_exec 
D:74a592aeddb3|xapi] Raised at file \"sm_exec.ml\", line 193, characters 
10-100\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|sm_exec 
D:74a592aeddb3|xapi] Raised at file \"lib/pervasiveext.ml\", line 26, 
characters 22-25\nCalled from file \"sm_exec.ml\", line 175, characters 
23-1023\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4340|sm_exec 
D:74a592aeddb3|xapi] Session.destroy 
trackid=be2e0d0044616f90c8b73f608309541e
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|sm_exec 
D:74a592aeddb3|backtrace] Raised at file \"lib/pervasiveext.ml\", line 
26, characters 22-25\nCalled from file \"server_helpers.ml\", line 72, 
characters 11-23\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|sm_exec 
D:74a592aeddb3|dispatcher] Server_helpers.exec exception_handler: Got 
exception SR_BACKEND_FAILURE: [ non-zero exit; ; Traceback (most recent 
call last):#012  File "/opt/xensource/sm/NFSSR", line 278, in 
<module>#012    SRCommand.run(NFSSR, DRIVER_INFO)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 344, in run#012    ret = 
cmd.run(sr)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 110, in 
run#012    return self._run_locked(sr)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 159, in _run_locked#012    rv = 
self._run(sr, target)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 
263, in _run#012    writable, caching_params)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 1486, in activate#012    if 
self._activate_locked(sr_uuid, vdi_uuid, options):#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper#012    ret = op(self, 
*args)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1522, in 
_activate_locked#012    dev_path = self._activate(sr_uuid, vdi_uuid, 
options)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1560, in 
_activate#012 self._get_pool_config(sr_uuid).get("mem-pool-size"))#012  
File "/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate#012 
tapdisk = Tapdisk.find_by_path(phy_path)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path#012 return 
cls.find(path=path)#012  File "/usr/lib/xapi/sm/blktap2.py", line 678, 
in find#012    found = list(cls.list(**args))#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list#012    for row in 
TapCtl.list(**args):#012  File "/usr/lib/xapi/sm/blktap2.py", line 118, 
in loop#012    return f(*__t, **__d)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list#012    if e.status in 
transient:#012  File "/usr/lib/xapi/sm/blktap2.py", line 162, in 
__getattr__#012    return object.__getattribute__(self, 
key)#012AttributeError: 'CommandFailure' object has no attribute 
'status'#012 ]
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|sm_exec 
D:74a592aeddb3|dispatcher] Raised at file \"hashtbl.ml\", line 136, 
characters 19-28\nCalled from file \"lib/debug.ml\", line 172, 
characters 37-64\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|sm_exec 
D:74a592aeddb3|backtrace] Raised at file \"hashtbl.ml\", line 136, 
characters 19-28\nCalled from file \"lib/debug.ml\", line 172, 
characters 37-64\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|sm_exec 
D:74a592aeddb3|xapi] Raised at file \"server_helpers.ml\", line 90, 
characters 14-15\nCalled from file \"lib/pervasiveext.ml\", line 22, 
characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|VDI.activate 
D:7b03301c184c|xapi] Raised at file \"lib/pervasiveext.ml\", line 26, 
characters 22-25\nCalled from file \"lib/debug.ml\", line 57, characters 
17-20\nRe-raised at file \"lib/debug.ml\", line 62, characters 
10-11\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|VDI.activate 
D:7b03301c184c|backtrace] Raised at file \"lib/pervasiveext.ml\", line 
26, characters 22-25\nCalled from file \"sm.ml\", line 171, characters 
21-72\nCalled from file \"server_helpers.ml\", line 72, characters 11-23\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|VDI.activate 
D:7b03301c184c|dispatcher] Server_helpers.exec exception_handler: Got 
exception SR_BACKEND_FAILURE: [ non-zero exit; ; Traceback (most recent 
call last):#012  File "/opt/xensource/sm/NFSSR", line 278, in 
<module>#012    SRCommand.run(NFSSR, DRIVER_INFO)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 344, in run#012    ret = 
cmd.run(sr)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 110, in 
run#012    return self._run_locked(sr)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 159, in _run_locked#012    rv = 
self._run(sr, target)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 
263, in _run#012    writable, caching_params)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 1486, in activate#012    if 
self._activate_locked(sr_uuid, vdi_uuid, options):#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper#012    ret = op(self, 
*args)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1522, in 
_activate_locked#012    dev_path = self._activate(sr_uuid, vdi_uuid, 
options)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1560, in 
_activate#012 self._get_pool_config(sr_uuid).get("mem-pool-size"))#012  
File "/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate#012 
tapdisk = Tapdisk.find_by_path(phy_path)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path#012 return 
cls.find(path=path)#012  File "/usr/lib/xapi/sm/blktap2.py", line 678, 
in find#012    found = list(cls.list(**args))#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list#012    for row in 
TapCtl.list(**args):#012  File "/usr/lib/xapi/sm/blktap2.py", line 118, 
in loop#012    return f(*__t, **__d)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list#012    if e.status in 
transient:#012  File "/usr/lib/xapi/sm/blktap2.py", line 162, in 
__getattr__#012    return object.__getattribute__(self, 
key)#012AttributeError: 'CommandFailure' object has no attribute 
'status'#012 ]
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|VDI.activate 
D:7b03301c184c|dispatcher] Raised at file \"hashtbl.ml\", line 136, 
characters 19-28\nCalled from file \"lib/debug.ml\", line 172, 
characters 37-64\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|VDI.activate 
D:7b03301c184c|backtrace] Raised at file \"hashtbl.ml\", line 136, 
characters 19-28\nCalled from file \"lib/debug.ml\", line 172, 
characters 37-64\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340|VDI.activate 
D:7b03301c184c|xapi] Raised at file \"server_helpers.ml\", line 90, 
characters 14-15\nCalled from file \"lib/pervasiveext.ml\", line 22, 
characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4340||xapi] Raised at 
file \"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from 
file \"lib/debug.ml\", line 57, characters 17-20\nRe-raised at file 
\"lib/debug.ml\", line 62, characters 10-11\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [error|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|VBD.plug R:6a9fba3a1166|storage_impl] Storage_impl: 
dp:vbd/0/xvda sr:787b0874-8e8b-e7d8-8ba9-74f55ff2ea16 
vdi:cae30e9b-ebd4-4450-8ce0-9f4035ca62f1 op:activate 
error:Storage_interface.Backend_error(_) backtrace:Called from file 
"storage_impl.ml", line 299, characters 7-50
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|VBD.plug R:6a9fba3a1166|xapi] Called from file 
\"storage_impl.ml\", line 299, characters 7-50\nRe-raised at file 
\"storage_impl.ml\", line 319, characters 11-12\nCalled from file 
\"list.ml\", line 84, characters 24-34\nCalled from file 
\"storage_impl.ml\", line 341, characters 6-55\nRe-raised at file 
\"storage_impl.ml\", line 345, characters 12-13\nCalled from file 
\"storage_impl.ml\", line 445, characters 14-77\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [error|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|VBD.plug R:6a9fba3a1166|storage_access] Re-raising as 
SR_BACKEND_FAILURE [ non-zero exit; ; Traceback (most recent call 
last):#012  File "/opt/xensource/sm/NFSSR", line 278, in <module>#012    
SRCommand.run(NFSSR, DRIVER_INFO)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 344, in run#012    ret = 
cmd.run(sr)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 110, in 
run#012    return self._run_locked(sr)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 159, in _run_locked#012    rv = 
self._run(sr, target)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 
263, in _run#012    writable, caching_params)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 1486, in activate#012    if 
self._activate_locked(sr_uuid, vdi_uuid, options):#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper#012    ret = op(self, 
*args)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1522, in 
_activate_locked#012    dev_path = self._activate(sr_uuid, vdi_uuid, 
options)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1560, in 
_activate#012 self._get_pool_config(sr_uuid).get("mem-pool-size"))#012  
File "/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate#012 
tapdisk = Tapdisk.find_by_path(phy_path)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path#012 return 
cls.find(path=path)#012  File "/usr/lib/xapi/sm/blktap2.py", line 678, 
in find#012    found = list(cls.list(**args))#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list#012    for row in 
TapCtl.list(**args):#012  File "/usr/lib/xapi/sm/blktap2.py", line 118, 
in loop#012    return f(*__t, **__d)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list#012    if e.status in 
transient:#012  File "/usr/lib/xapi/sm/blktap2.py", line 162, in 
__getattr__#012    return object.__getattribute__(self, 
key)#012AttributeError: 'CommandFailure' object has no attribute 
'status'#012 ]
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|VBD.plug R:6a9fba3a1166|xapi] Raised at file 
\"storage_access.ml\", line 971, characters 9-48\nCalled from file 
\"storage_access.ml\", line 967, characters 2-6\nRe-raised at file 
\"storage_access.ml\", line 972, characters 56-57\nCalled from file 
\"message_forwarding.ml\", line 233, characters 25-44\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|VBD.plug R:6a9fba3a1166|backtrace] Raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"message_forwarding.ml\", line 3502, characters 3-194\nCalled from file 
\"rbac.ml\", line 227, characters 16-23\n
Dec 30 06:51:38 g6xentest xapi: 
[20141230T05:51:38.465Z|audit|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|VBD.plug R:6a9fba3a1166|audit] 
('trackid=ca1e0e02f1c6f9fcc980368e710d8c8b' 'LOCAL_SUPERUSER' 
'OpaqueRef:bc001d57-65ce-8b2b-74b4-cd71976272c0' 'ALLOWED' 
'ERROR:SR_BACKEND_FAILURE: [ non-zero exit; ; Traceback (most recent 
call last):   File \"/opt/xensource/sm/NFSSR\", line 278, in 
<module>     SRCommand.run(NFSSR, DRIVER_INFO)   File 
\"/usr/lib/xapi/sm/SRCommand.py\", line 344, in run     ret = 
cmd.run(sr)   File \"/usr/lib/xapi/sm/SRCommand.py\", line 110, in 
run     return self._run_locked(sr)   File 
\"/usr/lib/xapi/sm/SRCommand.py\", line 159, in _run_locked     rv = 
self._run(sr, target)   File \"/usr/lib/xapi/sm/SRCommand.py\", line 
263, in _run     writable, caching_params)   File 
\"/usr/lib/xapi/sm/blktap2.py\", line 1486, in activate     if 
self._activate_locked(sr_uuid, vdi_uuid, options):   File 
\"/usr/lib/xapi/sm/blktap2.py\", line 83, in wrapper     ret = op(self, 
*args)   File \"/usr/lib/xapi/sm/blktap2.py\", line 1522, in 
_activate_locked     dev_path = self._activate(sr_uuid, vdi_uuid, 
options)   File \"/usr/lib/xapi/sm/blktap2.py\", line 1560, in _activate 
self._get_pool_config(sr_uuid).get(\"mem-pool-size\"))   File 
\"/usr/lib/xapi/sm/blktap2.py\", line 1243, in _tap_activate tapdisk = 
Tapdisk.find_by_path(phy_path)   File \"/usr/lib/xapi/sm/blktap2.py\", 
line 690, in find_by_path return cls.find(path=path)   File 
\"/usr/lib/xapi/sm/blktap2.py\", line 678, in find     found = 
list(cls.list(**args))   File \"/usr/lib/xapi/sm/blktap2.py\", line 653, 
in list     for row in TapCtl.list(**args):   File 
\"/usr/lib/xapi/sm/blktap2.py\", line 118, in loop     return f(*__t, 
**__d)   File \"/usr/lib/xapi/sm/blktap2.py\", line 348, in list     if 
e.status in transient:   File \"/usr/lib/xapi/sm/blktap2.py\", line 162, 
in __getattr__     return object.__getattribute__(self, key) 
AttributeError: \'CommandFailure\' object has no attribute \'status\'  
]' 'API' 'VBD.plug' (('self' '' '55716ecf-1a27-b265-6021-f21994a346df' 
'OpaqueRef:62934ed8-606c-9a27-ca66-625b42213fd1')))
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|VBD.plug R:6a9fba3a1166|backtrace] Raised at file 
\"rbac.ml\", line 236, characters 10-15\nCalled from file 
\"server_helpers.ml\", line 75, characters 11-41\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|VBD.plug R:6a9fba3a1166|dispatcher] 
Server_helpers.exec exception_handler: Got exception SR_BACKEND_FAILURE: 
[ non-zero exit; ; Traceback (most recent call last):#012  File 
"/opt/xensource/sm/NFSSR", line 278, in <module>#012    
SRCommand.run(NFSSR, DRIVER_INFO)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 344, in run#012    ret = 
cmd.run(sr)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 110, in 
run#012    return self._run_locked(sr)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 159, in _run_locked#012    rv = 
self._run(sr, target)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 
263, in _run#012    writable, caching_params)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 1486, in activate#012    if 
self._activate_locked(sr_uuid, vdi_uuid, options):#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper#012    ret = op(self, 
*args)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1522, in 
_activate_locked#012    dev_path = self._activate(sr_uuid, vdi_uuid, 
options)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1560, in 
_activate#012 self._get_pool_config(sr_uuid).get("mem-pool-size"))#012  
File "/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate#012 
tapdisk = Tapdisk.find_by_path(phy_path)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path#012 return 
cls.find(path=path)#012  File "/usr/lib/xapi/sm/blktap2.py", line 678, 
in find#012    found = list(cls.list(**args))#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list#012    for row in 
TapCtl.list(**args):#012  File "/usr/lib/xapi/sm/blktap2.py", line 118, 
in loop#012    return f(*__t, **__d)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list#012    if e.status in 
transient:#012  File "/usr/lib/xapi/sm/blktap2.py", line 162, in 
__getattr__#012    return object.__getattribute__(self, 
key)#012AttributeError: 'CommandFailure' object has no attribute 
'status'#012 ]
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|VBD.plug R:6a9fba3a1166|dispatcher] Raised at file 
\"rbac.ml\", line 236, characters 10-15\nCalled from file 
\"server_helpers.ml\", line 75, characters 11-41\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|VBD.plug R:6a9fba3a1166|backtrace] Raised at file 
\"rbac.ml\", line 236, characters 10-15\nCalled from file 
\"server_helpers.ml\", line 75, characters 11-41\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|VBD.plug R:6a9fba3a1166|xapi] Raised at file 
\"server_helpers.ml\", line 90, characters 14-15\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|dispatch:VBD.plug D:a065a8a06ea5|xapi] Raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"lib/debug.ml\", line 57, characters 17-20\nRe-raised at file 
\"lib/debug.ml\", line 62, characters 10-11\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4325 UNIX 
/var/lib/xcp/xapi|dispatch:VBD.plug D:a065a8a06ea5|backtrace] Raised at 
file \"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from 
file \"lib/debug.ml\", line 57, characters 17-20\nRe-raised at file 
\"lib/debug.ml\", line 62, characters 10-11\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\nRe-raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"server_helpers.ml\", line 136, characters 10-106\nCalled from file 
\"server.ml\", line 25047, characters 23-161\nCalled from file 
\"server_helpers.ml\", line 115, characters 4-7\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|xapi] Raised at file \"client.ml\", line 6, 
characters 37-75\nCalled from file \"client.ml\", line 18, characters 
55-110\nCalled from file \"client.ml\", line 10530, characters 
6-53\nCalled from file \"list.ml\", line 73, characters 12-15\nCalled 
from file \"attach_helpers.ml\", line 79, characters 7-787\nCalled from 
file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|mscgen] xapi=>xapi [label="session.slave_login"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4376 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.slave_login 
D:e29a1093437b created by task R:dbdb0ee5ae16
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4376 UNIX 
/var/lib/xcp/xapi|session.slave_login D:53f5a509153c|xapi] 
Session.create trackid=43bee4954daf50bb8c163bcd68702fd4 pool=true uname= 
originator= is_local_superuser=true auth_user_sid= 
parent=trackid=9834f5af41c964e225f24279aefe4e49
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4376 UNIX 
/var/lib/xcp/xapi|session.slave_login D:53f5a509153c|mscgen] xapi=>xapi 
[label="session.get_uuid"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4377 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid 
D:00c5bef3f954 created by task D:53f5a509153c
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|mscgen] xapi=>xapi [label="VBD.unplug"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:VBD.unplug 
D:28c1d1528ed1 created by task R:dbdb0ee5ae16
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|dispatch:VBD.unplug D:28c1d1528ed1|taskhelper] task 
VBD.unplug R:4dc35778c95d (uuid:3e1e7e97-ad3d-f6ea-74b0-b658f6a234c2) 
created (trackid=43bee4954daf50bb8c163bcd68702fd4) by task R:dbdb0ee5ae16
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|VBD.unplug R:4dc35778c95d|audit] VBD.unplug: VBD = 
'55716ecf-1a27-b265-6021-f21994a346df'
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|VBD.unplug R:4dc35778c95d|backtrace] Raised at file 
\"xapi_vbd_helpers.ml\", line 201, characters 33-72\nCalled from file 
\"message_forwarding.ml\", line 3430, characters 5-65\nCalled from file 
\"message_forwarding.ml\", line 283, characters 5-8\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|VBD.unplug R:4dc35778c95d|xapi] Caught exception while 
DEVICE_ALREADY_DETACHED: [ 
OpaqueRef:62934ed8-606c-9a27-ca66-625b42213fd1 ] in message forwarder: 
marking VBD for VBD.unplug
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|VBD.unplug R:4dc35778c95d|backtrace] Raised at file 
\"xapi_vbd_helpers.ml\", line 201, characters 33-72\nCalled from file 
\"message_forwarding.ml\", line 3430, characters 5-65\nCalled from file 
\"message_forwarding.ml\", line 283, characters 5-8\nRe-raised at file 
\"message_forwarding.ml\", line 286, characters 8-9\nCalled from file 
\"lib/threadext.ml\", line 20, characters 20-24\nRe-raised at file 
\"lib/threadext.ml\", line 20, characters 62-65\nCalled from file 
\"message_forwarding.ml\", line 149, characters 19-25\nCalled from file 
\"message_forwarding.ml\", line 3435, characters 3-87\nCalled from file 
\"message_forwarding.ml\", line 3511, characters 3-200\nCalled from file 
\"rbac.ml\", line 227, characters 16-23\n
Dec 30 06:51:38 g6xentest xapi: 
[20141230T05:51:38.539Z|audit|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|VBD.unplug R:4dc35778c95d|audit] 
('trackid=43bee4954daf50bb8c163bcd68702fd4' 'LOCAL_SUPERUSER' 
'OpaqueRef:bc001d57-65ce-8b2b-74b4-cd71976272c0' 'ALLOWED' 
'ERROR:DEVICE_ALREADY_DETACHED: [ 
OpaqueRef:62934ed8-606c-9a27-ca66-625b42213fd1 ]' 'API' 'VBD.unplug' 
(('self' '' '55716ecf-1a27-b265-6021-f21994a346df' 
'OpaqueRef:62934ed8-606c-9a27-ca66-625b42213fd1')))
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|VBD.unplug R:4dc35778c95d|backtrace] Raised at file 
\"rbac.ml\", line 236, characters 10-15\nCalled from file 
\"server_helpers.ml\", line 75, characters 11-41\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|VBD.unplug R:4dc35778c95d|dispatcher] 
Server_helpers.exec exception_handler: Got exception 
DEVICE_ALREADY_DETACHED: [ OpaqueRef:62934ed8-606c-9a27-ca66-625b42213fd1 ]
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|VBD.unplug R:4dc35778c95d|dispatcher] Raised at file 
\"rbac.ml\", line 236, characters 10-15\nCalled from file 
\"server_helpers.ml\", line 75, characters 11-41\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|VBD.unplug R:4dc35778c95d|backtrace] Raised at file 
\"rbac.ml\", line 236, characters 10-15\nCalled from file 
\"server_helpers.ml\", line 75, characters 11-41\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|VBD.unplug R:4dc35778c95d|xapi] Raised at file 
\"server_helpers.ml\", line 90, characters 14-15\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|dispatch:VBD.unplug D:28c1d1528ed1|xapi] Raised at 
file \"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from 
file \"lib/debug.ml\", line 57, characters 17-20\nRe-raised at file 
\"lib/debug.ml\", line 62, characters 10-11\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4378 UNIX 
/var/lib/xcp/xapi|dispatch:VBD.unplug D:28c1d1528ed1|backtrace] Raised 
at file \"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from 
file \"lib/debug.ml\", line 57, characters 17-20\nRe-raised at file 
\"lib/debug.ml\", line 62, characters 10-11\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\nRe-raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"server_helpers.ml\", line 136, characters 10-106\nCalled from file 
\"server.ml\", line 25068, characters 23-161\nCalled from file 
\"server_helpers.ml\", line 115, characters 4-7\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|xapi] safe_unplug caught DEVICE_ALREADY_DETACHED: 
this is safe to ignore
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|mscgen] xapi=>xapi [label="VBD.destroy"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4379 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:VBD.destroy 
D:d2a2ffe1ee4f created by task R:dbdb0ee5ae16
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4379 UNIX 
/var/lib/xcp/xapi|dispatch:VBD.destroy D:d2a2ffe1ee4f|taskhelper] task 
VBD.destroy R:76c8f8af8ec3 (uuid:2891c38f-8c95-4a84-01be-f3fb921b7eb8) 
created (trackid=43bee4954daf50bb8c163bcd68702fd4) by task R:dbdb0ee5ae16
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4379 UNIX 
/var/lib/xcp/xapi|VBD.destroy R:76c8f8af8ec3|audit] VBD.destroy: VBD = 
'55716ecf-1a27-b265-6021-f21994a346df'
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4379 UNIX 
/var/lib/xcp/xapi|VBD.destroy R:76c8f8af8ec3|xapi] VBD.destroy (uuid = 
55716ecf-1a27-b265-6021-f21994a346df; ref = 
OpaqueRef:62934ed8-606c-9a27-ca66-625b42213fd1)
Dec 30 06:51:38 g6xentest xapi: 
[20141230T05:51:38.583Z|audit|g6xentest|4379 UNIX 
/var/lib/xcp/xapi|VBD.destroy R:76c8f8af8ec3|audit] 
('trackid=43bee4954daf50bb8c163bcd68702fd4' 'LOCAL_SUPERUSER' 
'OpaqueRef:bc001d57-65ce-8b2b-74b4-cd71976272c0' 'ALLOWED' 'OK' 'API' 
'VBD.destroy' (('self' '' '55716ecf-1a27-b265-6021-f21994a346df' 
'OpaqueRef:62934ed8-606c-9a27-ca66-625b42213fd1') ('VDI' 'XOA System' 
'cae30e9b-ebd4-4450-8ce0-9f4035ca62f1' 
'OpaqueRef:0b132ca6-b8e7-c11f-6fff-dec08432cafe')))
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|mscgen] xapi=>xapi [label="session.logout"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4380 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.logout 
D:33d121ae703c created by task R:dbdb0ee5ae16
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4380 UNIX 
/var/lib/xcp/xapi|session.logout D:b68a5fb9f585|xapi] Session.destroy 
trackid=43bee4954daf50bb8c163bcd68702fd4
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|backtrace] Raised at file \"lib/pervasiveext.ml\", 
line 26, characters 22-25\nCalled from file \"list.ml\", line 73, 
characters 12-15\nCalled from file \"stream_vdi.ml\", line 58, 
characters 2-32\nCalled from file \"import.ml\", line 1493, characters 
36-124\n
Dec 30 06:51:38 g6xentest xapi: [error|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|import] Caught exception during import: 
SR_BACKEND_FAILURE: [ non-zero exit; ; Traceback (most recent call 
last):#012  File "/opt/xensource/sm/NFSSR", line 278, in <module>#012    
SRCommand.run(NFSSR, DRIVER_INFO)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 344, in run#012    ret = 
cmd.run(sr)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 110, in 
run#012    return self._run_locked(sr)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 159, in _run_locked#012    rv = 
self._run(sr, target)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 
263, in _run#012    writable, caching_params)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 1486, in activate#012    if 
self._activate_locked(sr_uuid, vdi_uuid, options):#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper#012    ret = op(self, 
*args)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1522, in 
_activate_locked#012    dev_path = self._activate(sr_uuid, vdi_uuid, 
options)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1560, in 
_activate#012 self._get_pool_config(sr_uuid).get("mem-pool-size"))#012  
File "/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate#012 
tapdisk = Tapdisk.find_by_path(phy_path)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path#012 return 
cls.find(path=path)#012  File "/usr/lib/xapi/sm/blktap2.py", line 678, 
in find#012    found = list(cls.list(**args))#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list#012    for row in 
TapCtl.list(**args):#012  File "/usr/lib/xapi/sm/blktap2.py", line 118, 
in loop#012    return f(*__t, **__d)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list#012    if e.status in 
transient:#012  File "/usr/lib/xapi/sm/blktap2.py", line 162, in 
__getattr__#012    return object.__getattribute__(self, 
key)#012AttributeError: 'CommandFailure' object has no attribute 
'status'#012 ]
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|backtrace] Raised at file \"lib/pervasiveext.ml\", 
line 26, characters 22-25\nCalled from file \"list.ml\", line 73, 
characters 12-15\nCalled from file \"stream_vdi.ml\", line 58, 
characters 2-32\nCalled from file \"import.ml\", line 1493, characters 
36-124\n
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|import] Cleaning up after import failure: 
SR_BACKEND_FAILURE: [ non-zero exit; ; Traceback (most recent call 
last):#012  File "/opt/xensource/sm/NFSSR", line 278, in <module>#012    
SRCommand.run(NFSSR, DRIVER_INFO)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 344, in run#012    ret = 
cmd.run(sr)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 110, in 
run#012    return self._run_locked(sr)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 159, in _run_locked#012    rv = 
self._run(sr, target)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 
263, in _run#012    writable, caching_params)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 1486, in activate#012    if 
self._activate_locked(sr_uuid, vdi_uuid, options):#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper#012    ret = op(self, 
*args)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1522, in 
_activate_locked#012    dev_path = self._activate(sr_uuid, vdi_uuid, 
options)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1560, in 
_activate#012 self._get_pool_config(sr_uuid).get("mem-pool-size"))#012  
File "/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate#012 
tapdisk = Tapdisk.find_by_path(phy_path)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path#012 return 
cls.find(path=path)#012  File "/usr/lib/xapi/sm/blktap2.py", line 678, 
in find#012    found = list(cls.list(**args))#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list#012    for row in 
TapCtl.list(**args):#012  File "/usr/lib/xapi/sm/blktap2.py", line 118, 
in loop#012    return f(*__t, **__d)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list#012    if e.status in 
transient:#012  File "/usr/lib/xapi/sm/blktap2.py", line 162, in 
__getattr__#012    return object.__getattribute__(self, 
key)#012AttributeError: 'CommandFailure' object has no attribute 
'status'#012 ]
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import (cleanup) R:09dd9c768450|mscgen] xapi=>xapi 
[label="session.slave_login"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4381 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.slave_login 
D:2d6fe045e67d created by task R:09dd9c768450
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4381 UNIX 
/var/lib/xcp/xapi|session.slave_login D:095689d3702c|xapi] 
Session.create trackid=ef3cd4745f1b43b77a99acfe3afbae8f pool=true uname= 
originator= is_local_superuser=true auth_user_sid= 
parent=trackid=9834f5af41c964e225f24279aefe4e49
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4381 UNIX 
/var/lib/xcp/xapi|session.slave_login D:095689d3702c|mscgen] xapi=>xapi 
[label="session.get_uuid"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4382 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid 
D:66485c08ca4a created by task D:095689d3702c
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import (cleanup) R:09dd9c768450|mscgen] xapi=>xapi 
[label="VIF.destroy"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4383 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:VIF.destroy 
D:4b7119580895 created by task R:09dd9c768450
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4383 UNIX 
/var/lib/xcp/xapi|dispatch:VIF.destroy D:4b7119580895|taskhelper] task 
VIF.destroy R:d4bf2c6c0049 (uuid:213cdb59-8ba7-3029-35e1-a5aec5b715a0) 
created (trackid=ef3cd4745f1b43b77a99acfe3afbae8f) by task R:09dd9c768450
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4383 UNIX 
/var/lib/xcp/xapi|VIF.destroy R:d4bf2c6c0049|audit] VIF.destroy: VIF = 
'c40d19c8-1964-eb04-e572-344b74bb7701'
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4383 UNIX 
/var/lib/xcp/xapi|VIF.destroy R:d4bf2c6c0049|xapi] VIF.destroy
Dec 30 06:51:38 g6xentest xapi: 
[20141230T05:51:38.628Z|audit|g6xentest|4383 UNIX 
/var/lib/xcp/xapi|VIF.destroy R:d4bf2c6c0049|audit] 
('trackid=ef3cd4745f1b43b77a99acfe3afbae8f' 'LOCAL_SUPERUSER' 
'OpaqueRef:bc001d57-65ce-8b2b-74b4-cd71976272c0' 'ALLOWED' 'OK' 'API' 
'VIF.destroy' (('self' '' 'c40d19c8-1964-eb04-e572-344b74bb7701' 
'OpaqueRef:55e3990c-9eb4-1d2f-c34d-17851cb87df8')))
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|89|xapi events 
D:b6497e2a8b15|xenops] Event on VM be764dde-ba6d-48f5-bdc2-8ae87ad74559; 
resident_here = true
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import (cleanup) R:09dd9c768450|mscgen] xapi=>xapi 
[label="VBD.destroy"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4384 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:VBD.destroy 
D:206e6030d791 created by task R:09dd9c768450
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4384 UNIX 
/var/lib/xcp/xapi|dispatch:VBD.destroy D:206e6030d791|taskhelper] task 
VBD.destroy R:0a7f5e962c4a (uuid:c2e5bc64-b3d2-f2fa-27e7-f8dc235281c8) 
created (trackid=ef3cd4745f1b43b77a99acfe3afbae8f) by task R:09dd9c768450
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4384 UNIX 
/var/lib/xcp/xapi|VBD.destroy R:0a7f5e962c4a|audit] VBD.destroy: VBD = 
'35870bde-8ccb-d001-3999-c6acad0c2b0a'
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4384 UNIX 
/var/lib/xcp/xapi|VBD.destroy R:0a7f5e962c4a|xapi] VBD.destroy (uuid = 
35870bde-8ccb-d001-3999-c6acad0c2b0a; ref = 
OpaqueRef:34bbcf5e-1c44-8a03-f1f2-0dc494b2846a)
Dec 30 06:51:38 g6xentest xapi: 
[20141230T05:51:38.671Z|audit|g6xentest|4384 UNIX 
/var/lib/xcp/xapi|VBD.destroy R:0a7f5e962c4a|audit] 
('trackid=ef3cd4745f1b43b77a99acfe3afbae8f' 'LOCAL_SUPERUSER' 
'OpaqueRef:bc001d57-65ce-8b2b-74b4-cd71976272c0' 'ALLOWED' 'OK' 'API' 
'VBD.destroy' (('self' '' '35870bde-8ccb-d001-3999-c6acad0c2b0a' 
'OpaqueRef:34bbcf5e-1c44-8a03-f1f2-0dc494b2846a') ('VDI' 'XOA System' 
'cae30e9b-ebd4-4450-8ce0-9f4035ca62f1' 
'OpaqueRef:0b132ca6-b8e7-c11f-6fff-dec08432cafe')))
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|89|xapi events 
D:b6497e2a8b15|mscgen] xapi=>xapi [label="event.from"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4385 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:event.from 
D:b10ddc2b5796 created by task D:b6497e2a8b15
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import (cleanup) R:09dd9c768450|mscgen] xapi=>xapi 
[label="VM.destroy"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4386 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:VM.destroy 
D:46b69d81a56f created by task R:09dd9c768450
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4386 UNIX 
/var/lib/xcp/xapi|dispatch:VM.destroy D:46b69d81a56f|taskhelper] task 
VM.destroy R:43577c42dd09 (uuid:a0b80bba-249a-4439-8424-1965efb19459) 
created (trackid=ef3cd4745f1b43b77a99acfe3afbae8f) by task R:09dd9c768450
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4386 UNIX 
/var/lib/xcp/xapi|VM.destroy R:43577c42dd09|audit] VM.destroy: VM = 
'3b16c2e7-08e7-3c0f-452d-458c3a008685 (XOA 3.6 Basic)'
Dec 30 06:51:38 g6xentest xcp-rrdd: [debug|g6xentest|1325 
unix_rpc||http] Request { frame = false; method = POST; uri = 
/var/lib/xcp/xcp-rrdd; query = [  ]; content_length = [ 266 ]; transfer 
encoding = ; version = 1.1; cookie = [ = ]; task = ; subtask_of = ; 
content-type = ; host = ; user_agent = /usr/sbin/xapi }
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4386 UNIX 
/var/lib/xcp/xapi|VM.destroy R:43577c42dd09|xapi] VM.destroy: deleting 
DB records
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4386 UNIX 
/var/lib/xcp/xapi|VM.destroy R:43577c42dd09|xapi] Raised at file 
\"db_cache_types.ml\", line 107, characters 27-69\nCalled from file 
\"db_cache_impl.ml\", line 240, characters 11-44\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:38 g6xentest xapi: 
[20141230T05:51:38.718Z|audit|g6xentest|4386 UNIX 
/var/lib/xcp/xapi|VM.destroy R:43577c42dd09|audit] 
('trackid=ef3cd4745f1b43b77a99acfe3afbae8f' 'LOCAL_SUPERUSER' 
'OpaqueRef:bc001d57-65ce-8b2b-74b4-cd71976272c0' 'ALLOWED' 'OK' 'API' 
'VM.destroy' (('self' 'XOA 3.6 Basic' 
'3b16c2e7-08e7-3c0f-452d-458c3a008685' 
'OpaqueRef:ec37ed87-e7c7-f67a-e0ae-8ba7079f4f84')))
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import (cleanup) R:09dd9c768450|mscgen] xapi=>xapi 
[label="VDI.destroy"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4387 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.destroy 
D:5395d7e29d84 created by task R:09dd9c768450
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4387 UNIX 
/var/lib/xcp/xapi|dispatch:VDI.destroy D:5395d7e29d84|taskhelper] task 
VDI.destroy R:eb73ba917080 (uuid:26ba88fa-28f0-f22e-e418-ae460115c4af) 
created (trackid=ef3cd4745f1b43b77a99acfe3afbae8f) by task R:09dd9c768450
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4387 UNIX 
/var/lib/xcp/xapi|VDI.destroy R:eb73ba917080|audit] VDI.destroy: VDI = 
'cae30e9b-ebd4-4450-8ce0-9f4035ca62f1'
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4387 UNIX 
/var/lib/xcp/xapi|VDI.destroy R:eb73ba917080|xapi] Marking SR for 
VDI.destroy (task=OpaqueRef:eb73ba91-7080-dc74-8ec1-beca0146bf0d)
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4387 UNIX 
/var/lib/xcp/xapi|VDI.destroy R:eb73ba917080|storage_impl] VDI.destroy 
dbg:OpaqueRef:eb73ba91-7080-dc74-8ec1-beca0146bf0d 
sr:787b0874-8e8b-e7d8-8ba9-74f55ff2ea16 
vdi:cae30e9b-ebd4-4450-8ce0-9f4035ca62f1
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4387 UNIX 
/var/lib/xcp/xapi|VDI.destroy R:eb73ba917080|storage_impl] Attempting to 
destroy datapath dp:vbd/0/xvda sr:787b0874-8e8b-e7d8-8ba9-74f55ff2ea16 
vdi:cae30e9b-ebd4-4450-8ce0-9f4035ca62f1
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4388||dummytaskhelper] 
task VDI.detach D:136386636701 created by task R:eb73ba917080
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4388|VDI.detach 
D:136386636701|sm] SM nfs vdi_detach 
sr=OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39 
vdi=OpaqueRef:0b132ca6-b8e7-c11f-6fff-dec08432cafe
Dec 30 06:51:38 g6xentest xapi: [ info|g6xentest|4388|sm_exec 
D:8418c2e72cc7|xapi] Session.create 
trackid=1d82ccf0a6619430098a53727371f855 pool=false uname= originator= 
is_local_superuser=true auth_user_sid= 
parent=trackid=9834f5af41c964e225f24279aefe4e49
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4388|sm_exec 
D:8418c2e72cc7|mscgen] xapi=>xapi [label="session.get_uuid"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4389 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid 
D:e6cafcef1d61 created by task D:8418c2e72cc7
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4388|sm_exec 
D:8418c2e72cc7|mscgen] smapiv2=>smapiv1 [label="vdi_detach"];
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4390 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config 
D:33b0b7636dc9 created by task D:136386636701
Dec 30 06:51:38 g6xentest xapi: [debug|g6xentest|4391 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_sm_config 
D:d5f1c6df32eb created by task D:136386636701
Dec 30 06:51:38 g6xentest SM: [5322] lock: acquired 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:38 g6xentest SM: [5322] ['/usr/sbin/td-util', 'query', 
'vhd', '-vpf', 
'/var/run/sr-mount/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/cae30e9b-ebd4-4450-8ce0-9f4035ca62f1.vhd']
Dec 30 06:51:38 g6xentest SM: [5322]   pread SUCCESS
Dec 30 06:51:38 g6xentest SM: [5322] vdi_detach {'sr_uuid': 
'787b0874-8e8b-e7d8-8ba9-74f55ff2ea16', 'subtask_of': 
'DummyRef:|13638663-6701-1091-57d1-cba68304ef6a|VDI.detach', 'vdi_ref': 
'OpaqueRef:0b132ca6-b8e7-c11f-6fff-dec08432cafe', 'vdi_on_boot': 
'persist', 'args': [], 'vdi_location': 
'cae30e9b-ebd4-4450-8ce0-9f4035ca62f1', 'host_ref': 
'OpaqueRef:bc001d57-65ce-8b2b-74b4-cd71976272c0', 'session_ref': 
'OpaqueRef:8f6fd5ed-9b88-0ecd-d0dc-2652ebe54969', 'device_config': 
{'SRmaster': 'true', 'serverpath': '/mnt/zbiornik/testvol/', 'server': 
'127.0.0.1'}, 'command': 'vdi_detach', 'vdi_allow_caching': 'false', 
'sr_ref': 'OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39', 'vdi_uuid': 
'cae30e9b-ebd4-4450-8ce0-9f4035ca62f1'}
Dec 30 06:51:38 g6xentest SM: [5322] lock: closed 
/var/lock/sm/cae30e9b-ebd4-4450-8ce0-9f4035ca62f1/vdi
Dec 30 06:51:38 g6xentest SM: [5322] lock: released 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:38 g6xentest SM: [5322] lock: closed 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:39 g6xentest xapi: [ info|g6xentest|4388|sm_exec 
D:8418c2e72cc7|xapi] Session.destroy 
trackid=1d82ccf0a6619430098a53727371f855
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4387 UNIX 
/var/lib/xcp/xapi|VDI.destroy R:eb73ba917080|storage_impl] 
dbg:OpaqueRef:eb73ba91-7080-dc74-8ec1-beca0146bf0d dp:vbd/0/xvda 
sr:787b0874-8e8b-e7d8-8ba9-74f55ff2ea16 
vdi:cae30e9b-ebd4-4450-8ce0-9f4035ca62f1 superstate:detached
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4392||dummytaskhelper] 
task VDI.destroy D:11fda1a1522a created by task R:eb73ba917080
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4392|VDI.destroy 
D:11fda1a1522a|sm] SM nfs vdi_delete 
sr=OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39 
vdi=OpaqueRef:0b132ca6-b8e7-c11f-6fff-dec08432cafe
Dec 30 06:51:39 g6xentest xapi: [ info|g6xentest|4392|sm_exec 
D:4ba93da78635|xapi] Session.create 
trackid=7bce8892b6f84f2fe7dfbce532468abf pool=false uname= originator= 
is_local_superuser=true auth_user_sid= 
parent=trackid=9834f5af41c964e225f24279aefe4e49
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4392|sm_exec 
D:4ba93da78635|mscgen] xapi=>xapi [label="session.get_uuid"];
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4393 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid 
D:3add1a864c11 created by task D:4ba93da78635
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4392|sm_exec 
D:4ba93da78635|mscgen] smapiv2=>smapiv1 [label="vdi_delete"];
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4394 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config 
D:6242d3ea51db created by task D:11fda1a1522a
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4395 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_sm_config 
D:a271bf3e8ab6 created by task D:11fda1a1522a
Dec 30 06:51:39 g6xentest SM: [5330] lock: acquired 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:39 g6xentest SM: [5330] ['/usr/sbin/td-util', 'query', 
'vhd', '-vpf', 
'/var/run/sr-mount/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/cae30e9b-ebd4-4450-8ce0-9f4035ca62f1.vhd']
Dec 30 06:51:39 g6xentest SM: [5330]   pread SUCCESS
Dec 30 06:51:39 g6xentest SM: [5330] vdi_delete {'sr_uuid': 
'787b0874-8e8b-e7d8-8ba9-74f55ff2ea16', 'subtask_of': 
'DummyRef:|11fda1a1-522a-5c2e-fccc-c4b4a3a60600|VDI.destroy', 'vdi_ref': 
'OpaqueRef:0b132ca6-b8e7-c11f-6fff-dec08432cafe', 'vdi_on_boot': 
'persist', 'args': [], 'vdi_location': 
'cae30e9b-ebd4-4450-8ce0-9f4035ca62f1', 'host_ref': 
'OpaqueRef:bc001d57-65ce-8b2b-74b4-cd71976272c0', 'session_ref': 
'OpaqueRef:3a15ce97-0316-f04e-e93f-a7726f563e51', 'device_config': 
{'SRmaster': 'true', 'serverpath': '/mnt/zbiornik/testvol/', 'server': 
'127.0.0.1'}, 'command': 'vdi_delete', 'vdi_allow_caching': 'false', 
'sr_ref': 'OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39', 'vdi_uuid': 
'cae30e9b-ebd4-4450-8ce0-9f4035ca62f1'}
Dec 30 06:51:39 g6xentest SM: [5330] ['/usr/bin/vhd-util', 'set', 
'--debug', '-n', 
'/var/run/sr-mount/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/cae30e9b-ebd4-4450-8ce0-9f4035ca62f1.vhd', 
'-f', 'hidden', '-v', '1']
Dec 30 06:51:39 g6xentest SM: [5330]   pread SUCCESS
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4396 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_by_uuid 
D:559f707a1835 created by task D:11fda1a1522a
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4397 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.db_forget 
D:7294fcb274ef created by task D:11fda1a1522a
Dec 30 06:51:39 g6xentest xapi: [ info|g6xentest|4397 UNIX 
/var/lib/xcp/xapi|dispatch:VDI.db_forget D:7294fcb274ef|taskhelper] task 
VDI.db_forget R:721552054cc8 (uuid:b202374a-148c-5d3a-f816-26cee5011830) 
created (trackid=7bce8892b6f84f2fe7dfbce532468abf) by task D:11fda1a1522a
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4397 UNIX 
/var/lib/xcp/xapi|VDI.db_forget R:721552054cc8|xapi] db_forget 
uuid=cae30e9b-ebd4-4450-8ce0-9f4035ca62f1
Dec 30 06:51:39 g6xentest xapi: 
[20141230T05:51:39.307Z|audit|g6xentest|4397 UNIX 
/var/lib/xcp/xapi|VDI.db_forget R:721552054cc8|audit] 
('trackid=7bce8892b6f84f2fe7dfbce532468abf' 'LOCAL_SUPERUSER' 
'__sm__backend' 'ALLOWED' 'OK' 'API' 'VDI.db_forget' (('vdi' '' '' 
'OpaqueRef:0b132ca6-b8e7-c11f-6fff-dec08432cafe')))
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4398 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task 
dispatch:SR.get_virtual_allocation D:0a63c6106fc9 created by task 
D:11fda1a1522a
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4399 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_by_uuid 
D:7254c48a6b00 created by task D:11fda1a1522a
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4400 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task 
dispatch:SR.set_virtual_allocation D:1e78e2fea3dc created by task 
D:11fda1a1522a
Dec 30 06:51:39 g6xentest xapi: 
[20141230T05:51:39.341Z|audit|g6xentest|4400 UNIX 
/var/lib/xcp/xapi|SR.set_virtual_allocation D:ef31ec008c45|audit] 
('trackid=7bce8892b6f84f2fe7dfbce532468abf' 'LOCAL_SUPERUSER' 
'__sm__backend' 'ALLOWED' 'OK' 'API' 'SR.set_virtual_allocation' 
(('self' 'local nfs' '787b0874-8e8b-e7d8-8ba9-74f55ff2ea16' 
'OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39')))
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4401 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_size 
D:eeb42cfa9aeb created by task D:11fda1a1522a
Dec 30 06:51:39 g6xentest xapi: 
[20141230T05:51:39.345Z|audit|g6xentest|4401 UNIX 
/var/lib/xcp/xapi|SR.set_physical_size D:b58348f5367a|audit] 
('trackid=7bce8892b6f84f2fe7dfbce532468abf' 'LOCAL_SUPERUSER' 
'__sm__backend' 'ALLOWED' 'OK' 'API' 'SR.set_physical_size' (('self' 
'local nfs' '787b0874-8e8b-e7d8-8ba9-74f55ff2ea16' 
'OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39')))
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4402 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task 
dispatch:SR.set_physical_utilisation D:c50ae404e957 created by task 
D:11fda1a1522a
Dec 30 06:51:39 g6xentest xapi: 
[20141230T05:51:39.349Z|audit|g6xentest|4402 UNIX 
/var/lib/xcp/xapi|SR.set_physical_utilisation D:f9015de20301|audit] 
('trackid=7bce8892b6f84f2fe7dfbce532468abf' 'LOCAL_SUPERUSER' 
'__sm__backend' 'ALLOWED' 'OK' 'API' 'SR.set_physical_utilisation' 
(('self' 'local nfs' '787b0874-8e8b-e7d8-8ba9-74f55ff2ea16' 
'OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39')))
Dec 30 06:51:39 g6xentest SM: [5330] lock: tried lock 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/running, acquired: 
True (exists: True)
Dec 30 06:51:39 g6xentest SM: [5330] lock: released 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/running
Dec 30 06:51:39 g6xentest SM: [5330] Kicking GC
Dec 30 06:51:39 g6xentest SMGC: [5330] === SR 
787b0874-8e8b-e7d8-8ba9-74f55ff2ea16: gc ===
Dec 30 06:51:39 g6xentest SMGC: [5342] Will finish as PID [5343]
Dec 30 06:51:39 g6xentest SMGC: [5330] New PID [5342]
Dec 30 06:51:39 g6xentest SM: [5330] lock: closed 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/running
Dec 30 06:51:39 g6xentest SM: [5330] lock: released 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:39 g6xentest SM: [5330] lock: closed 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:39 g6xentest xapi: [ info|g6xentest|4403 UNIX 
/var/lib/xcp/xapi|session.login_with_password D:044f99cccf96|xapi] 
Session.create trackid=875c3e601a042db2714f9fb83af9fcd7 pool=false 
uname=root originator= is_local_superuser=true auth_user_sid= 
parent=trackid=9834f5af41c964e225f24279aefe4e49
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4403 UNIX 
/var/lib/xcp/xapi|session.login_with_password D:044f99cccf96|mscgen] 
xapi=>xapi [label="session.get_uuid"];
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4404 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid 
D:a8da81d425e8 created by task D:044f99cccf96
Dec 30 06:51:39 g6xentest SMGC: [5343] Found 0 cache files
Dec 30 06:51:39 g6xentest xapi: [ info|g6xentest|4392|sm_exec 
D:4ba93da78635|xapi] Session.destroy 
trackid=7bce8892b6f84f2fe7dfbce532468abf
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4387 UNIX 
/var/lib/xcp/xapi|VDI.destroy R:eb73ba917080|xapi] Unmarking SR after 
VDI.destroy (task=OpaqueRef:eb73ba91-7080-dc74-8ec1-beca0146bf0d)
Dec 30 06:51:39 g6xentest SM: [5343] lock: tried lock 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr, acquired: True 
(exists: True)
Dec 30 06:51:39 g6xentest xapi: 
[20141230T05:51:39.457Z|audit|g6xentest|4387 UNIX 
/var/lib/xcp/xapi|VDI.destroy R:eb73ba917080|audit] 
('trackid=ef3cd4745f1b43b77a99acfe3afbae8f' 'LOCAL_SUPERUSER' 
'OpaqueRef:bc001d57-65ce-8b2b-74b4-cd71976272c0' 'ALLOWED' 'OK' 'API' 
'VDI.destroy' (('self' 'XOA System' 
'cae30e9b-ebd4-4450-8ce0-9f4035ca62f1' 
'OpaqueRef:0b132ca6-b8e7-c11f-6fff-dec08432cafe')))
Dec 30 06:51:39 g6xentest SM: [5343] ['/usr/bin/vhd-util', 'scan', '-f', 
'-c', '-m', '/var/run/sr-mount/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/*.vhd']
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import (cleanup) R:09dd9c768450|mscgen] xapi=>xapi 
[label="session.logout"];
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4415 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.logout 
D:ed15a846833e created by task R:09dd9c768450
Dec 30 06:51:39 g6xentest xapi: [ info|g6xentest|4415 UNIX 
/var/lib/xcp/xapi|session.logout D:294449be0a58|xapi] Session.destroy 
trackid=ef3cd4745f1b43b77a99acfe3afbae8f
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|xapi] Raised at file \"import.ml\", line 1525, 
characters 20-21\nCalled from file \"lib/pervasiveext.ml\", line 22, 
characters 2-9\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|import] Closing pipes
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4306||xapi] Raised by 
primitive operation at file \"unix.ml\", line 252, characters 
7-34\nCalled from file \"lib/unixext.ml\", line 222, characters 
13-34\nCalled from file \"import.ml\", line 1273, characters 
12-53\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:39 g6xentest forkexecd: [error|g6xentest|0||forkexecd] 5217 
(/bin/nice -n 19 /usr/bin/ionice -c 3 /bin/gzip --decompress --stdout 
--force) exitted with code 1
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4306||xapi] Raised at 
file \"pervasives.ml\", line 20, characters 22-33\nCalled from file 
\"lib/pervasiveext.ml\", line 26, characters 4-14\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4306||xapi] Raised at 
file \"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from 
file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|backtrace] Raised at file \"import.ml\", line 
1525, characters 20-21\nCalled from file \"lib/pervasiveext.ml\", line 
22, characters 2-9\nRe-raised at file \"lib/pervasiveext.ml\", line 26, 
characters 22-25\nCalled from file \"import.ml\", line 1459, characters 
9-1023\nRe-raised at file \"import.ml\", line 1554, characters 
14-15\nCalled from file \"server_helpers.ml\", line 72, characters 11-23\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|dispatcher] Server_helpers.exec exception_handler: 
Got exception SR_BACKEND_FAILURE: [ non-zero exit; ; Traceback (most 
recent call last):#012  File "/opt/xensource/sm/NFSSR", line 278, in 
<module>#012 SRCommand.run(NFSSR, DRIVER_INFO)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 344, in run#012    ret = 
cmd.run(sr)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 110, in 
run#012    return self._run_locked(sr)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 159, in _run_locked#012    rv = 
self._run(sr, target)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 
263, in _run#012    writable, caching_params)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 1486, in activate#012    if 
self._activate_locked(sr_uuid, vdi_uuid, options):#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper#012    ret = op(self, 
*args)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1522, in 
_activate_locked#012    dev_path = self._activate(sr_uuid, vdi_uuid, 
options)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1560, in 
_activate#012 self._get_pool_config(sr_uuid).get("mem-pool-size"))#012  
File "/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate#012 
tapdisk = Tapdisk.find_by_path(phy_path)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path#012 return 
cls.find(path=path)#012  File "/usr/lib/xapi/sm/blktap2.py", line 678, 
in find#012    found = list(cls.list(**args))#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list#012    for row in 
TapCtl.list(**args):#012  File "/usr/lib/xapi/sm/blktap2.py", line 118, 
in loop#012    return f(*__t, **__d)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list#012    if e.status in 
transient:#012  File "/usr/lib/xapi/sm/blktap2.py", line 162, in 
__getattr__#012    return object.__getattribute__(self, 
key)#012AttributeError: 'CommandFailure' object has no attribute 
'status'#012 ]
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|dispatcher] Raised at file \"import.ml\", line 
1525, characters 20-21\nCalled from file \"lib/pervasiveext.ml\", line 
22, characters 2-9\nRe-raised at file \"lib/pervasiveext.ml\", line 26, 
characters 22-25\nCalled from file \"import.ml\", line 1459, characters 
9-1023\nRe-raised at file \"import.ml\", line 1554, characters 
14-15\nCalled from file \"server_helpers.ml\", line 72, characters 11-23\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|backtrace] Raised at file \"import.ml\", line 
1525, characters 20-21\nCalled from file \"lib/pervasiveext.ml\", line 
22, characters 2-9\nRe-raised at file \"lib/pervasiveext.ml\", line 26, 
characters 22-25\nCalled from file \"import.ml\", line 1459, characters 
9-1023\nRe-raised at file \"import.ml\", line 1554, characters 
14-15\nCalled from file \"server_helpers.ml\", line 72, characters 11-23\n
Dec 30 06:51:39 g6xentest SM: [5343]   pread SUCCESS
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|xapi] Raised at file \"server_helpers.ml\", line 
90, characters 14-15\nCalled from file \"lib/pervasiveext.ml\", line 22, 
characters 2-9\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET :::80|VM 
import R:dbdb0ee5ae16|taskhelper] forwarded task destroyed
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import D:aa0a28febf06|xapi] Raised at file 
\"server_helpers.ml\", line 90, characters 14-15\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\nRe-raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"lib/debug.ml\", line 57, characters 17-20\nRe-raised at file 
\"lib/debug.ml\", line 62, characters 10-11\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import D:aa0a28febf06|xapi] Raised at file 
\"server_helpers.ml\", line 90, characters 14-15\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\nRe-raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"lib/debug.ml\", line 57, characters 17-20\nRe-raised at file 
\"lib/debug.ml\", line 62, characters 10-11\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\nRe-raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import D:aa0a28febf06|xapi] Raised at file 
\"server_helpers.ml\", line 90, characters 14-15\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\nRe-raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"lib/debug.ml\", line 57, characters 17-20\nRe-raised at file 
\"lib/debug.ml\", line 62, characters 10-11\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\nRe-raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\nRe-raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"xapi_http.ml\", line 193, characters 4-810\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import D:aa0a28febf06|mscgen] xapi=>xapi [label="session.logout"];
Dec 30 06:51:39 g6xentest xapi: 
[20141230T05:51:39.615Z|audit|g6xentest|4300 UNIX 
/var/lib/xcp/xapi|event.unregister D:2bfc995fc493|audit] 
('trackid=09a9f582057ad27816e43a4d3c18bf1b' 'LOCAL_SUPERUSER' 'root' 
'ALLOWED' 'OK' 'API' 'event.unregister' ())
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4416 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.logout 
D:b676c5227f87 created by task D:aa0a28febf06
Dec 30 06:51:39 g6xentest xapi: [ info|g6xentest|4416 UNIX 
/var/lib/xcp/xapi|session.logout D:1dc7a29a8cdd|xapi] Session.destroy 
trackid=ca1e0e02f1c6f9fcc980368e710d8c8b
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import D:aa0a28febf06|backtrace] Raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"import.ml\", line 1387, characters 20-1023\nCalled from file 
\"server_helpers.ml\", line 72, characters 11-23\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import D:aa0a28febf06|dispatcher] Server_helpers.exec 
exception_handler: Got exception SR_BACKEND_FAILURE: [ non-zero exit; ; 
Traceback (most recent call last):#012  File "/opt/xensource/sm/NFSSR", 
line 278, in <module>#012 SRCommand.run(NFSSR, DRIVER_INFO)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 344, in run#012    ret = 
cmd.run(sr)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 110, in 
run#012    return self._run_locked(sr)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 159, in _run_locked#012    rv = 
self._run(sr, target)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 
263, in _run#012    writable, caching_params)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 1486, in activate#012    if 
self._activate_locked(sr_uuid, vdi_uuid, options):#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper#012    ret = op(self, 
*args)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1522, in 
_activate_locked#012    dev_path = self._activate(sr_uuid, vdi_uuid, 
options)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1560, in 
_activate#012 self._get_pool_config(sr_uuid).get("mem-pool-size"))#012  
File "/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate#012 
tapdisk = Tapdisk.find_by_path(phy_path)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path#012 return 
cls.find(path=path)#012  File "/usr/lib/xapi/sm/blktap2.py", line 678, 
in find#012    found = list(cls.list(**args))#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list#012    for row in 
TapCtl.list(**args):#012  File "/usr/lib/xapi/sm/blktap2.py", line 118, 
in loop#012    return f(*__t, **__d)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list#012    if e.status in 
transient:#012  File "/usr/lib/xapi/sm/blktap2.py", line 162, in 
__getattr__#012    return object.__getattribute__(self, 
key)#012AttributeError: 'CommandFailure' object has no attribute 
'status'#012 ]
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import D:aa0a28febf06|dispatcher] Raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"import.ml\", line 1387, characters 20-1023\nCalled from file 
\"server_helpers.ml\", line 72, characters 11-23\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import D:aa0a28febf06|backtrace] Raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"import.ml\", line 1387, characters 20-1023\nCalled from file 
\"server_helpers.ml\", line 72, characters 11-23\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80|VM.import D:aa0a28febf06|xapi] Raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"import.ml\", line 1387, characters 20-1023\nCalled from file 
\"server_helpers.ml\", line 72, characters 11-23\nRe-raised at file 
\"server_helpers.ml\", line 90, characters 14-15\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET :::80||xapi] 
Raised at file \"lib/pervasiveext.ml\", line 26, characters 
22-25\nCalled from file \"import.ml\", line 1387, characters 
20-1023\nCalled from file \"server_helpers.ml\", line 72, characters 
11-23\nRe-raised at file \"server_helpers.ml\", line 90, characters 
14-15\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 
2-9\nRe-raised at file \"lib/pervasiveext.ml\", line 26, characters 
22-25\nCalled from file \"lib/debug.ml\", line 57, characters 
17-20\nRe-raised at file \"lib/debug.ml\", line 62, characters 
10-11\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:39 g6xentest xapi: [error|g6xentest|4303 INET :::80||xapi] 
Unhandled Api_errors.Server_error(SR_BACKEND_FAILURE, [ non-zero exit; ; 
Traceback (most recent call last):#012  File "/opt/xensource/sm/NFSSR", 
line 278, in <module>#012 SRCommand.run(NFSSR, DRIVER_INFO)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 344, in run#012    ret = 
cmd.run(sr)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 110, in 
run#012    return self._run_locked(sr)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 159, in _run_locked#012    rv = 
self._run(sr, target)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 
263, in _run#012    writable, caching_params)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 1486, in activate#012    if 
self._activate_locked(sr_uuid, vdi_uuid, options):#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper#012    ret = op(self, 
*args)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1522, in 
_activate_locked#012    dev_path = self._activate(sr_uuid, vdi_uuid, 
options)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1560, in 
_activate#012 self._get_pool_config(sr_uuid).get("mem-pool-size"))#012  
File "/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate#012 
tapdisk = Tapdisk.find_by_path(phy_path)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path#012 return 
cls.find(path=path)#012  File "/usr/lib/xapi/sm/blktap2.py", line 678, 
in find#012    found = list(cls.list(**args))#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list#012    for row in 
TapCtl.list(**args):#012  File "/usr/lib/xapi/sm/blktap2.py", line 118, 
in loop#012    return f(*__t, **__d)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list#012    if e.status in 
transient:#012  File "/usr/lib/xapi/sm/blktap2.py", line 162, in 
__getattr__#012    return object.__getattribute__(self, 
key)#012AttributeError: 'CommandFailure' object has no attribute 
'status'#012 ])
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4303 INET 
:::80||backtrace] Raised at file \"lib/pervasiveext.ml\", line 26, 
characters 22-25\nCalled from file \"import.ml\", line 1387, characters 
20-1023\nCalled from file \"server_helpers.ml\", line 72, characters 
11-23\nRe-raised at file \"server_helpers.ml\", line 90, characters 
14-15\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 
2-9\nRe-raised at file \"lib/pervasiveext.ml\", line 26, characters 
22-25\nCalled from file \"lib/debug.ml\", line 57, characters 
17-20\nRe-raised at file \"lib/debug.ml\", line 62, characters 
10-11\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 
2-9\nRe-raised at file \"lib/pervasiveext.ml\", line 26, characters 
22-25\nCalled from file \"xapi_http.ml\", line 295, characters 4-27\n
Dec 30 06:51:39 g6xentest SMGC: [5343] SR 787b ('local nfs') (1 VDIs in 
1 VHD trees):
Dec 30 06:51:39 g6xentest SMGC: [5343] *cae30e9b(8.000G/19.000K)
Dec 30 06:51:39 g6xentest SMGC: [5343]
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4300 UNIX 
/var/lib/xcp/xapi||cli] client-side reports failure
Dec 30 06:51:39 g6xentest SM: [5343] lock: released 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:39 g6xentest SM: [5343] lock: tried lock 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/running, acquired: 
True (exists: True)
Dec 30 06:51:39 g6xentest SM: [5343] lock: tried lock 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr, acquired: True 
(exists: True)
Dec 30 06:51:39 g6xentest SM: [5343] ['/usr/bin/vhd-util', 'scan', '-f', 
'-c', '-m', '/var/run/sr-mount/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/*.vhd']
Dec 30 06:51:39 g6xentest SM: [5343]   pread SUCCESS
Dec 30 06:51:39 g6xentest SMGC: [5343] SR 787b ('local nfs') (1 VDIs in 
1 VHD trees): no changes
Dec 30 06:51:39 g6xentest SM: [5343] lock: released 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:39 g6xentest SMGC: [5343] Found 1 VDIs for deletion:
Dec 30 06:51:39 g6xentest SMGC: [5343]   *cae30e9b(8.000G/19.000K)
Dec 30 06:51:39 g6xentest SMGC: [5343] Deleting unlinked VDI 
*cae30e9b(8.000G/19.000K)
Dec 30 06:51:39 g6xentest SM: [5343] lock: tried lock 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr, acquired: True 
(exists: True)
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4421 UNIX 
/var/lib/xcp/xapi|VDI.get_by_uuid D:e5402c78336c|backtrace] Raised at 
file \"db_cache_impl.ml\", line 198, characters 16-55\nCalled from file 
\"db_actions.ml\", line 13330, characters 27-61\nCalled from file 
\"rbac.ml\", line 227, characters 16-23\nRe-raised at file \"rbac.ml\", 
line 236, characters 10-15\nCalled from file \"server_helpers.ml\", line 
72, characters 11-23\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4421 UNIX 
/var/lib/xcp/xapi|VDI.get_by_uuid D:e5402c78336c|dispatcher] 
Server_helpers.exec exception_handler: Got exception UUID_INVALID: [ 
VDI; cae30e9b-ebd4-4450-8ce0-9f4035ca62f1 ]
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4421 UNIX 
/var/lib/xcp/xapi|VDI.get_by_uuid D:e5402c78336c|dispatcher] Raised at 
file \"db_cache_impl.ml\", line 198, characters 16-55\nCalled from file 
\"db_actions.ml\", line 13330, characters 27-61\nCalled from file 
\"rbac.ml\", line 227, characters 16-23\nRe-raised at file \"rbac.ml\", 
line 236, characters 10-15\nCalled from file \"server_helpers.ml\", line 
72, characters 11-23\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4421 UNIX 
/var/lib/xcp/xapi|VDI.get_by_uuid D:e5402c78336c|backtrace] Raised at 
file \"db_cache_impl.ml\", line 198, characters 16-55\nCalled from file 
\"db_actions.ml\", line 13330, characters 27-61\nCalled from file 
\"rbac.ml\", line 227, characters 16-23\nRe-raised at file \"rbac.ml\", 
line 236, characters 10-15\nCalled from file \"server_helpers.ml\", line 
72, characters 11-23\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4421 UNIX 
/var/lib/xcp/xapi|VDI.get_by_uuid D:e5402c78336c|xapi] Raised at file 
\"db_cache_impl.ml\", line 198, characters 16-55\nCalled from file 
\"db_actions.ml\", line 13330, characters 27-61\nCalled from file 
\"rbac.ml\", line 227, characters 16-23\nRe-raised at file \"rbac.ml\", 
line 236, characters 10-15\nCalled from file \"server_helpers.ml\", line 
72, characters 11-23\nRe-raised at file \"server_helpers.ml\", line 90, 
characters 14-15\nCalled from file \"lib/pervasiveext.ml\", line 22, 
characters 2-9\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4421 UNIX 
/var/lib/xcp/xapi|dispatch:VDI.get_by_uuid D:5a5b19c8596f|xapi] Raised 
at file \"db_cache_impl.ml\", line 198, characters 16-55\nCalled from 
file \"db_actions.ml\", line 13330, characters 27-61\nCalled from file 
\"rbac.ml\", line 227, characters 16-23\nRe-raised at file \"rbac.ml\", 
line 236, characters 10-15\nCalled from file \"server_helpers.ml\", line 
72, characters 11-23\nRe-raised at file \"server_helpers.ml\", line 90, 
characters 14-15\nCalled from file \"lib/pervasiveext.ml\", line 22, 
characters 2-9\nRe-raised at file \"lib/pervasiveext.ml\", line 26, 
characters 22-25\nCalled from file \"lib/debug.ml\", line 57, characters 
17-20\nRe-raised at file \"lib/debug.ml\", line 62, characters 
10-11\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4421 UNIX 
/var/lib/xcp/xapi|dispatch:VDI.get_by_uuid D:5a5b19c8596f|backtrace] 
Raised at file \"db_cache_impl.ml\", line 198, characters 16-55\nCalled 
from file \"db_actions.ml\", line 13330, characters 27-61\nCalled from 
file \"rbac.ml\", line 227, characters 16-23\nRe-raised at file 
\"rbac.ml\", line 236, characters 10-15\nCalled from file 
\"server_helpers.ml\", line 72, characters 11-23\nRe-raised at file 
\"server_helpers.ml\", line 90, characters 14-15\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\nRe-raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"lib/debug.ml\", line 57, characters 17-20\nRe-raised at file 
\"lib/debug.ml\", line 62, characters 10-11\nCalled from file 
\"lib/pervasiveext.ml\", line 22, characters 2-9\nRe-raised at file 
\"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file 
\"server_helpers.ml\", line 136, characters 10-106\nCalled from file 
\"server.ml\", line 22402, characters 23-150\nCalled from file 
\"server_helpers.ml\", line 115, characters 4-7\n
Dec 30 06:51:39 g6xentest SM: [5343] lock: released 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:39 g6xentest SM: [5343] lock: unlinking lock file 
/var/lock/sm/cae30e9b-ebd4-4450-8ce0-9f4035ca62f1/vdi
Dec 30 06:51:39 g6xentest SM: [5343] lock: removing lock dir 
/var/lock/sm/cae30e9b-ebd4-4450-8ce0-9f4035ca62f1
Dec 30 06:51:39 g6xentest SMGC: [5343] Starting asynch srUpdate for SR 
787b0874-8e8b-e7d8-8ba9-74f55ff2ea16
Dec 30 06:51:39 g6xentest xapi: [ info|g6xentest|4424|Async.SR.update 
R:8bf29b029ab9|dispatcher] spawning a new thread to handle the current 
task (trackid=875c3e601a042db2714f9fb83af9fcd7)
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4424|Async.SR.update 
R:8bf29b029ab9|audit] SR.update: SR = 
'787b0874-8e8b-e7d8-8ba9-74f55ff2ea16 (local nfs)'
Dec 30 06:51:39 g6xentest xapi: [ info|g6xentest|4424|Async.SR.update 
R:8bf29b029ab9|storage_impl] SR.stat 
dbg:OpaqueRef:8bf29b02-9ab9-3f8e-7f95-471dc322cf9f 
sr:787b0874-8e8b-e7d8-8ba9-74f55ff2ea16
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4426||dummytaskhelper] 
task SR.stat D:04483281650a created by task R:8bf29b029ab9
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4426|SR.stat 
D:04483281650a|sm] SM nfs sr_update 
sr=OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39
Dec 30 06:51:39 g6xentest xapi: [ info|g6xentest|4426|sm_exec 
D:de69815603af|xapi] Session.create 
trackid=c1839615d7fc71e0107d18c1c4397bdf pool=false uname= originator= 
is_local_superuser=true auth_user_sid= 
parent=trackid=9834f5af41c964e225f24279aefe4e49
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4426|sm_exec 
D:de69815603af|mscgen] xapi=>xapi [label="session.get_uuid"];
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4427 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid 
D:93d48568da8c created by task D:de69815603af
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4426|sm_exec 
D:de69815603af|mscgen] smapiv2=>smapiv1 [label="sr_update"];
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4428 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config 
D:bcccb75cca6c created by task D:04483281650a
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4429 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_sm_config 
D:f0493eefc730 created by task D:04483281650a
Dec 30 06:51:39 g6xentest SM: [5373] sr_update {'sr_uuid': 
'787b0874-8e8b-e7d8-8ba9-74f55ff2ea16', 'subtask_of': 
'DummyRef:|04483281-650a-7f1b-5e03-2a8cac209cf5|SR.stat', 'args': [], 
'host_ref': 'OpaqueRef:bc001d57-65ce-8b2b-74b4-cd71976272c0', 
'session_ref': 'OpaqueRef:375f5540-70a4-4040-4b08-fe070fc28285', 
'device_config': {'SRmaster': 'true', 'serverpath': 
'/mnt/zbiornik/testvol/', 'server': '127.0.0.1'}, 'command': 
'sr_update', 'sr_ref': 'OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39'}
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4430 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task 
dispatch:SR.get_virtual_allocation D:d031ec9841d0 created by task 
D:04483281650a
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4431 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_by_uuid 
D:e101c6ef40f7 created by task D:04483281650a
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4432 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task 
dispatch:SR.set_virtual_allocation D:53c96ed79ea0 created by task 
D:04483281650a
Dec 30 06:51:39 g6xentest xapi: 
[20141230T05:51:39.960Z|audit|g6xentest|4432 UNIX 
/var/lib/xcp/xapi|SR.set_virtual_allocation D:c63db6de10a8|audit] 
('trackid=c1839615d7fc71e0107d18c1c4397bdf' 'LOCAL_SUPERUSER' 
'__sm__backend' 'ALLOWED' 'OK' 'API' 'SR.set_virtual_allocation' 
(('self' 'local nfs' '787b0874-8e8b-e7d8-8ba9-74f55ff2ea16' 
'OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39')))
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4433 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_size 
D:cc05c69dd818 created by task D:04483281650a
Dec 30 06:51:39 g6xentest xapi: 
[20141230T05:51:39.964Z|audit|g6xentest|4433 UNIX 
/var/lib/xcp/xapi|SR.set_physical_size D:25e58ef3eb06|audit] 
('trackid=c1839615d7fc71e0107d18c1c4397bdf' 'LOCAL_SUPERUSER' 
'__sm__backend' 'ALLOWED' 'OK' 'API' 'SR.set_physical_size' (('self' 
'local nfs' '787b0874-8e8b-e7d8-8ba9-74f55ff2ea16' 
'OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39')))
Dec 30 06:51:39 g6xentest xapi: [debug|g6xentest|4434 UNIX 
/var/lib/xcp/xapi||dummytaskhelper] task 
dispatch:SR.set_physical_utilisation D:049eb44a11d2 created by task 
D:04483281650a
Dec 30 06:51:39 g6xentest xapi: 
[20141230T05:51:39.970Z|audit|g6xentest|4434 UNIX 
/var/lib/xcp/xapi|SR.set_physical_utilisation D:ae38bbd06d6d|audit] 
('trackid=c1839615d7fc71e0107d18c1c4397bdf' 'LOCAL_SUPERUSER' 
'__sm__backend' 'ALLOWED' 'OK' 'API' 'SR.set_physical_utilisation' 
(('self' 'local nfs' '787b0874-8e8b-e7d8-8ba9-74f55ff2ea16' 
'OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39')))
Dec 30 06:51:39 g6xentest SM: [5373] lock: closed 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:39 g6xentest xapi: [ info|g6xentest|4426|sm_exec 
D:de69815603af|xapi] Session.destroy 
trackid=c1839615d7fc71e0107d18c1c4397bdf
Dec 30 06:51:39 g6xentest xapi: 
[20141230T05:51:39.991Z|audit|g6xentest|4424|Async.SR.update 
R:8bf29b029ab9|audit] ('trackid=875c3e601a042db2714f9fb83af9fcd7' 
'LOCAL_SUPERUSER' 'root' 'ALLOWED' 'OK' 'API' 'SR.update' (('sr' 'local 
nfs' '787b0874-8e8b-e7d8-8ba9-74f55ff2ea16' 
'OpaqueRef:521231d6-df3b-790b-1e22-b344a83d9d39')))
Dec 30 06:51:40 g6xentest xapi: [debug|g6xentest|4300 UNIX 
/var/lib/xcp/xapi||xapi] Raised at file \"cli_util.ml\", line 134, 
characters 10-67\nCalled from file \"lib/pervasiveext.ml\", line 22, 
characters 2-9\n
Dec 30 06:51:40 g6xentest xapi: 
[20141230T05:51:40.644Z|audit|g6xentest|4300 UNIX 
/var/lib/xcp/xapi|task.destroy D:afc218562a1b|audit] 
('trackid=09a9f582057ad27816e43a4d3c18bf1b' 'LOCAL_SUPERUSER' 'root' 
'ALLOWED' 'OK' 'API' 'task.destroy' (('self' 'VM import' 
'70859ad6-0420-baa8-17ac-759cb01acde3' 
'OpaqueRef:dbdb0ee5-ae16-0f3d-0c0e-dcaf23a52a88')))
Dec 30 06:51:40 g6xentest xapi: [debug|g6xentest|4300 UNIX 
/var/lib/xcp/xapi||xapi] Raised at file \"lib/pervasiveext.ml\", line 
26, characters 22-25\nCalled from file \"cli_operations.ml\", line 3283, 
characters 18-111\nCalled from file \"xapi_cli.ml\", line 119, 
characters 18-58\nCalled from file \"lib/pervasiveext.ml\", line 22, 
characters 2-9\n
Dec 30 06:51:40 g6xentest xapi: [ info|g6xentest|4300 UNIX 
/var/lib/xcp/xapi|session.logout D:a74027f037bd|xapi] Session.destroy 
trackid=09a9f582057ad27816e43a4d3c18bf1b
Dec 30 06:51:40 g6xentest xapi: [debug|g6xentest|4300 UNIX 
/var/lib/xcp/xapi||backtrace] Raised at file \"lib/pervasiveext.ml\", 
line 26, characters 22-25\nCalled from file \"xapi_cli.ml\", line 118, 
characters 2-139\nCalled from file \"xapi_cli.ml\", line 221, characters 
7-44\nCalled from file \"xapi_cli.ml\", line 273, characters 4-23\n
Dec 30 06:51:40 g6xentest xapi: [debug|g6xentest|4300 UNIX 
/var/lib/xcp/xapi||cli] Xapi_cli.exception_handler: Got exception 
SR_BACKEND_FAILURE: [ non-zero exit; ; Traceback (most recent call 
last):#012  File "/opt/xensource/sm/NFSSR", line 278, in <module>#012    
SRCommand.run(NFSSR, DRIVER_INFO)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 344, in run#012    ret = 
cmd.run(sr)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 110, in 
run#012    return self._run_locked(sr)#012  File 
"/usr/lib/xapi/sm/SRCommand.py", line 159, in _run_locked#012    rv = 
self._run(sr, target)#012  File "/usr/lib/xapi/sm/SRCommand.py", line 
263, in _run#012    writable, caching_params)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 1486, in activate#012    if 
self._activate_locked(sr_uuid, vdi_uuid, options):#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 83, in wrapper#012    ret = op(self, 
*args)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1522, in 
_activate_locked#012    dev_path = self._activate(sr_uuid, vdi_uuid, 
options)#012  File "/usr/lib/xapi/sm/blktap2.py", line 1560, in 
_activate#012 self._get_pool_config(sr_uuid).get("mem-pool-size"))#012  
File "/usr/lib/xapi/sm/blktap2.py", line 1243, in _tap_activate#012 
tapdisk = Tapdisk.find_by_path(phy_path)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 690, in find_by_path#012 return 
cls.find(path=path)#012  File "/usr/lib/xapi/sm/blktap2.py", line 678, 
in find#012    found = list(cls.list(**args))#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 653, in list#012    for row in 
TapCtl.list(**args):#012  File "/usr/lib/xapi/sm/blktap2.py", line 118, 
in loop#012    return f(*__t, **__d)#012  File 
"/usr/lib/xapi/sm/blktap2.py", line 348, in list#012    if e.status in 
transient:#012  File "/usr/lib/xapi/sm/blktap2.py", line 162, in 
__getattr__#012    return object.__getattribute__(self, 
key)#012AttributeError: 'CommandFailure' object has no attribute 
'status'#012 ]
Dec 30 06:51:40 g6xentest xapi: [debug|g6xentest|4300 UNIX 
/var/lib/xcp/xapi||cli] Raised at file \"lib/pervasiveext.ml\", line 26, 
characters 22-25\nCalled from file \"xapi_cli.ml\", line 118, characters 
2-139\nCalled from file \"xapi_cli.ml\", line 221, characters 
7-44\nCalled from file \"xapi_cli.ml\", line 273, characters 4-23\n
Dec 30 06:51:40 g6xentest SMGC: [5343] SR.update_asynch status changed 
to [success]
Dec 30 06:51:40 g6xentest SM: [5343] lock: released 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/running
Dec 30 06:51:40 g6xentest SM: [5343] lock: tried lock 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr, acquired: True 
(exists: True)
Dec 30 06:51:40 g6xentest SM: [5343] ['/usr/bin/vhd-util', 'scan', '-f', 
'-c', '-m', '/var/run/sr-mount/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/*.vhd']
Dec 30 06:51:40 g6xentest SM: [5343]   pread SUCCESS
Dec 30 06:51:40 g6xentest SMGC: [5343] SR 787b ('local nfs') (0 VDIs in 
0 VHD trees): showing only VHD trees that changed:
Dec 30 06:51:40 g6xentest SMGC: [5343] Tree 
cae30e9b-ebd4-4450-8ce0-9f4035ca62f1 gone
Dec 30 06:51:40 g6xentest SMGC: [5343]
Dec 30 06:51:40 g6xentest SM: [5343] lock: released 
/var/lock/sm/787b0874-8e8b-e7d8-8ba9-74f55ff2ea16/sr
Dec 30 06:51:40 g6xentest SMGC: [5343] No work, exiting
Dec 30 06:51:40 g6xentest SMGC: [5343] In cleanup
Dec 30 06:51:40 g6xentest SMGC: [5343] SR 787b ('local nfs') (0 VDIs in 
0 VHD trees): no changes
Dec 30 06:51:40 g6xentest xapi: [ info|g6xentest|4437 UNIX 
/var/lib/xcp/xapi|session.logout D:62093672901c|xapi] Session.destroy 
trackid=875c3e601a042db2714f9fb83af9fcd7
Dec 30 06:51:40 g6xentest xcp-rrdd: [debug|g6xentest|1326 
unix_rpc||http] Request { frame = false; method = POST; uri = 
/var/lib/xcp/xcp-rrdd; query = [  ]; content_length = [ 330 ]; transfer 
encoding = ; version = 1.1; cookie = [ = ]; task = ; subtask_of = ; 
content-type = ; host = ; user_agent = /usr/sbin/xapi }
Dec 30 06:51:41 g6xentest xcp-rrdd: [debug|g6xentest|0 
monitor|main|rrdd_stats] system stats: MemTotal: 8141572 KiB; MemFree: 
6811472 KiB; Buffered: 77624 KiB; Cached: 762040 KiB; SwapTotal: 0 KiB; 
SwapFree: 0 KiB
Dec 30 06:51:41 g6xentest xcp-rrdd: [debug|g6xentest|0 
monitor|main|rrdd_stats] Clock drift: 0
Dec 30 06:51:41 g6xentest xcp-rrdd: [debug|g6xentest|0 
monitor|main|rrdd_stats] xcp-rrdd stats (n = 1): size: 340776 KiB; rss: 
8112 KiB; data: 316292 KiB; stack: 136 KiB
Dec 30 06:51:41 g6xentest xcp-rrdd: [debug|g6xentest|0 
monitor|main|rrdd_stats] xapi stats (n = 2): size: 3322164 KiB; rss: 
46944 KiB; data: 3226392 KiB; stack: 272 KiB
Dec 30 06:51:41 g6xentest forkexecd: [error|g6xentest|0||forkexecd] 5394 
(/bin/pidof xenopsd) exitted with code 1
Dec 30 06:51:41 g6xentest xcp-rrdd: [debug|g6xentest|0 
monitor|main|rrdd_stats] xenopsd stats (n = 0): size: 0 KiB; rss: 0 KiB; 
data: 0 KiB; stack: 0 KiB
_______________________________________________
Xen-api mailing list
Xen-api@xxxxxxxxxxxxx
http://lists.xen.org/cgi-bin/mailman/listinfo/xen-api