[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

[Xen-API] VDI cloning doesn't work - FFS SR on ext4


  • To: Xen API mailing list <xen-api@xxxxxxxxxxxxx>
  • From: Dawid Kowalski <dkadds2@xxxxxxxxx>
  • Date: Wed, 7 Jan 2015 03:34:14 +0100
  • Delivery-date: Wed, 07 Jan 2015 02:34:34 +0000
  • List-id: User and development list for XCP and XAPI <xen-api.lists.xen.org>

Hi Guys,

I've spotted another error when trying to create VDI clone.

This is fully reproducible error for me.
Steps to reproduce
1. create VDI.
2. create clone of this VDI. This won't raise error and VDI clone will be created however some errors are raised in logs.
3. create another clone of original VDI. This raises error immediately.

Below is dump from logs.
Xen-version reports 4.4

VDI Creation
Jan 7 03:23:33 silver xapi: [ info|silver|12576 UNIX /var/lib/xcp/xapi||cli] xe vdi-create type=system virtual-size=100MiB sr-uuid=5b245868-00c6-dcd1-3cc9-c590f8f2c502 name-label=test2-baseline username=root password=(omitted)

Jan 7 03:23:33 silver xapi: [ info|silver|12576 UNIX /var/lib/xcp/xapi|session.login_with_password D:66b29e9cb04a|xapi] Session.create trackid=a65630899f0ae0cca0a460b088c50b82 pool=false uname=root originator=cli is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|session.login_with_password D:66b29e9cb04a|mscgen] xapi=>xapi [label="session.get_uuid"];
Jan 7 03:23:33 silver xapi: [debug|silver|12577 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:50008a23749d created by task D:66b29e9cb04a
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|audit] VDI.create: SR = '5b245868-00c6-dcd1-3cc9-c590f8f2c502 (/mnt/images/)'; name label = 'test2-baseline'
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|xapi] Marking SR for VDI.create (task=OpaqueRef:3cc2497e-f6a3-10ba-d6a7-bde299f98be9)
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:33 silver xapi: [ info|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|storage_impl] VDI.create dbg:OpaqueRef:3cc2497e-f6a3-10ba-d6a7-bde299f98be9 sr:5b245868-00c6-dcd1-3cc9-c590f8f2c502 vdi_info:{"vdi": "", "content_id": "", "name_label": "test2-baseline", "name_description": "", "ty": "system", "metadata_of_pool": "", "is_a_snapshot": false, "snapshot_time": "19700101T00:00:00Z", "snapshot_of": "", "read_only": false, "virtual_size": 104857600, "physical_utilisation": 0, "persistent": true, "sm_config": {}}
Jan 7 03:23:33 silver ffs: [debug|silver|585||ffs] VDI.create test2-baseline -> /mnt/images/test2-baseline (104857600)
Jan 7 03:23:33 silver ffs: [debug|silver|585||ffs] write >/mnt/images/test2-baseline.json
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|xapi] Creating VDI: {"vdi": "test2-baseline", "content_id": "", "name_label": "test2-baseline", "name_description": "", "ty": "system", "metadata_of_pool": "", "is_a_snapshot": false, "snapshot_time": "19700101T00:00:00Z", "snapshot_of": "", "read_only": false, "virtual_size": 104857600, "physical_utilisation": 0, "persistent": true, "sm_config": {"type": "vhd"}} (ref=OpaqueRef:88ace392-9da7-997c-406d-f3718048dc1d)
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|xapi] Unmarking SR after VDI.create (task=OpaqueRef:3cc2497e-f6a3-10ba-d6a7-bde299f98be9)
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:33 silver xapi: [debug|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:33 silver xapi: [20150107T02:23:33.126Z|audit|silver|12576 UNIX /var/lib/xcp/xapi|VDI.create R:3cc2497ef6a3|audit] ('trackid=a65630899f0ae0cca0a460b088c50b82' 'LOCAL_SUPERUSER' 'root' 'ALLOWED' 'OK' 'API' 'VDI.create' (('name_label' 'test2-baseline' '' '') ('name_description' '' '' '') ('SR' '/mnt/images/' '5b245868-00c6-dcd1-3cc9-c590f8f2c502' 'OpaqueRef:74b063fc-ad90-f2fa-11f6-faa6dcf1fc48')))
Jan 7 03:23:33 silver xapi: [ info|silver|12576 UNIX /var/lib/xcp/xapi|session.logout D:6f8318105b60|xapi] Session.destroy trackid=a65630899f0ae0cca0a460b088c50b82
Jan 7 03:23:35 silver xapi: [debug|silver|72|xapi events D:ed0f8a33ba92|mscgen] xapi=>xapi [label="event.from"];
Jan 7 03:23:35 silver xapi: [debug|silver|12582 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:event.from D:836df63939ae created by task D:ed0f8a33ba92

VDI creation done



VDI clone - first - no error returned, cloned vdi accessible

Jan 7 03:23:59 silver xapi: [ info|silver|12588 UNIX /var/lib/xcp/xapi||cli] xe vdi-clone uuid=8939cdc8-d43d-16a9-efd2-
0a85fa239d79 new-name-label=test1-clone2 username=root password=(omitted)
Jan 7 03:23:59 silver xapi: [ info|silver|12588 UNIX /var/lib/xcp/xapi|session.login_with_password D:1a0091535bc5|xapi] Session.create trackid=a6d169dce75de4fc39d36fc4641c6c85 pool=false uname=root originator=cli is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|session.login_with_password D:1a0091535bc5|mscgen] xapi=>xapi [label="session.get_uuid"];
Jan 7 03:23:59 silver xapi: [debug|silver|12589 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:1042a0bc8d93 created by task D:1a0091535bc5
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|audit] VDI.clone: VDI = '8939cdc8-d43d-16a9-efd2-0a85fa239d79'
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|xapi] Marking SR for VDI.clone (task=OpaqueRef:46cd7fc1-d409-3d79-03c1-35324f33d454)
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [ info|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|storage_impl] VDI.clone dbg:OpaqueRef:46cd7fc1-d409-3d79-03c1-35324f33d454 sr:5b245868-00c6-dcd1-3cc9-c590f8f2c502 vdi_info:{"vdi": "test2-baseline", "content_id": "", "name_label": "test2-baseline", "name_description": "", "ty": "user", "metadata_of_pool": "", "is_a_snapshot": false, "snapshot_time": "19700101T00:00:00Z", "snapshot_of": "", "read_only": false, "virtual_size": 0, "physical_utilisation": 0, "persistent": true, "sm_config": {}}
Jan 7 03:23:59 silver ffs: [ info|silver|586||ffs] VDI.clone test2-baseline (format = vhd)
Jan 7 03:23:59 silver ffs: [ info|silver|586||ffs] rename /mnt/images/test2-baseline -> /mnt/images/test2-baseline.1
Jan 7 03:23:59 silver ffs: [debug|silver|586||ffs] write >/mnt/images/test2-baseline.1.readme
Jan 7 03:23:59 silver ffs: [debug|silver|586||ffs] write >/mnt/images/test2-baseline.2.json
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|xapi] Creating VDI: {"vdi": "test2-baseline.2", "content_id": "", "name_label": "test2-baseline", "name_description": "", "ty": "user", "metadata_of_pool": "", "is_a_snapshot": false, "snapshot_time": "19700101T00:00:00Z", "snapshot_of": "", "read_only": false, "virtual_size": 104857600, "physical_utilisation": 0, "persistent": true, "sm_config": {"type": "vhd"}} (ref=OpaqueRef:c5d26a86-80c2-5ba9-ceeb-b24ad92d9560)
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|xapi] Unmarking SR after VDI.clone (task=OpaqueRef:46cd7fc1-d409-3d79-03c1-35324f33d454)
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [20150107T02:23:59.911Z|audit|silver|12588 UNIX /var/lib/xcp/xapi|VDI.clone R:46cd7fc1d409|audit] ('trackid=a6d169dce75de4fc39d36fc4641c6c85' 'LOCAL_SUPERUSER' 'root' 'ALLOWED' 'OK' 'API' 'VDI.clone' (('vdi' 'test2-baseline' '8939cdc8-d43d-16a9-efd2-0a85fa239d79' 'OpaqueRef:88ace392-9da7-997c-406d-f3718048dc1d')))
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.set_name_label R:2b46893cd111|audit] VDI.set_name_label: VDI = '516a803e-212c-6a0d-2773-c26155f733e9' name-label = 'test1-clone2'
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.set_name_label R:2b46893cd111|mscgen] xapi=>xapi [label="session.slave_login"];
Jan 7 03:23:59 silver xapi: [debug|silver|12593 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.slave_login D:9cd0f5fc8a8e created by task R:2b46893cd111
Jan 7 03:23:59 silver xapi: [ info|silver|12593 UNIX /var/lib/xcp/xapi|session.slave_login D:2227b3fb9172|xapi] Session.create trackid=7a0b31934f7b85e9aaa1ac15e1753c30 pool=true uname= originator= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
Jan 7 03:23:59 silver xapi: [debug|silver|12593 UNIX /var/lib/xcp/xapi|session.slave_login D:2227b3fb9172|mscgen] xapi=>xapi [label="session.get_uuid"];
Jan 7 03:23:59 silver xapi: [debug|silver|12594 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:c099ff1645ff created by task D:2227b3fb9172
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.set_name_label R:2b46893cd111|mscgen] xapi=>xapi [label="VDI.update"];
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.update D:dcb4124ac3cd created by task R:2b46893cd111
Jan 7 03:23:59 silver xapi: [ info|silver|12595 UNIX /var/lib/xcp/xapi|dispatch:VDI.update D:dcb4124ac3cd|taskhelper] task VDI.update R:403920685128 (uuid:ed8ff905-97b5-842b-1fd7-9e64f5f03d5e) created (trackid=7a0b31934f7b85e9aaa1ac15e1753c30) by task R:2b46893cd111
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|backtrace] Raised at file \"xapi_vdi.ml\", line 190, characters 26-57\nCalled from file \"message_forwarding.ml\", line 3122, characters 5-57\nCalled from file \"message_forwarding.ml\", line 283, characters 5-8\n
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|xapi] Caught exception while SR_OPERATION_NOT_SUPPORTED: [ OpaqueRef:74b063fc-ad90-f2fa-11f6-faa6dcf1fc48 ] in message forwarder: marking VDI for VDI.update
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|backtrace] Raised at file \"xapi_vdi.ml\", line 190, characters 26-57\nCalled from file \"message_forwarding.ml\", line 3122, characters 5-57\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/pervasiveext.ml\", line 33, characters 36-39\nRe-raised at file \"message_forwarding.ml\", line 3136, characters 12-13\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 3128, characters 3-369\nCalled from file \"rbac.ml\", line 227, characters 16-23\n
Jan 7 03:23:59 silver xapi: [20150107T02:23:59.927Z|audit|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|audit] ('trackid=7a0b31934f7b85e9aaa1ac15e1753c30' 'LOCAL_SUPERUSER' 'OpaqueRef:0d0d7bf7-4d03-afc4-4280-8724014224ba' 'ALLOWED' 'ERROR:SR_OPERATION_NOT_SUPPORTED: [ OpaqueRef:74b063fc-ad90-f2fa-11f6-faa6dcf1fc48 ]' 'API' 'VDI.update' (('vdi' 'test1-clone2' '516a803e-212c-6a0d-2773-c26155f733e9' 'OpaqueRef:c5d26a86-80c2-5ba9-ceeb-b24ad92d9560')))
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|backtrace] Raised at file \"rbac.ml\", line 236, characters 10-15\nCalled from file \"server_helpers.ml\", line 75, characters 11-41\n
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|dispatcher] Server_helpers.exec exception_handler: Got exception SR_OPERATION_NOT_SUPPORTED: [ OpaqueRef:74b063fc-ad90-f2fa-11f6-faa6dcf1fc48 ]
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|dispatcher] Raised at file \"rbac.ml\", line 236, characters 10-15\nCalled from file \"server_helpers.ml\", line 75, characters 11-41\n
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|backtrace] Raised at file \"rbac.ml\", line 236, characters 10-15\nCalled from file \"server_helpers.ml\", line 75, characters 11-41\n
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|VDI.update R:403920685128|xapi] Raised at file \"server_helpers.ml\", line 90, characters 14-15\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|dispatch:VDI.update D:dcb4124ac3cd|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
Jan 7 03:23:59 silver xapi: [debug|silver|12595 UNIX /var/lib/xcp/xapi|dispatch:VDI.update D:dcb4124ac3cd|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 23617, characters 23-161\nCalled from file \"server_helpers.ml\", line 115, characters 4-7\n
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.set_name_label R:2b46893cd111|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 10073, characters 6-54\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.set_name_label R:2b46893cd111|mscgen] xapi=>xapi [label="session.logout"];
Jan 7 03:23:59 silver xapi: [debug|silver|12596 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.logout D:6b02d1d8a37c created by task R:2b46893cd111
Jan 7 03:23:59 silver xapi: [ info|silver|12596 UNIX /var/lib/xcp/xapi|session.logout D:f1bbc661e534|xapi] Session.destroy trackid=7a0b31934f7b85e9aaa1ac15e1753c30
Jan 7 03:23:59 silver xapi: [debug|silver|12588 UNIX /var/lib/xcp/xapi|VDI.set_name_label R:2b46893cd111|xapi] Could not persist change to SR - caught Api_errors.Server_error("SR_OPERATION_NOT_SUPPORTED", _)
Jan 7 03:23:59 silver xapi: [20150107T02:23:59.934Z|audit|silver|12588 UNIX /var/lib/xcp/xapi|VDI.set_name_label R:2b46893cd111|audit] ('trackid=a6d169dce75de4fc39d36fc4641c6c85' 'LOCAL_SUPERUSER' 'root' 'ALLOWED' 'OK' 'API' 'VDI.set_name_label' (('self' 'test1-clone2' '516a803e-212c-6a0d-2773-c26155f733e9' 'OpaqueRef:c5d26a86-80c2-5ba9-ceeb-b24ad92d9560')))
Jan 7 03:23:59 silver xapi: [ info|silver|12588 UNIX /var/lib/xcp/xapi|session.logout D:598fbb9088a5|xapi] Session.destroy trackid=a6d169dce75de4fc39d36fc4641c6c85




VDI clone2 - raises error immediately
Jan 7 03:24:08 silver xapi: [ info|silver|12599 UNIX /var/lib/xcp/xapi||cli] xe vdi-clone uuid=8939cdc8-d43d-16a9-efd2-0a85fa239d79 new-name-label=test1-clone3 username=root password=(omitted)
Jan 7 03:24:08 silver xapi: [ info|silver|12599 UNIX /var/lib/xcp/xapi|session.login_with_password D:3b33410b84e5|xapi] Session.create trackid=d2c167def0dba6859dcf7905aa46a0de pool=false uname=root originator=cli is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|session.login_with_password D:3b33410b84e5|mscgen] xapi=>xapi [label="session.get_uuid"];
Jan 7 03:24:08 silver xapi: [debug|silver|12600 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:2c456342cf2a created by task D:3b33410b84e5
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|audit] VDI.clone: VDI = '8939cdc8-d43d-16a9-efd2-0a85fa239d79'
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|xapi] Marking SR for VDI.clone (task=OpaqueRef:04f93157-e64e-0e44-c05f-16d591c6cbed)
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [ info|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|storage_impl] VDI.clone dbg:OpaqueRef:04f93157-e64e-0e44-c05f-16d591c6cbed sr:5b245868-00c6-dcd1-3cc9-c590f8f2c502 vdi_info:{"vdi": "test2-baseline", "content_id": "", "name_label": "test2-baseline", "name_description": "", "ty": "user", "metadata_of_pool": "", "is_a_snapshot": false, "snapshot_time": "19700101T00:00:00Z", "snapshot_of": "", "read_only": false, "virtual_size": 0, "physical_utilisation": 0, "persistent": true, "sm_config": {}}
Jan 7 03:24:08 silver ffs: [ info|silver|587||ffs] VDI.clone test2-baseline (format = vhd)
Jan 7 03:24:08 silver ffs: [ info|silver|587||ffs] rename /mnt/images/test2-baseline -> /mnt/images/test2-baseline.3
Jan 7 03:24:08 silver ffs: [ info|silver|587||ffs] No .readme file containing child information for /mnt/images/test2-baseline.1
Jan 7 03:24:08 silver ffs: [error|silver|587||ffs] image node /mnt/images/test2-baseline.1 has no associated metadata -- I can't risk manipulating it
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|xapi] Called from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Jan 7 03:24:08 silver xapi: [error|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|storage_access] Re-raising as INTERNAL_ERROR [ Storage_interface.Internal_error("Failure(\"image metadata integrity check failed\")") ]
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|xapi] Raised at file \"storage_access.ml\", line 973, characters 9-87\nCalled from file \"message_forwarding.ml\", line 233, characters 25-44\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|xapi] Unmarking SR after VDI.clone (task=OpaqueRef:04f93157-e64e-0e44-c05f-16d591c6cbed)
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_VHD
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_RAW
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|smint] SM.feature: unknown feature FORMAT_QCOW2
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|backtrace] Raised at file \"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file \"rbac.ml\", line 227, characters 16-23\n
Jan 7 03:24:08 silver xapi: [20150107T02:24:08.754Z|audit|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|audit] ('trackid=d2c167def0dba6859dcf7905aa46a0de' 'LOCAL_SUPERUSER' 'root' 'ALLOWED' 'ERROR:INTERNAL_ERROR: [ Storage_interface.Internal_error(\"Failure(\\\"image metadata integrity check failed\\\")\") ]' 'API' 'VDI.clone' (('vdi' 'test2-baseline' '8939cdc8-d43d-16a9-efd2-0a85fa239d79' 'OpaqueRef:88ace392-9da7-997c-406d-f3718048dc1d')))
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|backtrace] Raised at file \"rbac.ml\", line 236, characters 10-15\nCalled from file \"server_helpers.ml\", line 75, characters 11-41\n
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|dispatcher] Server_helpers.exec exception_handler: Got exception INTERNAL_ERROR: [ Storage_interface.Internal_error("Failure(\"image metadata integrity check failed\")") ]
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|dispatcher] Raised at file \"rbac.ml\", line 236, characters 10-15\nCalled from file \"server_helpers.ml\", line 75, characters 11-41\n
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|backtrace] Raised at file \"rbac.ml\", line 236, characters 10-15\nCalled from file \"server_helpers.ml\", line 75, characters 11-41\n
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|VDI.clone R:04f93157e64e|xapi] Raised at file \"server_helpers.ml\", line 90, characters 14-15\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|dispatch:VDI.clone D:889a3e3e1ece|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
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi|dispatch:VDI.clone D:889a3e3e1ece|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 23417, characters 23-161\nCalled from file \"server_helpers.ml\", line 115, characters 4-7\n
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi||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 9978, characters 6-68\nCalled from file \"cli_operations.ml\", line 1173, characters 14-63\nCalled from file \"xapi_cli.ml\", line 112, characters 18-56\nCalled from file \"lib/pervasiveext.ml\", line 22, characters 2-9\n
Jan 7 03:24:08 silver xapi: [ info|silver|12599 UNIX /var/lib/xcp/xapi|session.logout D:401462039ed3|xapi] Session.destroy trackid=d2c167def0dba6859dcf7905aa46a0de
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi||backtrace] Raised at file \"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file \"xapi_cli.ml\", line 111, characters 2-138\nCalled from file \"xapi_cli.ml\", line 221, characters 7-44\nCalled from file \"xapi_cli.ml\", line 273, characters 4-23\n
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi||cli] Xapi_cli.exception_handler: Got exception INTERNAL_ERROR: [ Storage_interface.Internal_error("Failure(\"image metadata integrity check failed\")") ]
Jan 7 03:24:08 silver xapi: [debug|silver|12599 UNIX /var/lib/xcp/xapi||cli] Raised at file \"lib/pervasiveext.ml\", line 26, characters 22-25\nCalled from file \"xapi_cli.ml\", line 111, characters 2-138\nCalled from file \"xapi_cli.ml\", line 221, characters 7-44\nCalled from file \"xapi_cli.ml\", line 273, characters 4-23\n

Regards,
Dawid

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

 


Rackspace

Lists.xenproject.org is hosted with RackSpace, monitoring our
servers 24x7x365 and backed by RackSpace's Fanatical Support®.