Skip to content

Instantly share code, notes, and snippets.

@jmaitrehenry

jmaitrehenry/rexray.log

Created December 7, 2017 18:38
Show Gist options
  • Save jmaitrehenry/dae6f7680c2f66d87a3dafa700103e0f to your computer and use it in GitHub Desktop.
Save jmaitrehenry/dae6f7680c2f66d87a3dafa700103e0f to your computer and use it in GitHub Desktop.
Download ZIP
Raw
rexray.log
INFO[3816] Entering go-plugins-helpers unmountPath time=1512670067108
INFO[3816] /csi.Node/NodeUnpublishVolume: REQ 0026: VolumeId=values:<key:"id" value:"test123.vhd" > , VolumeMetadata=values:<key:"name" value:"test123" > values:<key:"type" value:"DataDisk" > , TargetPath=/var/lib/rexray/docker/volumes/test123 host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1512670067108
INFO[3816] azureud_executor: Init host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067108 txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] local devices devicemap=map[/dev/sdc:0] host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067110 txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] xli localdevices success host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067110 txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
INFO[3816]
INFO[3816] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[3816] GET /volumes/azureud/test123.vhd?attachments=0 HTTP/1.1
INFO[3816] Host: libstorage-server
INFO[3816] Libstorage-Instanceid: azureud=swarm-unmanaged
INFO[3816] Libstorage-Localdevices: azureud=/dev/sdc::0
INFO[3816] Libstorage-Tx: txID=646269f7-70b2-4174-69d1-b9f6c742840a, txCR=1512670067
INFO[3816]
INFO[3816] http request host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067111 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=schema-validator route=volumeInspect server=pear-gem-bm time=1512670067111 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=storage-session-handler route=volumeInspect server=pear-gem-bm time=1512670067111 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=auth-svc-handler route=volumeInspect server=pear-gem-bm time=1512670067111 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=service-validator route=volumeInspect server=pear-gem-bm time=1512670067111 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=transaction-handler route=volumeInspect server=pear-gem-bm time=1512670067111 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=local-devices-handler route=volumeInspect server=pear-gem-bm time=1512670067111 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=instanceIDs-handler route=volumeInspect server=pear-gem-bm time=1512670067111 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=auth-global-handler route=volumeInspect server=pear-gem-bm time=1512670067111 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=error-handler route=volumeInspect server=pear-gem-bm time=1512670067112 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=transaction-handler route=volumeInspect server=pear-gem-bm time=1512670067112 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=logging-handler route=volumeInspect server=pear-gem-bm time=1512670067112 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=query-params-handler route=volumeInspect server=pear-gem-bm time=1512670067112 tls=false
DEBU[3816] query param host=unix:///var/run/rexray/384768199.sock key=attachments len(value)=1 route=volumeInspect server=pear-gem-bm time=1512670067112 tls=false value=[0]
DEBU[3816] http header Libstorage-Tx=txID=646269f7-70b2-4174-69d1-b9f6c742840a, txCR=1512670067 host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067112 tls=false
DEBU[3816] skipping global auth handler; empty allow & deny lists host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067112 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] http header Libstorage-Instanceid=[azureud=swarm-unmanaged] host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067112 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] http header Libstorage-Localdevices=[azureud=/dev/sdc::0] host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067113 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] getting storage service host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm service=azureud time=1512670067113 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067113 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] login to azure storage driver host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067113 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] using cached azure client cacheKey=7d82d0216f6bf82956c5ed99a883e500 host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067113 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] getting task service host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067113 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] getting task service host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067115 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
INFO[3816] executing task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067116 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] skipping response schema validation; disabled host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067120 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] task completed host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067120 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
INFO[3816] @ - - [07/Dec/2017:18:07:47 +0000] "GET /volumes/azureud/test123.vhd?attachments=0 HTTP/1.1" 200 81
INFO[3816]
INFO[3816] -------------------------- HTTP REQUEST (SERVER) --------------------------
INFO[3816] GET /volumes/azureud/test123.vhd?attachments=0 HTTP/1.1
INFO[3816] Host: libstorage-server
INFO[3816] Accept-Encoding: gzip
INFO[3816] Libstorage-Instanceid: azureud=swarm-unmanaged
INFO[3816] Libstorage-Localdevices: azureud=/dev/sdc::0
INFO[3816] Libstorage-Tx: txID=646269f7-70b2-4174-69d1-b9f6c742840a, txCR=1512670067
INFO[3816] User-Agent: Go-http-client/1.1
INFO[3816]
INFO[3816] -------------------------- HTTP RESPONSE (SERVER) -------------------------
INFO[3816]
INFO[3816] -------------------------- HTTP RESPONSE (CLIENT) -------------------------
INFO[3816] HTTP/1.1 200 OK
INFO[3816] Content-Length: 81
INFO[3816] Content-Type: application/json
INFO[3816] Date: Thu, 07 Dec 2017 18:07:47 GMT
INFO[3816] Libstorage-Servername: pear-gem-bm
INFO[3816]
INFO[3816] {
INFO[3816] "name": "test123",
INFO[3816] "size": 1,
INFO[3816] "id": "test123.vhd",
INFO[3816] "type": "DataDisk"
INFO[3816] }
INFO[3816] Content-Type=application/json
INFO[3816]
INFO[3816] {
INFO[3816] "name": "test123",
INFO[3816] "size": 1,
INFO[3816] "id": "test123.vhd",
INFO[3816] "type": "DataDisk"
INFO[3816] }
DEBU[3816] removing task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged removedAfter=0s route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 tasksLen=1 time=1512670067125 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
DEBU[3816] removed task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 tasksLen=0 time=1512670067129 tls=false txCR=1512670067 txID=646269f7-70b2-4174-69d1-b9f6c742840a
INFO[3816] azureud_executor: Init host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067128 txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] local devices devicemap=map[/dev/sdc:0] host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067130 txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] xli localdevices success host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067130 txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
INFO[3816]
INFO[3816] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[3816] GET /volumes/azureud/test123.vhd?attachments=7 HTTP/1.1
INFO[3816] Host: libstorage-server
INFO[3816] Libstorage-Instanceid: azureud=swarm-unmanaged
INFO[3816] Libstorage-Localdevices: azureud=/dev/sdc::0
INFO[3816] Libstorage-Tx: txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9, txCR=1512670067
INFO[3816]
INFO[3816] http request host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067131 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=schema-validator route=volumeInspect server=pear-gem-bm time=1512670067131 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=storage-session-handler route=volumeInspect server=pear-gem-bm time=1512670067131 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=auth-svc-handler route=volumeInspect server=pear-gem-bm time=1512670067131 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=service-validator route=volumeInspect server=pear-gem-bm time=1512670067131 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=transaction-handler route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=local-devices-handler route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=instanceIDs-handler route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=auth-global-handler route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=error-handler route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=transaction-handler route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=logging-handler route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=query-params-handler route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false
DEBU[3816] query param host=unix:///var/run/rexray/384768199.sock key=attachments len(value)=1 route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false value=[7]
DEBU[3816] http header Libstorage-Tx=txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9, txCR=1512670067 host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false
DEBU[3816] skipping global auth handler; empty allow & deny lists host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] http header Libstorage-Instanceid=[azureud=swarm-unmanaged] host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067132 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] http header Libstorage-Localdevices=[azureud=/dev/sdc::0] host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067133 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] getting storage service host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm service=azureud time=1512670067133 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067133 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] login to azure storage driver host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067133 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] using cached azure client cacheKey=7d82d0216f6bf82956c5ed99a883e500 host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067133 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] getting task service host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067133 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] getting task service host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067135 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
INFO[3816] executing task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067135 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] Found matching disk test123 on LUN 0 on instance, looking up dev from map[/dev/sdc:0] host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067194 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] manually calculating attachment state host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067194 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] including volume attachmentState=attached host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067195 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] skipping response schema validation; disabled host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067195 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] task completed host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067195 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
INFO[3816] @ - - [07/Dec/2017:18:07:47 +0000] "GET /volumes/azureud/test123.vhd?attachments=7 HTTP/1.1" 200 340
INFO[3816]
INFO[3816] -------------------------- HTTP REQUEST (SERVER) --------------------------
INFO[3816] GET /volumes/azureud/test123.vhd?attachments=7 HTTP/1.1
INFO[3816] Host: libstorage-server
INFO[3816] Accept-Encoding: gzip
INFO[3816] Libstorage-Instanceid: azureud=swarm-unmanaged
INFO[3816] Libstorage-Localdevices: azureud=/dev/sdc::0
INFO[3816] Libstorage-Tx: txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9, txCR=1512670067
INFO[3816] User-Agent: Go-http-client/1.1
INFO[3816]
INFO[3816] -------------------------- HTTP RESPONSE (SERVER) -------------------------
INFO[3816] Content-Type=application/json
INFO[3816]
INFO[3816] {
INFO[3816] "attachments": [
INFO[3816] {
INFO[3816] "deviceName": "/dev/sdc",
INFO[3816] "instanceID": {
INFO[3816] "id": "swarm-unmanaged",
INFO[3816] "driver": "azureud",
INFO[3816] "service": ""
INFO[3816] },
INFO[3816] "status": "",
INFO[3816] "volumeID": "test123.vhd"
INFO[3816] }
INFO[3816] ],
INFO[3816] "attachmentState": 2,
INFO[3816] "name": "test123",
INFO[3816] "size": 1,
INFO[3816] "id": "test123.vhd",
INFO[3816] "type": "DataDisk"
INFO[3816] }
DEBU[3816] removing task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged removedAfter=0s route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 tasksLen=1 time=1512670067199 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
DEBU[3816] removed task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 tasksLen=0 time=1512670067199 tls=false txCR=1512670067 txID=c7aecde8-403a-4ab9-40b4-083ccd2879c9
INFO[3816]
INFO[3816] -------------------------- HTTP RESPONSE (CLIENT) -------------------------
INFO[3816] HTTP/1.1 200 OK
INFO[3816] Content-Length: 340
INFO[3816] Content-Type: application/json
INFO[3816] Date: Thu, 07 Dec 2017 18:07:47 GMT
INFO[3816] Libstorage-Servername: pear-gem-bm
INFO[3816]
INFO[3816] {
INFO[3816] "attachments": [
INFO[3816] {
INFO[3816] "deviceName": "/dev/sdc",
INFO[3816] "instanceID": {
INFO[3816] "id": "swarm-unmanaged",
INFO[3816] "driver": "azureud",
INFO[3816] "service": ""
INFO[3816] },
INFO[3816] "status": "",
INFO[3816] "volumeID": "test123.vhd"
INFO[3816] }
INFO[3816] ],
INFO[3816] "attachmentState": 2,
INFO[3816] "name": "test123",
INFO[3816] "size": 1,
INFO[3816] "id": "test123.vhd",
INFO[3816] "type": "DataDisk"
INFO[3816] }
INFO[3816] azureud_executor: Init host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067207 txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] local devices devicemap=map[/dev/sdc:0] host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067211 txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] xli localdevices success host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067211 txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
INFO[3816]
INFO[3816] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[3816] GET /volumes/azureud/test123.vhd?attachments=15 HTTP/1.1
INFO[3816] Host: libstorage-server
INFO[3816] Libstorage-Instanceid: azureud=swarm-unmanaged
INFO[3816] Libstorage-Localdevices: azureud=/dev/sdc::0
INFO[3816] Libstorage-Tx: txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e, txCR=1512670067
INFO[3816]
INFO[3816] http request host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067212 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=schema-validator route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=storage-session-handler route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=auth-svc-handler route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=service-validator route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=transaction-handler route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=local-devices-handler route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=instanceIDs-handler route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=auth-global-handler route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=error-handler route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=transaction-handler route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=logging-handler route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=query-params-handler route=volumeInspect server=pear-gem-bm time=1512670067213 tls=false
DEBU[3816] query param host=unix:///var/run/rexray/384768199.sock key=attachments len(value)=1 route=volumeInspect server=pear-gem-bm time=1512670067214 tls=false value=[15]
DEBU[3816] http header Libstorage-Tx=txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e, txCR=1512670067 host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067214 tls=false
DEBU[3816] skipping global auth handler; empty allow & deny lists host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067214 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] http header Libstorage-Instanceid=[azureud=swarm-unmanaged] host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067214 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] http header Libstorage-Localdevices=[azureud=/dev/sdc::0] host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067214 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] getting storage service host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm service=azureud time=1512670067214 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067214 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] login to azure storage driver host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067214 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] using cached azure client cacheKey=7d82d0216f6bf82956c5ed99a883e500 host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067214 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] getting task service host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067215 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] getting task service host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067217 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
INFO[3816] executing task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067217 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] Found matching disk test123 on LUN 0 on instance, looking up dev from map[/dev/sdc:0] host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067251 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] manually calculating attachment state host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067251 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] including volume attachmentState=attached host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067251 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] skipping response schema validation; disabled host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067251 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] task completed host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067251 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
INFO[3816] @ - - [07/Dec/2017:18:07:47 +0000] "GET /volumes/azureud/test123.vhd?attachments=15 HTTP/1.1" 200 340
INFO[3816]
INFO[3816] -------------------------- HTTP REQUEST (SERVER) --------------------------
INFO[3816] GET /volumes/azureud/test123.vhd?attachments=15 HTTP/1.1
INFO[3816] Host: libstorage-server
INFO[3816] Accept-Encoding: gzip
INFO[3816] Libstorage-Instanceid: azureud=swarm-unmanaged
INFO[3816] Libstorage-Localdevices: azureud=/dev/sdc::0
INFO[3816] Libstorage-Tx: txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e, txCR=1512670067
INFO[3816] User-Agent: Go-http-client/1.1
INFO[3816]
INFO[3816] -------------------------- HTTP RESPONSE (SERVER) -------------------------
INFO[3816] Content-Type=application/json
INFO[3816]
INFO[3816] {
INFO[3816] "attachments": [
INFO[3816] {
INFO[3816] "deviceName": "/dev/sdc",
INFO[3816] "instanceID": {
INFO[3816] "id": "swarm-unmanaged",
INFO[3816] "driver": "azureud",
INFO[3816] "service": ""
INFO[3816] },
INFO[3816] "status": "",
INFO[3816] "volumeID": "test123.vhd"
INFO[3816] }
INFO[3816] ],
INFO[3816] "attachmentState": 2,
INFO[3816] "name": "test123",
INFO[3816] "size": 1,
INFO[3816] "id": "test123.vhd",
INFO[3816] "type": "DataDisk"
INFO[3816] }
DEBU[3816] removing task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged removedAfter=0s route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 tasksLen=1 time=1512670067255 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
DEBU[3816] removed task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 tasksLen=0 time=1512670067255 tls=false txCR=1512670067 txID=7c093235-ab0c-4ae0-629f-f98ec71fcd5e
INFO[3816]
INFO[3816] -------------------------- HTTP RESPONSE (CLIENT) -------------------------
INFO[3816] HTTP/1.1 200 OK
INFO[3816] Content-Length: 340
INFO[3816] Content-Type: application/json
INFO[3816] Date: Thu, 07 Dec 2017 18:07:47 GMT
INFO[3816] Libstorage-Servername: pear-gem-bm
INFO[3816]
INFO[3816] {
INFO[3816] "attachments": [
INFO[3816] {
INFO[3816] "deviceName": "/dev/sdc",
INFO[3816] "instanceID": {
INFO[3816] "id": "swarm-unmanaged",
INFO[3816] "driver": "azureud",
INFO[3816] "service": ""
INFO[3816] },
INFO[3816] "status": "",
INFO[3816] "volumeID": "test123.vhd"
INFO[3816] }
INFO[3816] ],
INFO[3816] "attachmentState": 2,
INFO[3816] "name": "test123",
INFO[3816] "size": 1,
INFO[3816] "id": "test123.vhd",
INFO[3816] "type": "DataDisk"
INFO[3816] }
INFO[3816] unmount command cmd=umount path=/var/lib/rexray/docker/volumes/test123
DEBU[3816] checking if private mount can be unmounted host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux path=/var/lib/rexray/volumes/test123 service=azureud storageDriver=libstorage time=1512670067276
INFO[3816] unmount command cmd=umount path=/var/lib/rexray/volumes/test123
INFO[3816] /csi.Node/NodeUnpublishVolume: REP 0026: Reply=&{} host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1512670067313
INFO[3816] /csi.Controller/ControllerGetCapabilities: REQ 0027 host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1512670067313
INFO[3816] /csi.Controller/ControllerGetCapabilities: REP 0027: Reply=&{capabilities:<rpc:<type:CREATE_DELETE_VOLUME > > capabilities:<rpc:<type:PUBLISH_UNPUBLISH_VOLUME > > capabilities:<rpc:<type:LIST_VOLUMES > > } host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1512670067313
INFO[3816] /csi.Node/GetNodeID: REQ 0028 host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1512670067314
INFO[3816] /csi.Node/GetNodeID: REP 0028: Reply=&{node_id:<values:<key:"driver" value:"azureud" > values:<key:"id" value:"swarm-unmanaged" > values:<key:"service" value:"" > > } host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1512670067314
INFO[3816] /csi.Controller/ControllerUnpublishVolume: REQ 0029: VolumeId=values:<key:"id" value:"test123.vhd" > , VolumeMetadata=values:<key:"name" value:"test123" > values:<key:"type" value:"DataDisk" > , NodeId=values:<key:"driver" value:"azureud" > values:<key:"id" value:"swarm-unmanaged" > values:<key:"service" value:"" > host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1512670067314
INFO[3816] azureud_executor: Init host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067314 txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] local devices devicemap=map[/dev/sdc:0] host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067316 txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] xli localdevices success host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067316 txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
INFO[3816]
INFO[3816] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[3816] GET /volumes/azureud/test123.vhd?attachments=0 HTTP/1.1
INFO[3816] Host: libstorage-server
INFO[3816] Libstorage-Instanceid: azureud=swarm-unmanaged
INFO[3816] Libstorage-Localdevices: azureud=/dev/sdc::0
INFO[3816] Libstorage-Tx: txID=331ac2ea-a2c1-436e-4e95-8595a6cada86, txCR=1512670067
INFO[3816]
INFO[3816] http request host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067317 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=schema-validator route=volumeInspect server=pear-gem-bm time=1512670067317 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=storage-session-handler route=volumeInspect server=pear-gem-bm time=1512670067317 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=auth-svc-handler route=volumeInspect server=pear-gem-bm time=1512670067317 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=service-validator route=volumeInspect server=pear-gem-bm time=1512670067317 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=transaction-handler route=volumeInspect server=pear-gem-bm time=1512670067317 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=local-devices-handler route=volumeInspect server=pear-gem-bm time=1512670067317 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=instanceIDs-handler route=volumeInspect server=pear-gem-bm time=1512670067317 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=auth-global-handler route=volumeInspect server=pear-gem-bm time=1512670067317 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=error-handler route=volumeInspect server=pear-gem-bm time=1512670067317 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=transaction-handler route=volumeInspect server=pear-gem-bm time=1512670067318 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=logging-handler route=volumeInspect server=pear-gem-bm time=1512670067318 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=query-params-handler route=volumeInspect server=pear-gem-bm time=1512670067318 tls=false
DEBU[3816] query param host=unix:///var/run/rexray/384768199.sock key=attachments len(value)=1 route=volumeInspect server=pear-gem-bm time=1512670067318 tls=false value=[0]
DEBU[3816] http header Libstorage-Tx=txID=331ac2ea-a2c1-436e-4e95-8595a6cada86, txCR=1512670067 host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067318 tls=false
DEBU[3816] skipping global auth handler; empty allow & deny lists host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067318 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] http header Libstorage-Instanceid=[azureud=swarm-unmanaged] host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067318 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] http header Libstorage-Localdevices=[azureud=/dev/sdc::0] host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067318 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] getting storage service host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm service=azureud time=1512670067318 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067319 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] login to azure storage driver host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067319 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] using cached azure client cacheKey=7d82d0216f6bf82956c5ed99a883e500 host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067319 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] getting task service host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067319 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] getting task service host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067321 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
INFO[3816] executing task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067321 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] skipping response schema validation; disabled host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067326 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] task completed host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067326 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
INFO[3816] @ - - [07/Dec/2017:18:07:47 +0000] "GET /volumes/azureud/test123.vhd?attachments=0 HTTP/1.1" 200 81
INFO[3816]
INFO[3816] -------------------------- HTTP REQUEST (SERVER) --------------------------
INFO[3816] GET /volumes/azureud/test123.vhd?attachments=0 HTTP/1.1
INFO[3816] Host: libstorage-server
INFO[3816] Accept-Encoding: gzip
INFO[3816] Libstorage-Instanceid: azureud=swarm-unmanaged
INFO[3816] Libstorage-Localdevices: azureud=/dev/sdc::0
INFO[3816] Libstorage-Tx: txID=331ac2ea-a2c1-436e-4e95-8595a6cada86, txCR=1512670067
INFO[3816] User-Agent: Go-http-client/1.1
INFO[3816]
INFO[3816] -------------------------- HTTP RESPONSE (SERVER) -------------------------
INFO[3816] Content-Type=application/json
INFO[3816]
INFO[3816] {
INFO[3816] "name": "test123",
INFO[3816] "size": 1,
INFO[3816] "id": "test123.vhd",
INFO[3816] "type": "DataDisk"
INFO[3816] }
DEBU[3816] removing task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged removedAfter=0s route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 tasksLen=1 time=1512670067331 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
DEBU[3816] removed task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 tasksLen=0 time=1512670067334 tls=false txCR=1512670067 txID=331ac2ea-a2c1-436e-4e95-8595a6cada86
INFO[3816]
INFO[3816] -------------------------- HTTP RESPONSE (CLIENT) -------------------------
INFO[3816] HTTP/1.1 200 OK
INFO[3816] Content-Length: 81
INFO[3816] Content-Type: application/json
INFO[3816] Date: Thu, 07 Dec 2017 18:07:47 GMT
INFO[3816] Libstorage-Servername: pear-gem-bm
INFO[3816]
INFO[3816] {
INFO[3816] "name": "test123",
INFO[3816] "size": 1,
INFO[3816] "id": "test123.vhd",
INFO[3816] "type": "DataDisk"
INFO[3816] }
INFO[3816] azureud_executor: Init host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067334 txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] local devices devicemap=map[/dev/sdc:0] host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067336 txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] xli localdevices success host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067336 txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
INFO[3816]
INFO[3816] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[3816] GET /volumes/azureud/test123.vhd?attachments=7 HTTP/1.1
INFO[3816] Host: libstorage-server
INFO[3816] Libstorage-Instanceid: azureud=swarm-unmanaged
INFO[3816] Libstorage-Localdevices: azureud=/dev/sdc::0
INFO[3816] Libstorage-Tx: txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d, txCR=1512670067
INFO[3816]
INFO[3816] http request host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=schema-validator route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=storage-session-handler route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=auth-svc-handler route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added route middleware host=unix:///var/run/rexray/384768199.sock middleware=service-validator route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=transaction-handler route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=local-devices-handler route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=instanceIDs-handler route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=auth-global-handler route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=error-handler route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=transaction-handler route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=logging-handler route=volumeInspect server=pear-gem-bm time=1512670067337 tls=false
DEBU[3816] added global middleware host=unix:///var/run/rexray/384768199.sock middleware=query-params-handler route=volumeInspect server=pear-gem-bm time=1512670067338 tls=false
DEBU[3816] query param host=unix:///var/run/rexray/384768199.sock key=attachments len(value)=1 route=volumeInspect server=pear-gem-bm time=1512670067338 tls=false value=[7]
DEBU[3816] http header Libstorage-Tx=txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d, txCR=1512670067 host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067338 tls=false
DEBU[3816] skipping global auth handler; empty allow & deny lists host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067338 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] http header Libstorage-Instanceid=[azureud=swarm-unmanaged] host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067338 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] http header Libstorage-Localdevices=[azureud=/dev/sdc::0] host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm time=1512670067338 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] getting storage service host=unix:///var/run/rexray/384768199.sock route=volumeInspect server=pear-gem-bm service=azureud time=1512670067338 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067338 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] login to azure storage driver host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067338 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] using cached azure client cacheKey=7d82d0216f6bf82956c5ed99a883e500 host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067339 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] getting task service host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067339 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] getting task service host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud time=1512670067341 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
INFO[3816] executing task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067341 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] Found matching disk test123 on LUN 0 on instance, looking up dev from map[/dev/sdc:0] host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067402 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] manually calculating attachment state host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067402 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] including volume attachmentState=attached host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067402 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] skipping response schema validation; disabled host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067402 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] task completed host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 time=1512670067402 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
INFO[3816] @ - - [07/Dec/2017:18:07:47 +0000] "GET /volumes/azureud/test123.vhd?attachments=7 HTTP/1.1" 200 340
INFO[3816]
INFO[3816] -------------------------- HTTP REQUEST (SERVER) --------------------------
INFO[3816] GET /volumes/azureud/test123.vhd?attachments=7 HTTP/1.1
INFO[3816] Host: libstorage-server
INFO[3816] Accept-Encoding: gzip
INFO[3816] Libstorage-Instanceid: azureud=swarm-unmanaged
INFO[3816] Libstorage-Localdevices: azureud=/dev/sdc::0
INFO[3816] Libstorage-Tx: txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d, txCR=1512670067
INFO[3816] User-Agent: Go-http-client/1.1
INFO[3816]
INFO[3816] -------------------------- HTTP RESPONSE (SERVER) -------------------------
INFO[3816] Content-Type=application/json
INFO[3816]
INFO[3816] {
INFO[3816] "attachments": [
INFO[3816] {
INFO[3816] "deviceName": "/dev/sdc",
INFO[3816] "instanceID": {
INFO[3816] "id": "swarm-unmanaged",
INFO[3816] "driver": "azureud",
INFO[3816] "service": ""
INFO[3816] },
INFO[3816] "status": "",
INFO[3816] "volumeID": "test123.vhd"
INFO[3816] }
INFO[3816] ],
INFO[3816] "attachmentState": 2,
INFO[3816] "name": "test123",
INFO[3816] "size": 1,
INFO[3816] "id": "test123.vhd",
INFO[3816] "type": "DataDisk"
INFO[3816] }
DEBU[3816] removing task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged removedAfter=0s route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 tasksLen=1 time=1512670067406 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
DEBU[3816] removed task host=unix:///var/run/rexray/384768199.sock instanceID=azureud=swarm-unmanaged route=volumeInspect server=pear-gem-bm service=azureud storageDriver=azureud task=0 tasksLen=0 time=1512670067406 tls=false txCR=1512670067 txID=2630b3aa-2ab6-4979-6ded-c40d7e3fcf5d
INFO[3816]
INFO[3816] -------------------------- HTTP RESPONSE (CLIENT) -------------------------
INFO[3816] HTTP/1.1 200 OK
INFO[3816] Content-Length: 340
INFO[3816] Content-Type: application/json
INFO[3816] Date: Thu, 07 Dec 2017 18:07:47 GMT
INFO[3816] Libstorage-Servername: pear-gem-bm
INFO[3816]
INFO[3816] {
INFO[3816] "attachments": [
INFO[3816] {
INFO[3816] "deviceName": "/dev/sdc",
INFO[3816] "instanceID": {
INFO[3816] "id": "swarm-unmanaged",
INFO[3816] "driver": "azureud",
INFO[3816] "service": ""
INFO[3816] },
INFO[3816] "status": "",
INFO[3816] "volumeID": "test123.vhd"
INFO[3816] }
INFO[3816] ],
INFO[3816] "attachmentState": 2,
INFO[3816] "name": "test123",
INFO[3816] "size": 1,
INFO[3816] "id": "test123.vhd",
INFO[3816] "type": "DataDisk"
INFO[3816] }
INFO[3816] azureud_executor: Init host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067408 txCR=1512670067 txID=917dfc3b-082d-4a36-56cd-96373c09e531
DEBU[3816] local devices devicemap=map[/dev/sdc:0] host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067409 txCR=1512670067 txID=917dfc3b-082d-4a36-56cd-96373c09e531
DEBU[3816] xli localdevices success host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux server=pear-gem-bm service=azureud storageDriver=libstorage time=1512670067409 txCR=1512670067 txID=917dfc3b-082d-4a36-56cd-96373c09e531
INFO[3816] /csi.Controller/ControllerUnpublishVolume: REP 0029: ControllerUnpublishVolume failed: unable to find attached vol in local devices host=unix:///var/run/rexray/384768199.sock integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1512670067409
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment