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

[David]

Hi Dave,

Many thanks for quick reply! Really appreciated.
My comments inline below.
On 30/12/14 16:30, Dave Scott wrote:
Hi,

On 30 Dec 2014, at 14:44, David <dkad...@gmail.com> 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:
Yes, these are similar, I've modified paths in:
vhdutil.py - VHD_UTIL path
FileSR.py to skip "b" switch as it's not supported by current version, 
line 879 & 880 (commented out).
SR.py - TAPDISK_UTIL path
Above were quick and dirty inline changes as I wanted to move forward.

I'm happy to post diffs if required. I've gave up with FileSR as it gave 
me some further issues but I'm happy to revert back to FileSR and drop 
NFS if you guys could help me with issues :)

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?

This one exist for contrary, so no need to fix path. The error came from 
other issue as per dump.

BLKTAP2:<function _activate_locked at 0x7f611a801ed8>: EXCEPTION <type 
'exceptions.AttributeError'>, 'CommandFailure' object has no attribute 'status'

File exists:
root@g6xentest:/usr/lib/xapi/sm# ls -l /usr/lib/xapi/sm/blktap2.py
-rwxr-xr-x 1 root root 85718 Dec 22 02:52 /usr/lib/xapi/sm/blktap2.py

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.
Using FileSR did fail miserably at earlier stage. I'll post in a sec a 
new thread to cover this, just to not mix two in one and to not loose 
track on what's going on :)

Thanks,
Dawid
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@lists.xen.org
http://lists.xen.org/cgi-bin/mailman/listinfo/xen-api



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