Skip to content

Instantly share code, notes, and snippets.

Embed
What would you like to do?
failed to attach cinder volume
DEBU[0030] /VolumeDriver.Get host=unix:///var/run/libstorage/451292995.sock pluginResponse={Name:voltest Opts:map[]} time=1500447977914
DEBU[0030] inspecting volume host=unix:///var/run/libstorage/451292995.sock opts=map[] time=1500447977914 volumeName=voltest
INFO[0030] inspecting volume host=unix:///var/run/libstorage/451292995.sock integrationDriver=linux opts=map[] osDriver=linux service=cinder storageDriver=libstorage time=1500447977914 volumeName=voltest
DEBU[0030] waiting on executor lock host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447977914 txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] invoking executor cli args=[cinder localDevices quick] cmd=/var/lib/libstorage/lsx-linux host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447977914 txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] signalling executor lock host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447977993 txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] xli localdevices success host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447977993 txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
INFO[0030]
INFO[0030] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[0030] GET /volumes/cinder/voltest?attachments=0&byName HTTP/1.1
INFO[0030] Host: libstorage-server
INFO[0030] Libstorage-Instanceid: cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1
INFO[0030] Libstorage-Localdevices: cinder=
INFO[0030] Libstorage-Tx: txID=b707d597-3365-46c6-6879-141d9fcc1030, txCR=1500447977
INFO[0030]
INFO[0030] http request host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=schema-validator route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=storage-session-handler route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=auth-svc-handler route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=service-validator route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=transaction-handler route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=local-devices-handler route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=instanceIDs-handler route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=auth-global-handler route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=error-handler route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=transaction-handler route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=logging-handler route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=query-params-handler route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] query param host=unix:///var/run/libstorage/451292995.sock key=attachments len(value)=1 route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false value=[0]
DEBU[0030] query param host=unix:///var/run/libstorage/451292995.sock key=byName len(value)=1 route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false value=[]
DEBU[0030] http header Libstorage-Tx=txID=b707d597-3365-46c6-6879-141d9fcc1030, txCR=1500447977 host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false
DEBU[0030] skipping global auth handler; empty allow & deny lists host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447977994 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] http header Libstorage-Instanceid=[cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1] host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447977995 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] http header Libstorage-Localdevices=[cinder=] host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447977995 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] getting storage service host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg service=cinder time=1500447977995 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447977995 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] driver is not StorageDriverWithLogin host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447977995 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] getting task service host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447977995 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
INFO[0030] executing task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447977995 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] driver is not StorageDriverVolInspectByName host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447977996 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] getting task service host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447977996 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] skipping response schema validation; disabled host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447978199 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] task completed host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447978199 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
INFO[0030] @ - - [19/Jul/2017:15:06:18 +0800] "GET /volumes/cinder/voltest?attachments=0&byName HTTP/1.1" 200 153
INFO[0030]
INFO[0030] -------------------------- HTTP REQUEST (SERVER) --------------------------
INFO[0030] GET /volumes/cinder/voltest?attachments=0&byName HTTP/1.1
INFO[0030] Host: libstorage-server
INFO[0030] Accept-Encoding: gzip
INFO[0030] Libstorage-Instanceid: cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1
INFO[0030] Libstorage-Localdevices: cinder=
INFO[0030] Libstorage-Tx: txID=b707d597-3365-46c6-6879-141d9fcc1030, txCR=1500447977
INFO[0030] User-Agent: Go-http-client/1.1
INFO[0030]
INFO[0030] -------------------------- HTTP RESPONSE (SERVER) -------------------------
INFO[0030] Content-Type=application/json
INFO[0030]
INFO[0030] {
INFO[0030] "availabilityZone": "nova",
INFO[0030] "name": "voltest",
INFO[0030] "size": 1,
INFO[0030] "status": "available",
INFO[0030] "id": "54704d6a-c853-4bb1-b98c-8c08e33ee2c2",
INFO[0030] "type": ""
INFO[0030] }
INFO[0030]
INFO[0030] -------------------------- HTTP RESPONSE (CLIENT) -------------------------
INFO[0030] HTTP/1.1 200 OK
INFO[0030] Content-Length: 153
INFO[0030] Content-Type: application/json
INFO[0030] Date: Wed, 19 Jul 2017 07:06:18 GMT
INFO[0030] Libstorage-Servername: helix-bear-vg
INFO[0030]
INFO[0030] {
INFO[0030] "availabilityZone": "nova",
INFO[0030] "name": "voltest",
INFO[0030] "size": 1,
INFO[0030] "status": "available",
INFO[0030] "id": "54704d6a-c853-4bb1-b98c-8c08e33ee2c2",
INFO[0030] "type": ""
INFO[0030] }
INFO[0030] volume inspected host=unix:///var/run/libstorage/451292995.sock integrationDriver=linux osDriver=linux service=cinder storageDriver=libstorage time=1500447978199 volume=&{Name:voltest VolumeMountPoint: VolumeStatus:map[iops:0 type: availabilityZone:nova fields:map[] service:cinder server:cinder name:voltest size:1]} volumeName=voltest
DEBU[0030] /VolumeDriver.Path host=unix:///var/run/libstorage/451292995.sock pluginResponse={Name:voltest Opts:map[]} time=1500447978200
DEBU[0030] getting path to volume host=unix:///var/run/libstorage/451292995.sock opts=map[] time=1500447978200 volumeID= volumeName=voltest
DEBU[0030] removing task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 removedAfter=0s route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 tasksLen=1 time=1500447978202 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] removed task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 tasksLen=0 time=1500447978202 tls=false txCR=1500447977 txID=b707d597-3365-46c6-6879-141d9fcc1030
DEBU[0030] skipping path lookup host=unix:///var/run/libstorage/451292995.sock opts=map[] time=1500447978203 volumeID= volumeName=voltest
DEBU[0031] /VolumeDriver.Mount host=unix:///var/run/libstorage/451292995.sock pluginResponse={Name:voltest Opts:map[]} time=1500447979128
DEBU[0031] mounting volume host=unix:///var/run/libstorage/451292995.sock opts=&{OverwriteFS:false NewFSType: Preempt:false Opts:<nil>} time=1500447979131 volumeID= volumeName=voltest
INFO[0031] mounting volume host=unix:///var/run/libstorage/451292995.sock integrationDriver=linux opts=&{OverwriteFS:false NewFSType: Preempt:false Opts:<nil>} osDriver=linux service=cinder storageDriver=libstorage time=1500447979131 volumeID= volumeName=voltest
DEBU[0031] waiting on executor lock host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979131 txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] invoking executor cli args=[cinder localDevices quick] cmd=/var/lib/libstorage/lsx-linux host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979132 txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] signalling executor lock host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979214 txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] xli localdevices success host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979214 txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
INFO[0031]
INFO[0031] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[0031] GET /volumes/cinder/voltest?attachments=31&byName HTTP/1.1
INFO[0031] Host: libstorage-server
INFO[0031] Libstorage-Instanceid: cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1
INFO[0031] Libstorage-Localdevices: cinder=
INFO[0031] Libstorage-Tx: txID=559f8327-55b8-40e1-4d92-8a2584a487a0, txCR=1500447979
INFO[0031]
INFO[0031] http request host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447979214 tls=false
DEBU[0031] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=schema-validator route=volumeInspect server=helix-bear-vg time=1500447979214 tls=false
DEBU[0031] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=storage-session-handler route=volumeInspect server=helix-bear-vg time=1500447979214 tls=false
DEBU[0031] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=auth-svc-handler route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false
DEBU[0031] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=service-validator route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=transaction-handler route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=local-devices-handler route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=instanceIDs-handler route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=auth-global-handler route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=error-handler route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=transaction-handler route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=logging-handler route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=query-params-handler route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false
DEBU[0031] query param host=unix:///var/run/libstorage/451292995.sock key=byName len(value)=1 route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false value=[]
DEBU[0031] query param host=unix:///var/run/libstorage/451292995.sock key=attachments len(value)=1 route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false value=[31]
DEBU[0031] http header Libstorage-Tx=txID=559f8327-55b8-40e1-4d92-8a2584a487a0, txCR=1500447979 host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false
DEBU[0031] skipping global auth handler; empty allow & deny lists host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] http header Libstorage-Instanceid=[cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1] host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] http header Libstorage-Localdevices=[cinder=] host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447979215 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] getting storage service host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg service=cinder time=1500447979215 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447979215 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] driver is not StorageDriverWithLogin host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447979215 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] getting task service host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447979215 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
INFO[0031] executing task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447979215 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] driver is not StorageDriverVolInspectByName host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447979215 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] getting task service host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447979218 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] manually calculating attachment state host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447979422 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] including volume attachmentState=available host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447979422 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] skipping response schema validation; disabled host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447979422 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] task completed host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447979422 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
INFO[0031] @ - - [19/Jul/2017:15:06:19 +0800] "GET /volumes/cinder/voltest?attachments=31&byName HTTP/1.1" 200 177
INFO[0031]
INFO[0031] -------------------------- HTTP REQUEST (SERVER) --------------------------
INFO[0031] GET /volumes/cinder/voltest?attachments=31&byName HTTP/1.1
INFO[0031] Host: libstorage-server
INFO[0031] Accept-Encoding: gzip
INFO[0031] Libstorage-Instanceid: cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1
INFO[0031] Libstorage-Localdevices: cinder=
INFO[0031] Libstorage-Tx: txID=559f8327-55b8-40e1-4d92-8a2584a487a0, txCR=1500447979
INFO[0031] User-Agent: Go-http-client/1.1
INFO[0031]
INFO[0031] -------------------------- HTTP RESPONSE (SERVER) -------------------------
INFO[0031] Content-Type=application/json
INFO[0031]
INFO[0031] {
INFO[0031] "attachmentState": 3,
INFO[0031] "availabilityZone": "nova",
INFO[0031] "name": "voltest",
INFO[0031] "size": 1,
INFO[0031] "status": "available",
INFO[0031] "id": "54704d6a-c853-4bb1-b98c-8c08e33ee2c2",
INFO[0031] "type": ""
INFO[0031] }
INFO[0031]
INFO[0031] -------------------------- HTTP RESPONSE (CLIENT) -------------------------
INFO[0031] HTTP/1.1 200 OK
INFO[0031] Content-Length: 177
INFO[0031] Content-Type: application/json
INFO[0031] Date: Wed, 19 Jul 2017 07:06:19 GMT
INFO[0031] Libstorage-Servername: helix-bear-vg
INFO[0031]
INFO[0031] {
INFO[0031] "attachmentState": 3,
INFO[0031] "availabilityZone": "nova",
INFO[0031] "name": "voltest",
INFO[0031] "size": 1,
INFO[0031] "status": "available",
INFO[0031] "id": "54704d6a-c853-4bb1-b98c-8c08e33ee2c2",
INFO[0031] "type": ""
INFO[0031] }
DEBU[0031] removing task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 removedAfter=0s route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 tasksLen=1 time=1500447979425 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] removed task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 tasksLen=0 time=1500447979425 tls=false txCR=1500447979 txID=559f8327-55b8-40e1-4d92-8a2584a487a0
DEBU[0031] performing precautionary unmount host=unix:///var/run/libstorage/451292995.sock integrationDriver=linux osDriver=linux service=cinder storageDriver=libstorage time=1500447979425
DEBU[0031] waiting on executor lock host=unix:///var/run/libstorage/451292995.sock integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979426 txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] invoking executor cli args=[cinder nextDevice] cmd=/var/lib/libstorage/lsx-linux host=unix:///var/run/libstorage/451292995.sock integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979426 txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] signalling executor lock host=unix:///var/run/libstorage/451292995.sock integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979513 txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] xli nextdevice success host=unix:///var/run/libstorage/451292995.sock integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979513 txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] waiting on executor lock host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979514 txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] invoking executor cli args=[cinder localDevices quick] cmd=/var/lib/libstorage/lsx-linux host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979514 txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] signalling executor lock host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979590 txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] xli localdevices success host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447979592 txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
INFO[0031]
INFO[0031] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[0031] POST /volumes/cinder/54704d6a-c853-4bb1-b98c-8c08e33ee2c2?attach HTTP/1.1
INFO[0031] Host: libstorage-server
INFO[0031] Libstorage-Instanceid: cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1
INFO[0031] Libstorage-Localdevices: cinder=
INFO[0031] Libstorage-Tx: txID=84fef27b-ba59-4b07-6b65-66a81d3d7043, txCR=1500447979
INFO[0031]
INFO[0031] {}
INFO[0031] http request host=unix:///var/run/libstorage/451292995.sock route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=post-args-handler route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=schema-validator route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=storage-session-handler route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=auth-svc-handler route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=service-validator route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=transaction-handler route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=local-devices-handler route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=instanceIDs-handler route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=auth-global-handler route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=error-handler route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=transaction-handler route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=logging-handler route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=query-params-handler route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] query param host=unix:///var/run/libstorage/451292995.sock key=attach len(value)=1 route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false value=[]
DEBU[0031] http header Libstorage-Tx=txID=84fef27b-ba59-4b07-6b65-66a81d3d7043, txCR=1500447979 host=unix:///var/run/libstorage/451292995.sock route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false
DEBU[0031] skipping global auth handler; empty allow & deny lists host=unix:///var/run/libstorage/451292995.sock route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] http header Libstorage-Instanceid=[cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1] host=unix:///var/run/libstorage/451292995.sock route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] http header Libstorage-Localdevices=[cinder=] host=unix:///var/run/libstorage/451292995.sock route=volumeAttach server=helix-bear-vg time=1500447979593 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] getting storage service host=unix:///var/run/libstorage/451292995.sock route=volumeAttach server=helix-bear-vg service=cinder time=1500447979593 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeAttach server=helix-bear-vg service=cinder storageDriver=cinder time=1500447979593 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] driver is not StorageDriverWithLogin host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeAttach server=helix-bear-vg service=cinder storageDriver=cinder time=1500447979593 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] validating schema body={} host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeAttach schema={
"$schema": "http://json-schema.org/draft-04/schema#",
"$ref": "https://github.com/codedellemc/libstorage#/definitions/volumeAttachRequest"
} server=helix-bear-vg service=cinder storageDriver=cinder time=1500447979594 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] getting task service host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeAttach server=helix-bear-vg service=cinder storageDriver=cinder time=1500447979597 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
INFO[0031] executing task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeAttach server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447979597 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0031] getting task service host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeAttach server=helix-bear-vg service=cinder storageDriver=cinder time=1500447979598 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
ERRO[0032] error attaching volume host=unix:///var/run/libstorage/451292995.sock inner=Resource not found instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 instanceId=c62d1c7a-65f4-4658-99de-d65dc99733c1 provider=cinder route=volumeAttach server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447979812 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043 volumeId=54704d6a-c853-4bb1-b98c-8c08e33ee2c2
DEBU[0032] task completed host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeAttach server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447979812 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
ERRO[0032] error: api call failed error.volumeId=54704d6a-c853-4bb1-b98c-8c08e33ee2c2 error.instanceId=c62d1c7a-65f4-4658-99de-d65dc99733c1 error.provider=cinder host=unix:///var/run/libstorage/451292995.sock route=volumeAttach server=helix-bear-vg time=1500447979813 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0032] api call error json apiErr={"message":"error attaching volume","status":500,"error":{"inner":"Resource not found","instanceId":"c62d1c7a-65f4-4658-99de-d65dc99733c1","provider":"cinder","volumeId":"54704d6a-c853-4bb1-b98c-8c08e33ee2c2"}} host=unix:///var/run/libstorage/451292995.sock route=volumeAttach server=helix-bear-vg time=1500447979813 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
INFO[0032] @ - - [19/Jul/2017:15:06:19 +0800] "POST /volumes/cinder/54704d6a-c853-4bb1-b98c-8c08e33ee2c2?attach HTTP/1.1" 500 250
INFO[0032]
INFO[0032] -------------------------- HTTP REQUEST (SERVER) --------------------------
INFO[0032] POST /volumes/cinder/54704d6a-c853-4bb1-b98c-8c08e33ee2c2?attach HTTP/1.1
INFO[0032] Host: libstorage-server
INFO[0032] Accept-Encoding: gzip
INFO[0032] Content-Length: 2
INFO[0032] Libstorage-Instanceid: cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1
INFO[0032] Libstorage-Localdevices: cinder=
INFO[0032] Libstorage-Tx: txID=84fef27b-ba59-4b07-6b65-66a81d3d7043, txCR=1500447979
INFO[0032] User-Agent: Go-http-client/1.1
INFO[0032]
INFO[0032] {}
INFO[0032] -------------------------- HTTP RESPONSE (SERVER) -------------------------
INFO[0032] Content-Type=application/json
INFO[0032]
INFO[0032] {
INFO[0032] "message": "error attaching volume",
INFO[0032] "status": 500,
INFO[0032] "error": {
INFO[0032] "inner": "Resource not found",
INFO[0032] "instanceId": "c62d1c7a-65f4-4658-99de-d65dc99733c1",
INFO[0032] "provider": "cinder",
INFO[0032] "volumeId": "54704d6a-c853-4bb1-b98c-8c08e33ee2c2"
INFO[0032] }
INFO[0032] }
INFO[0032]
INFO[0032] -------------------------- HTTP RESPONSE (CLIENT) -------------------------
INFO[0032] HTTP/1.1 500 Internal Server Error
INFO[0032] Content-Length: 250
INFO[0032] Content-Type: application/json
INFO[0032] Date: Wed, 19 Jul 2017 07:06:19 GMT
INFO[0032] Libstorage-Servername: helix-bear-vg
INFO[0032]
INFO[0032] {
INFO[0032] "message": "error attaching volume",
INFO[0032] "status": 500,
INFO[0032] "error": {
INFO[0032] "inner": "Resource not found",
INFO[0032] "instanceId": "c62d1c7a-65f4-4658-99de-d65dc99733c1",
INFO[0032] "provider": "cinder",
INFO[0032] "volumeId": "54704d6a-c853-4bb1-b98c-8c08e33ee2c2"
INFO[0032] }
INFO[0032] }
ERRO[0032] /VolumeDriver.Mount: error mounting volume error.inner.instanceId=c62d1c7a-65f4-4658-99de-d65dc99733c1 error.inner.provider=cinder error.inner.volumeId=54704d6a-c853-4bb1-b98c-8c08e33ee2c2 error.status=500 host=unix:///var/run/libstorage/451292995.sock time=1500447979814
DEBU[0032] removing task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 removedAfter=0s route=volumeAttach server=helix-bear-vg service=cinder storageDriver=cinder task=0 tasksLen=1 time=1500447979815 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0032] removed task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeAttach server=helix-bear-vg service=cinder storageDriver=cinder task=0 tasksLen=0 time=1500447979815 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0032] /VolumeDriver.Unmount host=unix:///var/run/libstorage/451292995.sock pluginResponse={Name:voltest Opts:map[]} time=1500447980297
DEBU[0032] unmounting volume host=unix:///var/run/libstorage/451292995.sock opts=map[] time=1500447980297 volumeID= volumeName=voltest
DEBU[0032] init count count=0 host=unix:///var/run/libstorage/451292995.sock integrationDriver=linux osDriver=linux service=cinder storageDriver=libstorage time=1500447980300 volumeName=voltest
INFO[0032] unmounting volume host=unix:///var/run/libstorage/451292995.sock integrationDriver=linux opts=map[] osDriver=linux service=cinder storageDriver=libstorage time=1500447980300 volumeID= volumeName=voltest
DEBU[0032] waiting on executor lock host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447980301 txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] invoking executor cli args=[cinder localDevices quick] cmd=/var/lib/libstorage/lsx-linux host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447980301 txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] signalling executor lock host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447980379 txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] xli localdevices success host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 integrationDriver=linux osDriver=linux server=helix-bear-vg service=cinder storageDriver=libstorage time=1500447980379 txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
INFO[0032]
INFO[0032] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[0032] GET /volumes/cinder/voltest?attachments=15&byName HTTP/1.1
INFO[0032] Host: libstorage-server
INFO[0032] Libstorage-Instanceid: cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1
INFO[0032] Libstorage-Localdevices: cinder=
INFO[0032] Libstorage-Tx: txID=f955789a-312f-4b9d-60e3-4de80a45d06a, txCR=1500447980
INFO[0032]
INFO[0032] http request host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447980379 tls=false
DEBU[0032] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=schema-validator route=volumeInspect server=helix-bear-vg time=1500447980380 tls=false
DEBU[0032] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=storage-session-handler route=volumeInspect server=helix-bear-vg time=1500447980381 tls=false
DEBU[0032] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=auth-svc-handler route=volumeInspect server=helix-bear-vg time=1500447980381 tls=false
DEBU[0032] added route middleware host=unix:///var/run/libstorage/451292995.sock middleware=service-validator route=volumeInspect server=helix-bear-vg time=1500447980381 tls=false
DEBU[0032] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=transaction-handler route=volumeInspect server=helix-bear-vg time=1500447980381 tls=false
DEBU[0032] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=local-devices-handler route=volumeInspect server=helix-bear-vg time=1500447980381 tls=false
DEBU[0032] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=instanceIDs-handler route=volumeInspect server=helix-bear-vg time=1500447980381 tls=false
DEBU[0032] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=auth-global-handler route=volumeInspect server=helix-bear-vg time=1500447980381 tls=false
DEBU[0032] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=error-handler route=volumeInspect server=helix-bear-vg time=1500447980381 tls=false
DEBU[0032] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=transaction-handler route=volumeInspect server=helix-bear-vg time=1500447980381 tls=false
DEBU[0032] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=logging-handler route=volumeInspect server=helix-bear-vg time=1500447980381 tls=false
DEBU[0032] added global middleware host=unix:///var/run/libstorage/451292995.sock middleware=query-params-handler route=volumeInspect server=helix-bear-vg time=1500447980382 tls=false
DEBU[0032] query param host=unix:///var/run/libstorage/451292995.sock key=attachments len(value)=1 route=volumeInspect server=helix-bear-vg time=1500447980382 tls=false value=[15]
DEBU[0032] query param host=unix:///var/run/libstorage/451292995.sock key=byName len(value)=1 route=volumeInspect server=helix-bear-vg time=1500447980382 tls=false value=[]
DEBU[0032] http header Libstorage-Tx=txID=f955789a-312f-4b9d-60e3-4de80a45d06a, txCR=1500447980 host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447980382 tls=false
DEBU[0032] skipping global auth handler; empty allow & deny lists host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447980382 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] http header Libstorage-Instanceid=[cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1] host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447980382 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] http header Libstorage-Localdevices=[cinder=] host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447980382 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] getting storage service host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg service=cinder time=1500447980382 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447980382 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] driver is not StorageDriverWithLogin host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447980382 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] getting task service host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447980382 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] getting task service host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder time=1500447980384 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
INFO[0032] executing task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447980384 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] driver is not StorageDriverVolInspectByName host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447980384 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] manually calculating attachment state host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447980584 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] omitting unattached volume attachmentState=available host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447980584 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
ERRO[0032] resource not found host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 resourceID=voltest route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447980584 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] task completed host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447980584 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
ERRO[0032] error: api call failed error.resourceID=voltest host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447980584 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] api call error json apiErr={"message":"resource not found","status":404,"error":{"resourceID":"voltest"}} host=unix:///var/run/libstorage/451292995.sock route=volumeInspect server=helix-bear-vg time=1500447980584 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
INFO[0032] @ - - [19/Jul/2017:15:06:20 +0800] "GET /volumes/cinder/voltest?attachments=15&byName HTTP/1.1" 404 100
INFO[0032]
INFO[0032] -------------------------- HTTP REQUEST (SERVER) --------------------------
INFO[0032] GET /volumes/cinder/voltest?attachments=15&byName HTTP/1.1
INFO[0032] Host: libstorage-server
INFO[0032] Accept-Encoding: gzip
INFO[0032] Libstorage-Instanceid: cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1
INFO[0032] Libstorage-Localdevices: cinder=
INFO[0032] Libstorage-Tx: txID=f955789a-312f-4b9d-60e3-4de80a45d06a, txCR=1500447980
INFO[0032] User-Agent: Go-http-client/1.1
INFO[0032]
INFO[0032] -------------------------- HTTP RESPONSE (SERVER) -------------------------
INFO[0032] Content-Type=application/json
INFO[0032]
INFO[0032] {
INFO[0032] "message": "resource not found",
INFO[0032] "status": 404,
INFO[0032] "error": {
INFO[0032] "resourceID": "voltest"
INFO[0032] }
INFO[0032] }
INFO[0032]
INFO[0032] -------------------------- HTTP RESPONSE (CLIENT) -------------------------
INFO[0032] HTTP/1.1 404 Not Found
INFO[0032] Content-Length: 100
INFO[0032] Content-Type: application/json
INFO[0032] Date: Wed, 19 Jul 2017 07:06:20 GMT
INFO[0032] Libstorage-Servername: helix-bear-vg
INFO[0032]
INFO[0032] {
INFO[0032] "message": "resource not found",
INFO[0032] "status": 404,
INFO[0032] "error": {
INFO[0032] "resourceID": "voltest"
INFO[0032] }
INFO[0032] }
ERRO[0032] /VolumeDriver.Unmount: error unmounting volume error.inner.resourceID=voltest error.status=404 host=unix:///var/run/libstorage/451292995.sock time=1500447980585
DEBU[0032] removing task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 removedAfter=0s route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 tasksLen=1 time=1500447980586 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
DEBU[0032] removed task host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeInspect server=helix-bear-vg service=cinder storageDriver=cinder task=0 tasksLen=0 time=1500447980586 tls=false txCR=1500447980 txID=f955789a-312f-4b9d-60e3-4de80a45d06a
@zhiweiyin318

This comment has been minimized.

Copy link
Owner Author

zhiweiyin318 commented Jul 19, 2017

the log shows :
ERRO[0032] error attaching volume host=unix:///var/run/libstorage/451292995.sock inner=Resource not found instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 instanceId=c62d1c7a-65f4-4658-99de-d65dc99733c1 provider=cinder route=volumeAttach server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447979812 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043 volumeId=54704d6a-c853-4bb1-b98c-8c08e33ee2c2
DEBU[0032] task completed host=unix:///var/run/libstorage/451292995.sock instanceID=cinder=c62d1c7a-65f4-4658-99de-d65dc99733c1 route=volumeAttach server=helix-bear-vg service=cinder storageDriver=cinder task=0 time=1500447979812 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
ERRO[0032] error: api call failed error.volumeId=54704d6a-c853-4bb1-b98c-8c08e33ee2c2 error.instanceId=c62d1c7a-65f4-4658-99de-d65dc99733c1 error.provider=cinder host=unix:///var/run/libstorage/451292995.sock route=volumeAttach server=helix-bear-vg time=1500447979813 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043
DEBU[0032] api call error json apiErr={"message":"error attaching volume","status":500,"error":{"inner":"Resource not found","instanceId":"c62d1c7a-65f4-4658-99de-d65dc99733c1","provider":"cinder","volumeId":"54704d6a-c853-4bb1-b98c-8c08e33ee2c2"}} host=unix:///var/run/libstorage/451292995.sock route=volumeAttach server=helix-bear-vg time=1500447979813 tls=false txCR=1500447979 txID=84fef27b-ba59-4b07-6b65-66a81d3d7043

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
You can’t perform that action at this time.