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

[Dave Scott <Dave.Scott@xxxxxxxxxx>]

Hi,

> On 30 Dec 2014, at 14:44, David <dkadds2@xxxxxxxxx> wrote:
> 
> 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.

Do your path modifications look similar to Bobâs? (also cc:d in case he can 
spot something)

https://github.com/xenserver/buildroot/pull/580

Iâm suspicious because of one of the sections of the stack trace below:

> 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):

â this is where the SM plugin runs âtap-ctl listâ and parses the output. Could 
the path to this command be wrong?


> 
> 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.

I think using .vhd files directly on an ZFS mount via FileSR would be ideal. 
Once weâve figured out whatâs wrong with the shared blktap2.py code that should 
work.

Cheers,
Dave
> 
> 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

_______________________________________________
Xen-api mailing list
Xen-api@xxxxxxxxxxxxx
http://lists.xen.org/cgi-bin/mailman/listinfo/xen-api