Skip to content

Instantly share code, notes, and snippets.

@cecchisandrone

cecchisandrone/rexray-client.log.sh

Created March 22, 2017 10:50
Show Gist options
  • Save cecchisandrone/902ea8eb84d283157b7d9476b52a7dc9 to your computer and use it in GitHub Desktop.
Save cecchisandrone/902ea8eb84d283157b7d9476b52a7dc9 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
rexray-client.log.sh
INFO[0000] updated log level logLevel=debug
DEBU[0000] os.args time=1490179700955 val=[rexray volume create test --size=1]
DEBU[0000] activating libStorage cmd=create time=1490179700956
DEBU[0000] read spec file host=unix:///var/run/libstorage/019760198.sock path=/var/run/rexray/rexray.spec time=1490179700958
DEBU[0000] is local unix server active sock=/var/run/libstorage/019760198.sock time=1490179700958
DEBU[0000] set host in context host=unix:///var/run/libstorage/019760198.sock time=1490179700958
DEBU[0000] set host in config host=unix:///var/run/libstorage/019760198.sock time=1490179700959
DEBU[0000] not starting embedded server; already running host=unix:///var/run/libstorage/019760198.sock time=1490179700959
DEBU[0000] creating libStorage client cmd=create host=unix:///var/run/libstorage/019760198.sock time=1490179700959
DEBU[0000] created scoped scope new=libstorage.client parentScopes=rexray,
INFO[0000] configured logging host=unix:///var/run/libstorage/019760198.sock libstorage.logging.httpRequests=true libstorage.logging.httpResponses=true libstorage.logging.level=debug time=1490179701009
DEBU[0000] got configured host address host=unix:///var/run/libstorage/019760198.sock service=azureud storageDriver=libstorage time=1490179701015
INFO[0000] created libStorage client clientType=integration disableKeepAlive=false enableInstanceIDHeaders=true enableLocalDevicesHeaders=true host=unix:///var/run/libstorage/019760198.sock logRequests=true logResponses=true lsxPath=/var/lib/libstorage/lsx-linux service=azureud storageDriver=libstorage time=1490179701028
INFO[0000] lsx lock file path host=unix:///var/run/libstorage/019760198.sock path=/var/run/libstorage/lsx.lock service=azureud storageDriver=libstorage time=1490179701028
INFO[0000]
INFO[0000] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[0000] GET /services HTTP/1.1
INFO[0000] Host: libstorage-server
INFO[0000] Libstorage-Tx: txID=484012b0-23bc-4e5d-7a9d-3d7fd36589f5, txCR=1490179701
INFO[0000]
INFO[0000]
INFO[0000] -------------------------- HTTP RESPONSE (CLIENT) -------------------------
INFO[0000] HTTP/1.1 200 OK
INFO[0000] Content-Length: 221
INFO[0000] Content-Type: application/json
INFO[0000] Date: Wed, 22 Mar 2017 10:48:21 GMT
INFO[0000] Libstorage-Servername: foil-fish-ly
INFO[0000]
INFO[0000] {
INFO[0000] "azureud": {
INFO[0000] "name": "azureud",
INFO[0000] "driver": {
INFO[0000] "name": "azureud",
INFO[0000] "type": "block",
INFO[0000] "nextDevice": {
INFO[0000] "ignore": false,
INFO[0000] "prefix": "sd",
INFO[0000] "pattern": "[c-z]"
INFO[0000] }
INFO[0000] }
INFO[0000] }
INFO[0000] }
INFO[0000] initializing executors cache host=unix:///var/run/libstorage/019760198.sock service=azureud storageDriver=libstorage time=1490179701036
INFO[0000]
INFO[0000] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[0000] GET /executors HTTP/1.1
INFO[0000] Host: libstorage-server
INFO[0000] Libstorage-Tx: txID=de2005b5-3953-4f48-74f2-f5daa761cafc, txCR=1490179701
INFO[0000]
INFO[0000]
INFO[0000] -------------------------- HTTP RESPONSE (CLIENT) -------------------------
INFO[0000] HTTP/1.1 200 OK
INFO[0000] Content-Length: 157
INFO[0000] Content-Type: application/json
INFO[0000] Date: Wed, 22 Mar 2017 10:48:21 GMT
INFO[0000] Libstorage-Servername: foil-fish-ly
INFO[0000]
INFO[0000] {
INFO[0000] "lsx-linux": {
INFO[0000] "name": "lsx-linux",
INFO[0000] "md5checksum": "00f00faf344d273ee0a08bc344c18e44",
INFO[0000] "size": 11028077,
INFO[0000] "lastModified": 1487991614
INFO[0000] }
INFO[0000] }
DEBU[0000] updating executor host=unix:///var/run/libstorage/019760198.sock service=azureud storageDriver=libstorage time=1490179701039
DEBU[0000] waiting on executor lock host=unix:///var/run/libstorage/019760198.sock service=azureud storageDriver=libstorage time=1490179701039
DEBU[0000] executor exists, getting local checksum host=unix:///var/run/libstorage/019760198.sock service=azureud storageDriver=libstorage time=1490179701039
DEBU[0000] getting executor checksum host=unix:///var/run/libstorage/019760198.sock service=azureud storageDriver=libstorage time=1490179701040
DEBU[0000] got local executor checksum host=unix:///var/run/libstorage/019760198.sock localChecksum=00f00faf344d273ee0a08bc344c18e44 service=azureud storageDriver=libstorage time=1490179701066
DEBU[0000] signalling executor lock host=unix:///var/run/libstorage/019760198.sock service=azureud storageDriver=libstorage time=1490179701066
INFO[0000] initializing supported cache host=unix:///var/run/libstorage/019760198.sock server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179701066
DEBU[0000] waiting on executor lock host=unix:///var/run/libstorage/019760198.sock server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179701066 txCR=1490179701 txID=45523e7b-2d00-4bcd-763a-db34d2a65bc5
DEBU[0000] invoking executor cli args=[azureud supported] cmd=/var/lib/libstorage/lsx-linux host=unix:///var/run/libstorage/019760198.sock server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179701066 txCR=1490179701 txID=45523e7b-2d00-4bcd-763a-db34d2a65bc5
DEBU[0000] signalling executor lock host=unix:///var/run/libstorage/019760198.sock server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179701131 txCR=1490179701 txID=45523e7b-2d00-4bcd-763a-db34d2a65bc5
DEBU[0000] cached supported flag host=unix:///var/run/libstorage/019760198.sock server=foil-fish-ly service=azureud storageDriver=libstorage supported=0 time=1490179701131 txCR=1490179701 txID=45523e7b-2d00-4bcd-763a-db34d2a65bc5
WARN[0000] executor not supported host=unix:///var/run/libstorage/019760198.sock server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179701131
INFO[0000] successefully dialed libStorage server host=unix:///var/run/libstorage/019760198.sock server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179701132
INFO[0000] storage driver initialized host=unix:///var/run/libstorage/019760198.sock service=azureud storageDriver=libstorage time=1490179701132
INFO[0000] os driver initialized host=unix:///var/run/libstorage/019760198.sock osDriver=linux service=azureud storageDriver=libstorage time=1490179701133
INFO[0000] linux integration driver successfully initialized host=unix:///var/run/libstorage/019760198.sock integrationDriver=linux libstorage.integration.volume.operations.create.default.IOPS= libstorage.integration.volume.operations.create.default.availabilityZone= libstorage.integration.volume.operations.create.default.fsType=ext4 libstorage.integration.volume.operations.create.default.size=16 libstorage.integration.volume.operations.create.default.type= libstorage.integration.volume.operations.create.implicit=true libstorage.integration.volume.operations.mount.path=/var/lib/libstorage/volumes libstorage.integration.volume.operations.mount.rootPath=/data osDriver=linux service=azureud storageDriver=libstorage time=1490179701148
INFO[0000] path cache initializion disabled host=unix:///var/run/libstorage/019760198.sock integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1490179701150
INFO[0000] libStorage integration driver successfully initialized host=unix:///var/run/libstorage/019760198.sock integrationDriver=linux libstorage.integration.volume.operations.create.disable=false libstorage.integration.volume.operations.mount.preempt=false libstorage.integration.volume.operations.path.cache.async=true libstorage.integration.volume.operations.path.cache.enabled=false libstorage.integration.volume.operations.remove.disable=false libstorage.integration.volume.operations.unmount.ignoreusedcount=false osDriver=linux service=azureud storageDriver=libstorage time=1490179701158
INFO[0000] integration driver initialized host=unix:///var/run/libstorage/019760198.sock integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1490179701158
INFO[0000] created libStorage client host=unix:///var/run/libstorage/019760198.sock integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1490179701158
WARN[0000] cannot get local deviecs error=executor not supported host=unix:///var/run/libstorage/019760198.sock integrationDriver=linux osDriver=linux server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179701158 txCR=1490179701 txID=164c52a1-ef0f-46ab-4b08-22a256a90874
INFO[0000]
INFO[0000] -------------------------- HTTP REQUEST (CLIENT) -------------------------
INFO[0000] POST /volumes/azureud HTTP/1.1
INFO[0000] Host: libstorage-server
INFO[0000] Libstorage-Tx: txID=164c52a1-ef0f-46ab-4b08-22a256a90874, txCR=1490179701
INFO[0000]
INFO[0000] {"name":"test","availabilityZone":"","encrypted":false,"iops":0,"size":1,"type":""}
DEBU[0000] built output template host=unix:///var/run/libstorage/019760198.sock template={{define "printScriptFields"}}{{.Path}} {{.Installed}} {{.Modified}}{{end}}{{define "printJSON"}}{{. | json}}{{end}}{{define "printVolumeID"}}{{.ID}}{{end}}{{define "printVolumeWithPathFields"}}{{.ID}} {{.Name}} {{.Volume.AttachmentState | printAttState}} {{.Size}} {{.Path}}{{end}}{{define "printInstanceFields"}}{{.InstanceID.ID}} {{.Name}} {{.ProviderName}} {{.Region}}{{end}}{{define "printMountFields"}}{{.ID}} {{.Source}} {{.MountPoint}}{{end}}{{define "printObject"}}{{printf "%v" .}}{{end}}{{define "printEncVolumeFields"}}{{.ID}} {{.Name}} {{.AttachmentState | printAttState}} {{.Size}} {{.Encrypted}}{{end}}{{define "printEncVolumeWithPathFields"}}{{.ID}} {{.Name}} {{.Volume.AttachmentState | printAttState}} {{.Size}} {{.Encrypted}} {{.Path}}{{end}}{{define "printVolumeFields"}}{{.ID}} {{.Name}} {{.AttachmentState | printAttState}} {{.Size}}{{end}}{{define "printSnapshotFields"}}{{.ID}} {{.Name}} {{.Status}} {{.VolumeID}}{{end}}{{define "printServiceFields"}}{{.Name}} {{.Driver.Name}}{{end}}{{define "printStringSlice"}}{{.}}{{end}}{{define "printPrettyJSON"}}{{. | jsonp}}{{end}}{{range .D }}{{template "printObject" .}}
{{end}} time=1490179701380
FATA[0000] error creating volume error=Post http://libstorage-server/volumes/azureud: EOF volume=test
Raw
service.log
⌐▄Q▓▄Ç▓▄,▄_
Σ▄▓▓▓▓▓▓▓▓▓▓▄π
╒▓▓▌▓▓▓▓▓▓▓▓▓▓▀▓▄▄.
,_▄▀▓▓ ▓▓ ▓▓▓▓▓▓▓▓▓▓▓█
│▄▓▓ _▓▓▓▓▓▓▓▓▓┌▓▓▓▓▓█
_J┤▓▓▓▓▓▓▓▓▓▓▓▓▓├█▓█▓▀Γ
,▄▓▓▓▓▓▓^██▓▓▓▓▓▓▓▓▓▓▓▓▄▀▄▄▓▓Ω▄
F▌▓▌█ⁿⁿⁿ ⁿ└▀ⁿ██▓▀▀▀▀▀▀▀▀▀▀▌▓▓▓▌
'ⁿ_ ,▄▄▄▄▄▄▄▄▄█_▄▄▄▄▄▄▄▄▄ⁿ▀~██
Γ ├▓▓▓▓▓█▀ⁿ█▌▓Ω]█▓▓▓▓▓▓ ├▓
│ ├▓▓▓▓▓▌≡,__▄▓▓▓█▓▓▓▓▓ ╞█~ Y,┐
╞ ├▓▓▓▓▓▄▄__^^▓▓▓▌▓▓▓▓▓ ▓ /▓▓▓
├▓▓▓▓▓▓▓▄▄═▄▓▓▓▓▓▓▓▓▓ π ⌐▄▓▓█║n
_ ├▓▓▓▓▓▓▓▓▓~▓▓▓▓▓▓▓▓▓▓ ▄4▄▓▓▓██
µ ├▓▓▓▓█▀█▓▓_▓▓███▓▓▓▓▓ ▓▓▓▓▓Ω4
µ ├▓▀▀L └ⁿ ▀ ▀ ▓▓█w ▓▓▓▀ìⁿ
⌐ ├_ τ▀▓ Σ⌐└
~ ├▓▓ ▄ _ ╒ ┌▄▓▓ Γ
├▓▓▓▌█═┴▓▄╒▀▄_▄▌═¢▓▓▓ ╚
⌠ ├▓▓▓▓▓ⁿ▄▓▓▓▓▓▓▓┐▄▓▓▓▓ └
Ω_.└██▓▀ⁿÇⁿ▀▀▀▀▀▀█≡▀▀▀▀▀ µ
ⁿ .▄▄▓▓▓▓▄▄┌ ╖__▓_▄▄▄▄▄*Oⁿ
û▌├▓█▓▓▓██ⁿ ¡▓▓▓▓▓▓▓▓█▓╪
╙Ω▀█ ▓██ⁿ └█▀██▀▓█├█Å
ⁿⁿ ⁿ ⁿ^
:::::::.. .,:::::: .,:: .::::::::.. :::. .-:. ::-.
;;;;'';;;; ;;;;'''' ';;;, .,;; ;;;;'';;;; ;;';; ';;. ;;;;'
[[[,/[[[' [[cccc '[[,,[[' [[[,/[[[' ,[[ '[[, '[[,[[['
$$$$$$c $$"""" Y$$$Pcccc $$$$$$c c$$$cc$$$c c$$"
888b "88bo,888oo,__ oP"''"Yo, 888b "88bo,888 888,,8P"'
MMMM "W" """"YUMMM,m" "Mm, MMMM "W" YMM ""'mM"
REX-Ray
-------
Binary: /usr/bin/rexray
Flavor: client+agent+controller
SemVer: 0.8.1
OsArch: Linux-x86_64
Branch: v0.8.1
Commit: 30e9082dd9917f0d218ca981f886d701110ce5f5
Formed: Sat, 25 Feb 2017 03:00:28 UTC
libStorage
----------
SemVer: 0.5.1
OsArch: Linux-x86_64
Branch: v0.8.1
Commit: 35c7b6d96d5f17aa0c0379924615ae22c1ad3d45
Formed: Sat, 25 Feb 2017 02:59:00 UTC
time="2017-03-22T10:47:41Z" level=info msg="created pid file" pid=52760 time=1490179661333
time="2017-03-22T10:47:41Z" level=debug msg="host is empty; initiliazing default services" time=1490179661338
time="2017-03-22T10:47:41Z" level=info msg="libStorage auto service mode enabled" driver=azureud time=1490179661340
time="2017-03-22T10:47:41Z" level=debug msg="starting embedded libStorage server" time=1490179661340
time="2017-03-22T10:47:41Z" level=debug msg="created scoped scope" new=libstorage.server parentScopes="rexray,"
time="2017-03-22T10:47:41Z" level=info msg="configured logging" libstorage.logging.httpRequests=true libstorage.logging.httpResponses=true libstorage.logging.level=debug server=foil-fish-ly time=1490179661373
time="2017-03-22T10:47:41Z" level=info msg="initializing server" server=foil-fish-ly time=1490179661373
time="2017-03-22T10:47:41Z" level=info msg="initializing default endpoint" autoEndpointMode=unix server=foil-fish-ly time=1490179661378
time="2017-03-22T10:47:41Z" level=info msg="initializing auto unix endpoint" endpoint=libstorage.server.endpoints.localhost server=foil-fish-ly time=1490179661381
time="2017-03-22T10:47:41Z" level=debug msg="endpoint info" address="unix:///var/run/libstorage/019760198.sock" endpoint=libstorage.server.endpoints.localhost server=foil-fish-ly time=1490179661381
time="2017-03-22T10:47:41Z" level=debug msg="created scoped scope" new=libstorage.server.endpoints.localhost parentScopes="libstorage.server,rexray,"
time="2017-03-22T10:47:41Z" level=info msg="configured endpoint" address="unix:///var/run/libstorage/019760198.sock" endpoint=localhost server=foil-fish-ly time=1490179661388
time="2017-03-22T10:47:41Z" level=info msg="server created" server=foil-fish-ly time=1490179661389
time="2017-03-22T10:47:41Z" level=info msg="initialized endpoints" server=foil-fish-ly time=1490179661389
time="2017-03-22T10:47:41Z" level=info msg="initializing server services" server=foil-fish-ly time=1490179661389
time="2017-03-22T10:47:41Z" level=debug msg="configured result schema validation" enabled=false server=foil-fish-ly time=1490179661390
time="2017-03-22T10:47:41Z" level=debug msg="got services map" count=1 server=foil-fish-ly time=1490179661392
time="2017-03-22T10:47:41Z" level=debug msg="processing service config" server=foil-fish-ly service=azureud time=1490179661392
time="2017-03-22T10:47:41Z" level=debug msg="getting scoped config for service" scope=libstorage.server.services.azureud server=foil-fish-ly service=azureud time=1490179661392
time="2017-03-22T10:47:41Z" level=debug msg="created scoped scope" new=libstorage.server.services.azureud parentScopes="libstorage.server,rexray,"
time="2017-03-22T10:47:41Z" level=debug msg="got driver name" driverName=azureud server=foil-fish-ly service=azureud time=1490179661394
time="2017-03-22T10:47:41Z" level=info msg="storage driver initialized" server=foil-fish-ly service=azureud storageDriver=azureud time=1490179661425
time="2017-03-22T10:47:41Z" level=info msg="created new service" server=foil-fish-ly service=azureud time=1490179661425
time="2017-03-22T10:47:41Z" level=info msg="initialized services" server=foil-fish-ly time=1490179661425
time="2017-03-22T10:47:41Z" level=info msg="initialized router" len(routes)=3 router=executor-router
time="2017-03-22T10:47:41Z" level=info msg="initialized router" len(routes)=4 router=help-router
time="2017-03-22T10:47:41Z" level=info msg="initialized router" len(routes)=1 router=root-router
time="2017-03-22T10:47:41Z" level=info msg="initialized router" len(routes)=2 router=service-router
time="2017-03-22T10:47:41Z" level=info msg="initialized router" len(routes)=11 router=volume-router
time="2017-03-22T10:47:41Z" level=info msg="initialized router" len(routes)=6 router=snapshot-router
time="2017-03-22T10:47:41Z" level=info msg="initialized router" len(routes)=2 router=tasks-router
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/executors" queries=[] route=executors server=foil-fish-ly time=1490179661425 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/executors/{executor}" queries=[] route=executorInspect server=foil-fish-ly time=1490179661425 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=HEAD path="/executors/{executor}" queries=[] route=executorHead server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/help" queries=[] route=version server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/help/config" queries=[] route=version server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/help/env" queries=[] route=version server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/help/version" queries=[] route=version server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/" queries=[] route=root server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/services" queries=[] route=services server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/services/{service}" queries=[] route=serviceInspect server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/volumes" queries=[] route=volumes server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/volumes/{service}" queries=[] route=volumesForService server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/volumes/{service}/{volumeID}" queries=[] route=volumeInspect server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=2 method=POST path="/volumes/{service}" queries=[detach ] route=volumesDetachForService server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=POST path="/volumes/{service}" queries=[] route=volumeCreate server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=2 method=POST path="/volumes/{service}/{volumeID}" queries=[copy ] route=volumeCopy server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=2 method=POST path="/volumes/{service}/{volumeID}" queries=[snapshot ] route=volumeSnapshot server=foil-fish-ly time=1490179661426 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=2 method=POST path="/volumes/{service}/{volumeID}" queries=[attach ] route=volumeAttach server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=2 method=POST path="/volumes" queries=[detach ] route=volumesDetachAll server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=2 method=POST path="/volumes/{service}/{volumeID}" queries=[detach ] route=volumeDetach server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=DELETE path="/volumes/{service}/{volumeID}" queries=[] route=volumeRemove server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/snapshots" queries=[] route=snapshots server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/snapshots/{service}" queries=[] route=snapshotsForService server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/snapshots/{service}/{snapshotID}" queries=[] route=snapshotInspect server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=2 method=POST path="/snapshots/{service}/{snapshotID}" queries=[create ] route=snapshotCreate server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=2 method=POST path="/snapshots/{service}/{snapshotID}" queries=[copy ] route=snapshotCopy server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=DELETE path="/snapshots/{service}/{snapshotID}" queries=[] route=snapshotRemove server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/tasks" queries=[] route=tasks server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=debug msg="registered route" host="unix:///var/run/libstorage/019760198.sock" len(queries)=0 method=GET path="/tasks/{taskID}" queries=[] route=taskInspect server=foil-fish-ly time=1490179661427 tls=false
time="2017-03-22T10:47:41Z" level=info msg="api listening" host="unix:///var/run/libstorage/019760198.sock" server=foil-fish-ly time=1490179661428 tls=false
time="2017-03-22T10:47:41Z" level=info msg="waiting for err or close signal" server=foil-fish-ly time=1490179661428
time="2017-03-22T10:47:42Z" level=info msg="server started" server=foil-fish-ly time=1490179662428
time="2017-03-22T10:47:42Z" level=debug msg="got host from new server address" host="unix:///var/run/libstorage/019760198.sock" time=1490179662428
time="2017-03-22T10:47:42Z" level=debug msg="set host in context" host="unix:///var/run/libstorage/019760198.sock" time=1490179662428
time="2017-03-22T10:47:42Z" level=debug msg="set host in config" host="unix:///var/run/libstorage/019760198.sock" time=1490179662428
time="2017-03-22T10:47:42Z" level=debug msg="created spec file" host="unix:///var/run/libstorage/019760198.sock" path="/var/run/rexray/rexray.spec" time=1490179662428
time="2017-03-22T10:47:42Z" level=debug msg="got modules map" count=1 host="unix:///var/run/libstorage/019760198.sock" time=1490179662429
time="2017-03-22T10:47:42Z" level=debug msg="processing module config" host="unix:///var/run/libstorage/019760198.sock" name=default-docker time=1490179662429
time="2017-03-22T10:47:42Z" level=debug msg="created scoped scope" new=rexray.modules.default-docker parentScopes="rexray,"
time="2017-03-22T10:47:42Z" level=info msg="created new mod config" addr="unix:///run/docker/plugins/rexray.sock" desc="The default docker module." host="unix:///var/run/libstorage/019760198.sock" name=default-docker time=1490179662437 type=docker
time="2017-03-22T10:47:42Z" level=debug msg="got configured modules" host="unix:///var/run/libstorage/019760198.sock" len(modConfigs)=1 time=1490179662437
time="2017-03-22T10:47:42Z" level=debug msg="creating libStorage client for module instance" host="unix:///var/run/libstorage/019760198.sock" name=default-docker time=1490179662437
time="2017-03-22T10:47:42Z" level=debug msg="created scoped scope" new=libstorage.client parentScopes="rexray.modules.default-docker,rexray,"
time="2017-03-22T10:47:42Z" level=info msg="configured logging" host="unix:///var/run/libstorage/019760198.sock" libstorage.logging.httpRequests=true libstorage.logging.httpResponses=true libstorage.logging.level=debug time=1490179662525
time="2017-03-22T10:47:42Z" level=debug msg="got configured host address" host="unix:///var/run/libstorage/019760198.sock" service=azureud storageDriver=libstorage time=1490179662536
time="2017-03-22T10:47:42Z" level=info msg="created libStorage client" clientType=integration disableKeepAlive=false enableInstanceIDHeaders=true enableLocalDevicesHeaders=true host="unix:///var/run/libstorage/019760198.sock" logRequests=true logResponses=true lsxPath="/var/lib/libstorage/lsx-linux" service=azureud storageDriver=libstorage time=1490179662563
time="2017-03-22T10:47:42Z" level=info msg="lsx lock file path" host="unix:///var/run/libstorage/019760198.sock" path="/var/run/libstorage/lsx.lock" service=azureud storageDriver=libstorage time=1490179662563
time="2017-03-22T10:47:42Z" level=info
time="2017-03-22T10:47:42Z" level=info msg=" -------------------------- HTTP REQUEST (CLIENT) -------------------------"
time="2017-03-22T10:47:42Z" level=info msg=" GET /services HTTP/1.1"
time="2017-03-22T10:47:42Z" level=info msg=" Host: libstorage-server"
time="2017-03-22T10:47:42Z" level=info msg=" Libstorage-Tx: txID=3e158813-2e7b-4f8c-63df-4258c3ece76b, txCR=1490179662"
time="2017-03-22T10:47:42Z" level=info msg=" "
time="2017-03-22T10:47:42Z" level=info msg="http request" host="unix:///var/run/libstorage/019760198.sock" route=services server=foil-fish-ly time=1490179662565 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=schema-validator route=services server=foil-fish-ly time=1490179662565 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=services server=foil-fish-ly time=1490179662565 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=local-devices-handler route=services server=foil-fish-ly time=1490179662565 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=instanceIDs-handler route=services server=foil-fish-ly time=1490179662565 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=error-handler route=services server=foil-fish-ly time=1490179662566 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=services server=foil-fish-ly time=1490179662566 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=logging-handler route=services server=foil-fish-ly time=1490179662566 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=query-params-handler route=services server=foil-fish-ly time=1490179662566 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="http header" Libstorage-Tx="txID=3e158813-2e7b-4f8c-63df-4258c3ece76b, txCR=1490179662" host="unix:///var/run/libstorage/019760198.sock" route=services server=foil-fish-ly time=1490179662567 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="http header" Libstorage-Instanceid=[] host="unix:///var/run/libstorage/019760198.sock" route=services server=foil-fish-ly time=1490179662567 tls=false txCR=1490179662 txID=3e158813-2e7b-4f8c-63df-4258c3ece76b
time="2017-03-22T10:47:42Z" level=debug msg="http header" Libstorage-Localdevices=[] host="unix:///var/run/libstorage/019760198.sock" route=services server=foil-fish-ly time=1490179662567 tls=false txCR=1490179662 txID=3e158813-2e7b-4f8c-63df-4258c3ece76b
time="2017-03-22T10:47:42Z" level=info msg="@ - - [22/Mar/2017:10:47:42 +0000] \"GET /services HTTP/1.1\" 200 221"
time="2017-03-22T10:47:42Z" level=info
time="2017-03-22T10:47:42Z" level=info msg=" -------------------------- HTTP REQUEST (SERVER) --------------------------"
time="2017-03-22T10:47:42Z" level=info msg=" GET /services HTTP/1.1"
time="2017-03-22T10:47:42Z" level=info msg=" Host: libstorage-server"
time="2017-03-22T10:47:42Z" level=info msg=" Accept-Encoding: gzip"
time="2017-03-22T10:47:42Z" level=info msg=" Libstorage-Tx: txID=3e158813-2e7b-4f8c-63df-4258c3ece76b, txCR=1490179662"
time="2017-03-22T10:47:42Z" level=info msg=" User-Agent: Go-http-client/1.1"
time="2017-03-22T10:47:42Z" level=info msg=" "
time="2017-03-22T10:47:42Z" level=info msg=" -------------------------- HTTP RESPONSE (SERVER) -------------------------"
time="2017-03-22T10:47:42Z" level=info msg=" Content-Type=application/json"
time="2017-03-22T10:47:42Z" level=info
time="2017-03-22T10:47:42Z" level=info msg=" {"
time="2017-03-22T10:47:42Z" level=info msg=" \"azureud\": {"
time="2017-03-22T10:47:42Z" level=info msg=" \"name\": \"azureud\","
time="2017-03-22T10:47:42Z" level=info msg=" \"driver\": {"
time="2017-03-22T10:47:42Z" level=info msg=" \"name\": \"azureud\","
time="2017-03-22T10:47:42Z" level=info msg=" \"type\": \"block\","
time="2017-03-22T10:47:42Z" level=info msg=" \"nextDevice\": {"
time="2017-03-22T10:47:42Z" level=info msg=" \"ignore\": false,"
time="2017-03-22T10:47:42Z" level=info msg=" \"prefix\": \"sd\","
time="2017-03-22T10:47:42Z" level=info msg=" \"pattern\": \"[c-z]\""
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=info
time="2017-03-22T10:47:42Z" level=info msg=" -------------------------- HTTP RESPONSE (CLIENT) -------------------------"
time="2017-03-22T10:47:42Z" level=info msg=" HTTP/1.1 200 OK"
time="2017-03-22T10:47:42Z" level=info msg=" Content-Length: 221"
time="2017-03-22T10:47:42Z" level=info msg=" Content-Type: application/json"
time="2017-03-22T10:47:42Z" level=info msg=" Date: Wed, 22 Mar 2017 10:47:42 GMT"
time="2017-03-22T10:47:42Z" level=info msg=" Libstorage-Servername: foil-fish-ly"
time="2017-03-22T10:47:42Z" level=info msg=" "
time="2017-03-22T10:47:42Z" level=info msg=" {"
time="2017-03-22T10:47:42Z" level=info msg=" \"azureud\": {"
time="2017-03-22T10:47:42Z" level=info msg=" \"name\": \"azureud\","
time="2017-03-22T10:47:42Z" level=info msg=" \"driver\": {"
time="2017-03-22T10:47:42Z" level=info msg=" \"name\": \"azureud\","
time="2017-03-22T10:47:42Z" level=info msg=" \"type\": \"block\","
time="2017-03-22T10:47:42Z" level=info msg=" \"nextDevice\": {"
time="2017-03-22T10:47:42Z" level=info msg=" \"ignore\": false,"
time="2017-03-22T10:47:42Z" level=info msg=" \"prefix\": \"sd\","
time="2017-03-22T10:47:42Z" level=info msg=" \"pattern\": \"[c-z]\""
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=info msg="initializing executors cache" host="unix:///var/run/libstorage/019760198.sock" service=azureud storageDriver=libstorage time=1490179662578
time="2017-03-22T10:47:42Z" level=info
time="2017-03-22T10:47:42Z" level=info msg=" -------------------------- HTTP REQUEST (CLIENT) -------------------------"
time="2017-03-22T10:47:42Z" level=info msg=" GET /executors HTTP/1.1"
time="2017-03-22T10:47:42Z" level=info msg=" Host: libstorage-server"
time="2017-03-22T10:47:42Z" level=info msg=" Libstorage-Tx: txID=5ebbc000-9c10-4b22-7171-4a07ea85579c, txCR=1490179662"
time="2017-03-22T10:47:42Z" level=info msg=" "
time="2017-03-22T10:47:42Z" level=info msg="http request" host="unix:///var/run/libstorage/019760198.sock" route=executors server=foil-fish-ly time=1490179662578 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=executors server=foil-fish-ly time=1490179662578 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=local-devices-handler route=executors server=foil-fish-ly time=1490179662578 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=instanceIDs-handler route=executors server=foil-fish-ly time=1490179662578 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=error-handler route=executors server=foil-fish-ly time=1490179662578 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=executors server=foil-fish-ly time=1490179662578 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=logging-handler route=executors server=foil-fish-ly time=1490179662579 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=query-params-handler route=executors server=foil-fish-ly time=1490179662579 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="http header" Libstorage-Tx="txID=5ebbc000-9c10-4b22-7171-4a07ea85579c, txCR=1490179662" host="unix:///var/run/libstorage/019760198.sock" route=executors server=foil-fish-ly time=1490179662579 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="http header" Libstorage-Instanceid=[] host="unix:///var/run/libstorage/019760198.sock" route=executors server=foil-fish-ly time=1490179662579 tls=false txCR=1490179662 txID=5ebbc000-9c10-4b22-7171-4a07ea85579c
time="2017-03-22T10:47:42Z" level=debug msg="http header" Libstorage-Localdevices=[] host="unix:///var/run/libstorage/019760198.sock" route=executors server=foil-fish-ly time=1490179662579 tls=false txCR=1490179662 txID=5ebbc000-9c10-4b22-7171-4a07ea85579c
time="2017-03-22T10:47:42Z" level=info msg="@ - - [22/Mar/2017:10:47:42 +0000] \"GET /executors HTTP/1.1\" 200 157"
time="2017-03-22T10:47:42Z" level=info
time="2017-03-22T10:47:42Z" level=info msg=" -------------------------- HTTP REQUEST (SERVER) --------------------------"
time="2017-03-22T10:47:42Z" level=info msg=" GET /executors HTTP/1.1"
time="2017-03-22T10:47:42Z" level=info msg=" Host: libstorage-server"
time="2017-03-22T10:47:42Z" level=info msg=" Accept-Encoding: gzip"
time="2017-03-22T10:47:42Z" level=info msg=" Libstorage-Tx: txID=5ebbc000-9c10-4b22-7171-4a07ea85579c, txCR=1490179662"
time="2017-03-22T10:47:42Z" level=info msg=" User-Agent: Go-http-client/1.1"
time="2017-03-22T10:47:42Z" level=info msg=" "
time="2017-03-22T10:47:42Z" level=info msg=" -------------------------- HTTP RESPONSE (SERVER) -------------------------"
time="2017-03-22T10:47:42Z" level=info msg=" Content-Type=application/json"
time="2017-03-22T10:47:42Z" level=info
time="2017-03-22T10:47:42Z" level=info msg=" {"
time="2017-03-22T10:47:42Z" level=info msg=" \"lsx-linux\": {"
time="2017-03-22T10:47:42Z" level=info msg=" \"name\": \"lsx-linux\","
time="2017-03-22T10:47:42Z" level=info msg=" \"md5checksum\": \"00f00faf344d273ee0a08bc344c18e44\","
time="2017-03-22T10:47:42Z" level=info msg=" \"size\": 11028077,"
time="2017-03-22T10:47:42Z" level=info msg=" \"lastModified\": 1487991614"
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=info
time="2017-03-22T10:47:42Z" level=info msg=" -------------------------- HTTP RESPONSE (CLIENT) -------------------------"
time="2017-03-22T10:47:42Z" level=info msg=" HTTP/1.1 200 OK"
time="2017-03-22T10:47:42Z" level=info msg=" Content-Length: 157"
time="2017-03-22T10:47:42Z" level=info msg=" Content-Type: application/json"
time="2017-03-22T10:47:42Z" level=info msg=" Date: Wed, 22 Mar 2017 10:47:42 GMT"
time="2017-03-22T10:47:42Z" level=info msg=" Libstorage-Servername: foil-fish-ly"
time="2017-03-22T10:47:42Z" level=info msg=" "
time="2017-03-22T10:47:42Z" level=info msg=" {"
time="2017-03-22T10:47:42Z" level=info msg=" \"lsx-linux\": {"
time="2017-03-22T10:47:42Z" level=info msg=" \"name\": \"lsx-linux\","
time="2017-03-22T10:47:42Z" level=info msg=" \"md5checksum\": \"00f00faf344d273ee0a08bc344c18e44\","
time="2017-03-22T10:47:42Z" level=info msg=" \"size\": 11028077,"
time="2017-03-22T10:47:42Z" level=info msg=" \"lastModified\": 1487991614"
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=info msg=" }"
time="2017-03-22T10:47:42Z" level=debug msg="updating executor" host="unix:///var/run/libstorage/019760198.sock" service=azureud storageDriver=libstorage time=1490179662579
time="2017-03-22T10:47:42Z" level=debug msg="waiting on executor lock" host="unix:///var/run/libstorage/019760198.sock" service=azureud storageDriver=libstorage time=1490179662579
time="2017-03-22T10:47:42Z" level=debug msg="executor exists, getting local checksum" host="unix:///var/run/libstorage/019760198.sock" service=azureud storageDriver=libstorage time=1490179662580
time="2017-03-22T10:47:42Z" level=debug msg="getting executor checksum" host="unix:///var/run/libstorage/019760198.sock" service=azureud storageDriver=libstorage time=1490179662580
time="2017-03-22T10:47:42Z" level=debug msg="got local executor checksum" host="unix:///var/run/libstorage/019760198.sock" localChecksum=00f00faf344d273ee0a08bc344c18e44 service=azureud storageDriver=libstorage time=1490179662607
time="2017-03-22T10:47:42Z" level=debug msg="signalling executor lock" host="unix:///var/run/libstorage/019760198.sock" service=azureud storageDriver=libstorage time=1490179662608
time="2017-03-22T10:47:42Z" level=info msg="initializing supported cache" host="unix:///var/run/libstorage/019760198.sock" server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179662608
time="2017-03-22T10:47:42Z" level=debug msg="waiting on executor lock" host="unix:///var/run/libstorage/019760198.sock" server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179662608 txCR=1490179662 txID=4e673795-7992-4688-5f23-68e8eea629d1
time="2017-03-22T10:47:42Z" level=debug msg="invoking executor cli" args=[azureud supported] cmd="/var/lib/libstorage/lsx-linux" host="unix:///var/run/libstorage/019760198.sock" server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179662608 txCR=1490179662 txID=4e673795-7992-4688-5f23-68e8eea629d1
time="2017-03-22T10:47:42Z" level=debug msg="signalling executor lock" host="unix:///var/run/libstorage/019760198.sock" server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179662679 txCR=1490179662 txID=4e673795-7992-4688-5f23-68e8eea629d1
time="2017-03-22T10:47:42Z" level=debug msg="cached supported flag" host="unix:///var/run/libstorage/019760198.sock" server=foil-fish-ly service=azureud storageDriver=libstorage supported=0 time=1490179662679 txCR=1490179662 txID=4e673795-7992-4688-5f23-68e8eea629d1
time="2017-03-22T10:47:42Z" level=warning msg="executor not supported" host="unix:///var/run/libstorage/019760198.sock" server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179662679
time="2017-03-22T10:47:42Z" level=info msg="successefully dialed libStorage server" host="unix:///var/run/libstorage/019760198.sock" server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179662679
time="2017-03-22T10:47:42Z" level=info msg="storage driver initialized" host="unix:///var/run/libstorage/019760198.sock" service=azureud storageDriver=libstorage time=1490179662679
time="2017-03-22T10:47:42Z" level=info msg="os driver initialized" host="unix:///var/run/libstorage/019760198.sock" osDriver=linux service=azureud storageDriver=libstorage time=1490179662682
time="2017-03-22T10:47:42Z" level=info msg="linux integration driver successfully initialized" host="unix:///var/run/libstorage/019760198.sock" integrationDriver=linux libstorage.integration.volume.operations.create.default.IOPS= libstorage.integration.volume.operations.create.default.availabilityZone= libstorage.integration.volume.operations.create.default.fsType=ext4 libstorage.integration.volume.operations.create.default.size=16 libstorage.integration.volume.operations.create.default.type= libstorage.integration.volume.operations.create.implicit=true libstorage.integration.volume.operations.mount.path="/var/lib/libstorage/volumes" libstorage.integration.volume.operations.mount.rootPath="/data" osDriver=linux service=azureud storageDriver=libstorage time=1490179662712
time="2017-03-22T10:47:42Z" level=info msg="initializing the path cache" async=true host="unix:///var/run/libstorage/019760198.sock" integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1490179662718
time="2017-03-22T10:47:42Z" level=debug msg="listing volumes" host="unix:///var/run/libstorage/019760198.sock" integrationDriver=linux opts=map[attachments:true] osDriver=linux service=azureud storageDriver=libstorage time=1490179662718
time="2017-03-22T10:47:42Z" level=warning msg="cannot get local deviecs" error="executor not supported" host="unix:///var/run/libstorage/019760198.sock" integrationDriver=linux osDriver=linux server=foil-fish-ly service=azureud storageDriver=libstorage time=1490179662718 txCR=1490179662 txID=922f67ce-2592-482a-7cd9-c2952ef497da
time="2017-03-22T10:47:42Z" level=info
time="2017-03-22T10:47:42Z" level=info msg=" -------------------------- HTTP REQUEST (CLIENT) -------------------------"
time="2017-03-22T10:47:42Z" level=info msg=" GET /volumes/azureud?attachments=15 HTTP/1.1"
time="2017-03-22T10:47:42Z" level=info msg=" Host: libstorage-server"
time="2017-03-22T10:47:42Z" level=info msg=" Libstorage-Tx: txID=922f67ce-2592-482a-7cd9-c2952ef497da, txCR=1490179662"
time="2017-03-22T10:47:42Z" level=info msg=" "
time="2017-03-22T10:47:42Z" level=info msg="http request" host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly time=1490179662720 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=schema-validator route=volumesForService server=foil-fish-ly time=1490179662720 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=storage-session-handler route=volumesForService server=foil-fish-ly time=1490179662720 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=service-validator route=volumesForService server=foil-fish-ly time=1490179662720 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=volumesForService server=foil-fish-ly time=1490179662721 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=local-devices-handler route=volumesForService server=foil-fish-ly time=1490179662721 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=instanceIDs-handler route=volumesForService server=foil-fish-ly time=1490179662721 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=error-handler route=volumesForService server=foil-fish-ly time=1490179662721 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=volumesForService server=foil-fish-ly time=1490179662721 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=logging-handler route=volumesForService server=foil-fish-ly time=1490179662722 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=query-params-handler route=volumesForService server=foil-fish-ly time=1490179662722 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="query param" host="unix:///var/run/libstorage/019760198.sock" key=attachments len(value)=1 route=volumesForService server=foil-fish-ly time=1490179662722 tls=false value=[15]
time="2017-03-22T10:47:42Z" level=debug msg="http header" Libstorage-Tx="txID=922f67ce-2592-482a-7cd9-c2952ef497da, txCR=1490179662" host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly time=1490179662722 tls=false
time="2017-03-22T10:47:42Z" level=debug msg="http header" Libstorage-Instanceid=[] host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly time=1490179662722 tls=false txCR=1490179662 txID=922f67ce-2592-482a-7cd9-c2952ef497da
time="2017-03-22T10:47:42Z" level=debug msg="http header" Libstorage-Localdevices=[] host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly time=1490179662723 tls=false txCR=1490179662 txID=922f67ce-2592-482a-7cd9-c2952ef497da
time="2017-03-22T10:47:42Z" level=debug msg="getting storage service" host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly service=azureud time=1490179662723 tls=false txCR=1490179662 txID=922f67ce-2592-482a-7cd9-c2952ef497da
time="2017-03-22T10:47:42Z" level=debug msg="login to azure storage driver" host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly service=azureud storageDriver=azureud time=1490179662723 tls=false txCR=1490179662 txID=922f67ce-2592-482a-7cd9-c2952ef497da
time="2017-03-22T10:47:42Z" level=info msg="Authenticating via clientSecret" host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly service=azureud storageDriver=azureud time=1490179662723 tls=false txCR=1490179662 txID=922f67ce-2592-482a-7cd9-c2952ef497da
time="2017-03-22T10:47:42Z" level=info msg="libStorage integration driver successfully initialized" host="unix:///var/run/libstorage/019760198.sock" integrationDriver=linux libstorage.integration.volume.operations.create.disable=false libstorage.integration.volume.operations.mount.preempt=false libstorage.integration.volume.operations.path.cache.async=true libstorage.integration.volume.operations.path.cache.enabled=true libstorage.integration.volume.operations.remove.disable=false libstorage.integration.volume.operations.unmount.ignoreusedcount=false osDriver=linux service=azureud storageDriver=libstorage time=1490179662739
time="2017-03-22T10:47:42Z" level=info msg="integration driver initialized" host="unix:///var/run/libstorage/019760198.sock" integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1490179662740
time="2017-03-22T10:47:42Z" level=info msg="created libStorage client" host="unix:///var/run/libstorage/019760198.sock" integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1490179662741
time="2017-03-22T10:47:42Z" level=debug msg="initializing module instance" host="unix:///var/run/libstorage/019760198.sock" name=default-docker time=1490179662741
time="2017-03-22T10:47:42Z" level=info msg="initialized module instance" address="unix:///run/docker/plugins/rexray.sock" host="unix:///var/run/libstorage/019760198.sock" name=default-docker time=1490179662741 typeName=docker
time="2017-03-22T10:47:42Z" level=info msg="started module" address="unix:///run/docker/plugins/rexray.sock" name=default-docker time=1490179662742 typeName=docker
time="2017-03-22T10:47:42Z" level=info msg="service successfully initialized, waiting on stop signal" time=1490179662742
time="2017-03-22T10:47:53Z" level=error msg="http: panic serving @: reflect: call of reflect.Value.Elem on struct Value\ngoroutine 130 [running]:\nnet/http.(*conn).serve.func1(0xc420194680)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:1491 +0x12a\npanic(0x1230140, 0xc42041f8e0)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/runtime/panic.go:458 +0x243\nreflect.Value.Elem(0x13b7f40, 0xc420488050, 0x99, 0xc420488050, 0x99, 0x1514063)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/reflect/value.go:734 +0x114\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf.fore(0xc42057e9c0, 0xb, 0x13b7f40, 0xc420488050, 0xc420425740)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/golf.go:170 +0x4f1\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf.fore(0x1478348, 0x5, 0x14109c0, 0xc42041f6a0, 0xc420425740)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/golf.go:215 +0x3d0\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf.Fore(0x1478348, 0x5, 0x14109c0, 0xc42041f6a0, 0xc420312901)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/golf.go:146 +0xa8\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus.(*TextFormatter).appendKeyValue(0xc4201ab020, 0xc42022a770, 0x1478348, 0x5, 0x14109c0, 0xc42041f6a0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus/text_formatter.go:176 +0xce\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus.(*TextFormatter).Format(0xc4201ab020, 0xc42039cbc0, 0x0, 0xc420312c40, 0x4f064e, 0x12c26e0, 0xc42000d5c0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus/text_formatter.go:89 +0x4a6\ngithub.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus.(*Entry).Reader(0xc42039cbc0, 0xc404a59d02, 0xc42039cbc0, 0x0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus/entry.go:44 +0x40\ngithub.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus.Entry.log(0xc42004c3c0, 0xc420425650, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8010102, 0xc420425710, ...)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus/entry.go:94 +0x200\ngithub.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus.(*Entry).Error(0xc42039cb40, 0xc42057e6c0, 0x1, 0x1)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus/entry.go:152 +0xd9\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context.(*entry).Error(0xc42057e6b0, 0xc42057e6c0, 0x1, 0x1)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context/context_logger.go:228 +0x7f\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context.WithStorageSession(0x1d81fe0, 0xc4203f1b00, 0xc4203f1b00, 0x1d81fe0, 0xc4203f1b00, 0x4f6060)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context/context.go:136 +0x44c\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*storageSessionHandler).Handle(0xc4202264e0, 0x1d898c0, 0xc4203f1b00, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0xc4204abcd0, 0x120b7e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_storage_session.go:38 +0x5d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*storageSessionHandler).Handle-fm(0x1d898c0, 0xc4203f1b00, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x4555f0, 0xc4201f6b40)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_storage_session.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*serviceValidator).Handle(0xc4202264e8, 0x1d898c0, 0xc4203f19e0, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0xc42004c3c0, 0xc4203f19e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_service_validator.go:50 +0x1a2\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*serviceValidator).Handle-fm(0x1d898c0, 0xc4203f19e0, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x0, 0x0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_service_validator.go:29 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*onRequestHandler).Handle(0xc4202264f8, 0x1d898c0, 0xc4203f19e0, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x1d898c0, 0xc4203f19e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_onrequest.go:43 +0x88\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*onRequestHandler).Handle-fm(0x1d898c0, 0xc4203f19e0, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x1413800, 0x214ef01)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_onrequest.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*localDevicesHandler).Handle(0xc420226500, 0x1d898c0, 0xc4203f1920, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x1d898c0, 0xc4203f1920)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_local_devices.go:51 +0x477\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*localDevicesHandler).Handle-fm(0x1d898c0, 0xc4203f1920, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x1513e24, 0xc41ffe10ef)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_local_devices.go:28 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*instanceIDHandler).Handle(0xc4204aa080, 0x1d898c0, 0xc4203f1860, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x4f4e3a, 0x1413800)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_instanceid.go:88 +0x6d8\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*instanceIDHandler).Handle-fm(0x1d898c0, 0xc4203f1860, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0xc4203f0720, 0x120b7e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_instanceid.go:35 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*errorHandler).Handle(0xc420226508, 0x1d898c0, 0xc4203f1860, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x1d898c0, 0xc4203f1860)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_errors.go:37 +0x8f\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*errorHandler).Handle-fm(0x1d898c0, 0xc4203f1860, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0xc420271658, 0x4102be)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_errors.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*transactionHandler).Handle(0xc420226518, 0x1d898c0, 0xc4203f0720, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x113fa01, 0xc4201f6f00)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_transaction.go:50 +0x26b\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*transactionHandler).Handle-fm(0x1d898c0, 0xc4203f0720, 0x1d80f20, 0xc4201f6f00, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0xc420271730, 0xc420271730)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_transaction.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*loggingHandler).Handle(0xc42041eca0, 0x1d898c0, 0xc4203f0720, 0x1d80ee0, 0xc4202a5ee0, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x0, 0x0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_logging.go:76 +0x248\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*loggingHandler).Handle-fm(0x1d898c0, 0xc4203f0720, 0x1d80ee0, 0xc4202a5ee0, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0xc400000000, 0x1)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_logging.go:52 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*queryParamsHandler).Handle(0xc420226520, 0x1d898c0, 0xc4203f0720, 0x1d80ee0, 0xc4202a5ee0, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x0, 0xffffffffffffffff)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_query_params.go:64 +0x543\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*queryParamsHandler).Handle-fm(0x1d898c0, 0xc4203f0720, 0x1d80ee0, 0xc4202a5ee0, 0xc4200bcb40, 0x1d87840, 0xc4201f68c0, 0x4939ab, 0x14499e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_query_params.go:29 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server.(*server).makeHTTPHandler.func1(0x1d80ee0, 0xc4202a5ee0, 0xc4200bcb40)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/server_http.go:193 +0x3d8\nnet/http.HandlerFunc.ServeHTTP(0xc42012fbf0, 0x1d80ee0, 0xc4202a5ee0, 0xc4200bcb40)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:1726 +0x44\ngithub.com/codedellemc/rexray/vendor/github.com/gorilla/mux.(*Router).ServeHTTP(0xc42028b6d0, 0x1d80ee0, 0xc4202a5ee0, 0xc4200bcb40)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/gorilla/mux/mux.go:114 +0x10d\nnet/http.serverHandler.ServeHTTP(0xc420194100, 0x1d80ee0, 0xc4202a5ee0, 0xc4200bcf00)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:2202 +0x7d\nnet/http.(*conn).serve(0xc420194680, 0x1d81ce0, 0xc42039d800)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:1579 +0x4b7\ncreated by net/http.(*Server).Serve\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:2293 +0x44d\n"
time="2017-03-22T10:47:53Z" level=info msg="http request" host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly time=1490179673082 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=schema-validator route=volumesForService server=foil-fish-ly time=1490179673082 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=storage-session-handler route=volumesForService server=foil-fish-ly time=1490179673082 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=service-validator route=volumesForService server=foil-fish-ly time=1490179673082 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=volumesForService server=foil-fish-ly time=1490179673082 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=local-devices-handler route=volumesForService server=foil-fish-ly time=1490179673082 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=instanceIDs-handler route=volumesForService server=foil-fish-ly time=1490179673082 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=error-handler route=volumesForService server=foil-fish-ly time=1490179673082 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=volumesForService server=foil-fish-ly time=1490179673082 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=logging-handler route=volumesForService server=foil-fish-ly time=1490179673083 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=query-params-handler route=volumesForService server=foil-fish-ly time=1490179673083 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="query param" host="unix:///var/run/libstorage/019760198.sock" key=attachments len(value)=1 route=volumesForService server=foil-fish-ly time=1490179673083 tls=false value=[15]
time="2017-03-22T10:47:53Z" level=debug msg="http header" Libstorage-Tx="txID=922f67ce-2592-482a-7cd9-c2952ef497da, txCR=1490179662" host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly time=1490179673083 tls=false
time="2017-03-22T10:47:53Z" level=debug msg="http header" Libstorage-Instanceid=[] host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly time=1490179673083 tls=false txCR=1490179662 txID=922f67ce-2592-482a-7cd9-c2952ef497da
time="2017-03-22T10:47:53Z" level=debug msg="http header" Libstorage-Localdevices=[] host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly time=1490179673083 tls=false txCR=1490179662 txID=922f67ce-2592-482a-7cd9-c2952ef497da
time="2017-03-22T10:47:53Z" level=debug msg="getting storage service" host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly service=azureud time=1490179673083 tls=false txCR=1490179662 txID=922f67ce-2592-482a-7cd9-c2952ef497da
time="2017-03-22T10:47:53Z" level=debug msg="login to azure storage driver" host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly service=azureud storageDriver=azureud time=1490179673083 tls=false txCR=1490179662 txID=922f67ce-2592-482a-7cd9-c2952ef497da
time="2017-03-22T10:47:53Z" level=info msg="Authenticating via clientSecret" host="unix:///var/run/libstorage/019760198.sock" route=volumesForService server=foil-fish-ly service=azureud storageDriver=azureud time=1490179673083 tls=false txCR=1490179662 txID=922f67ce-2592-482a-7cd9-c2952ef497da
time="2017-03-22T10:47:53Z" level=error msg="http: panic serving @: reflect: call of reflect.Value.Elem on struct Value\ngoroutine 219 [running]:\nnet/http.(*conn).serve.func1(0xc420826100)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:1491 +0x12a\npanic(0x1230140, 0xc42030e5a0)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/runtime/panic.go:458 +0x243\nreflect.Value.Elem(0x13b7f40, 0xc42031c000, 0x99, 0xc42031c000, 0x99, 0x1514063)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/reflect/value.go:734 +0x114\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf.fore(0xc42057e720, 0xb, 0x13b7f40, 0xc42031c000, 0xc4202f8540)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/golf.go:170 +0x4f1\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf.fore(0x1478348, 0x5, 0x14109c0, 0xc42030e3e0, 0xc4202f8540)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/golf.go:215 +0x3d0\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf.Fore(0x1478348, 0x5, 0x14109c0, 0xc42030e3e0, 0xc420316901)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/golf.go:146 +0xa8\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus.(*TextFormatter).appendKeyValue(0xc4201ab020, 0xc42022a460, 0x1478348, 0x5, 0x14109c0, 0xc42030e3e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus/text_formatter.go:176 +0xce\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus.(*TextFormatter).Format(0xc4201ab020, 0xc42039c400, 0x0, 0xc420316c40, 0x4f064e, 0x12c26e0, 0xc42000d5c0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus/text_formatter.go:89 +0x4a6\ngithub.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus.(*Entry).Reader(0xc42039c400, 0xc410ba9602, 0xc42039c400, 0x0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus/entry.go:44 +0x40\ngithub.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus.Entry.log(0xc42004c3c0, 0xc4202f8420, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x8010102, 0xc4202f8510, ...)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus/entry.go:94 +0x200\ngithub.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus.(*Entry).Error(0xc42039c380, 0xc42057e1f0, 0x1, 0x1)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus/entry.go:152 +0xd9\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context.(*entry).Error(0xc42057e1e0, 0xc42057e1f0, 0x1, 0x1)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context/context_logger.go:228 +0x7f\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context.WithStorageSession(0x1d81fe0, 0xc420059980, 0xc420059980, 0x1d81fe0, 0xc420059980, 0x4f6060)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context/context.go:136 +0x44c\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*storageSessionHandler).Handle(0xc42001e020, 0x1d898c0, 0xc420059980, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0xc42057fc98, 0x120b7e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_storage_session.go:38 +0x5d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*storageSessionHandler).Handle-fm(0x1d898c0, 0xc420059980, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x4555f0, 0xc420340200)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_storage_session.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*serviceValidator).Handle(0xc42001e050, 0x1d898c0, 0xc420059740, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0xc42004c3c0, 0xc420059740)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_service_validator.go:50 +0x1a2\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*serviceValidator).Handle-fm(0x1d898c0, 0xc420059740, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x0, 0x0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_service_validator.go:29 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*onRequestHandler).Handle(0xc42001e0b0, 0x1d898c0, 0xc420059740, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x1d898c0, 0xc420059740)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_onrequest.go:43 +0x88\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*onRequestHandler).Handle-fm(0x1d898c0, 0xc420059740, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x1413800, 0x214ef01)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_onrequest.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*localDevicesHandler).Handle(0xc42001e0c8, 0x1d898c0, 0xc420059680, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x1d898c0, 0xc420059680)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_local_devices.go:51 +0x477\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*localDevicesHandler).Handle-fm(0x1d898c0, 0xc420059680, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x1513e24, 0xc41ffc12ef)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_local_devices.go:28 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*instanceIDHandler).Handle(0xc420593690, 0x1d898c0, 0xc4200595c0, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x4f4e3a, 0x1413800)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_instanceid.go:88 +0x6d8\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*instanceIDHandler).Handle-fm(0x1d898c0, 0xc4200595c0, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0xc4200583c0, 0x120b7e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_instanceid.go:35 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*errorHandler).Handle(0xc42001e1f0, 0x1d898c0, 0xc4200595c0, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x1d898c0, 0xc4200595c0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_errors.go:37 +0x8f\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*errorHandler).Handle-fm(0x1d898c0, 0xc4200595c0, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0xc420271658, 0x4102be)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_errors.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*transactionHandler).Handle(0xc42001e1f8, 0x1d898c0, 0xc4200583c0, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x113fa01, 0xc420340cc0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_transaction.go:50 +0x26b\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*transactionHandler).Handle-fm(0x1d898c0, 0xc4200583c0, 0x1d80f20, 0xc420340cc0, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0xc420271730, 0xc420271730)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_transaction.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*loggingHandler).Handle(0xc4204487e0, 0x1d898c0, 0xc4200583c0, 0x1d80ee0, 0xc42013c410, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x0, 0x0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_logging.go:76 +0x248\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*loggingHandler).Handle-fm(0x1d898c0, 0xc4200583c0, 0x1d80ee0, 0xc42013c410, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0xc400000000, 0x1)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_logging.go:52 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*queryParamsHandler).Handle(0xc42001e200, 0x1d898c0, 0xc4200583c0, 0x1d80ee0, 0xc42013c410, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x0, 0xffffffffffffffff)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_query_params.go:64 +0x543\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*queryParamsHandler).Handle-fm(0x1d898c0, 0xc4200583c0, 0x1d80ee0, 0xc42013c410, 0xc42082a1e0, 0x1d87840, 0xc420340600, 0x4939ab, 0x14499e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_query_params.go:29 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server.(*server).makeHTTPHandler.func1(0x1d80ee0, 0xc42013c410, 0xc42082a1e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/server_http.go:193 +0x3d8\nnet/http.HandlerFunc.ServeHTTP(0xc42012fbf0, 0x1d80ee0, 0xc42013c410, 0xc42082a1e0)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:1726 +0x44\ngithub.com/codedellemc/rexray/vendor/github.com/gorilla/mux.(*Router).ServeHTTP(0xc42028b6d0, 0x1d80ee0, 0xc42013c410, 0xc42082a1e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/gorilla/mux/mux.go:114 +0x10d\nnet/http.serverHandler.ServeHTTP(0xc420194100, 0x1d80ee0, 0xc42013c410, 0xc42082a000)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:2202 +0x7d\nnet/http.(*conn).serve(0xc420826100, 0x1d81ce0, 0xc420340100)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:1579 +0x4b7\ncreated by net/http.(*Server).Serve\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:2293 +0x44d\n"
time="2017-03-22T10:47:53Z" level=error msg="error initializing the path cache" async=true error="Get http://libstorage-server/volumes/azureud?attachments=15: EOF" host="unix:///var/run/libstorage/019760198.sock" integrationDriver=linux osDriver=linux service=azureud storageDriver=libstorage time=1490179673283
time="2017-03-22T10:48:21Z" level=info msg="http request" host="unix:///var/run/libstorage/019760198.sock" route=services server=foil-fish-ly time=1490179701030 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=schema-validator route=services server=foil-fish-ly time=1490179701030 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=services server=foil-fish-ly time=1490179701030 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=local-devices-handler route=services server=foil-fish-ly time=1490179701030 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=instanceIDs-handler route=services server=foil-fish-ly time=1490179701030 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=error-handler route=services server=foil-fish-ly time=1490179701030 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=services server=foil-fish-ly time=1490179701030 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=logging-handler route=services server=foil-fish-ly time=1490179701030 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=query-params-handler route=services server=foil-fish-ly time=1490179701030 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="http header" Libstorage-Tx="txID=484012b0-23bc-4e5d-7a9d-3d7fd36589f5, txCR=1490179701" host="unix:///var/run/libstorage/019760198.sock" route=services server=foil-fish-ly time=1490179701030 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="http header" Libstorage-Instanceid=[] host="unix:///var/run/libstorage/019760198.sock" route=services server=foil-fish-ly time=1490179701030 tls=false txCR=1490179701 txID=484012b0-23bc-4e5d-7a9d-3d7fd36589f5
time="2017-03-22T10:48:21Z" level=debug msg="http header" Libstorage-Localdevices=[] host="unix:///var/run/libstorage/019760198.sock" route=services server=foil-fish-ly time=1490179701030 tls=false txCR=1490179701 txID=484012b0-23bc-4e5d-7a9d-3d7fd36589f5
time="2017-03-22T10:48:21Z" level=info msg="@ - - [22/Mar/2017:10:48:21 +0000] \"GET /services HTTP/1.1\" 200 221"
time="2017-03-22T10:48:21Z" level=info
time="2017-03-22T10:48:21Z" level=info msg=" -------------------------- HTTP REQUEST (SERVER) --------------------------"
time="2017-03-22T10:48:21Z" level=info msg=" GET /services HTTP/1.1"
time="2017-03-22T10:48:21Z" level=info msg=" Host: libstorage-server"
time="2017-03-22T10:48:21Z" level=info msg=" Accept-Encoding: gzip"
time="2017-03-22T10:48:21Z" level=info msg=" Libstorage-Tx: txID=484012b0-23bc-4e5d-7a9d-3d7fd36589f5, txCR=1490179701"
time="2017-03-22T10:48:21Z" level=info msg=" User-Agent: Go-http-client/1.1"
time="2017-03-22T10:48:21Z" level=info msg=" "
time="2017-03-22T10:48:21Z" level=info msg=" -------------------------- HTTP RESPONSE (SERVER) -------------------------"
time="2017-03-22T10:48:21Z" level=info msg=" Content-Type=application/json"
time="2017-03-22T10:48:21Z" level=info
time="2017-03-22T10:48:21Z" level=info msg=" {"
time="2017-03-22T10:48:21Z" level=info msg=" \"azureud\": {"
time="2017-03-22T10:48:21Z" level=info msg=" \"name\": \"azureud\","
time="2017-03-22T10:48:21Z" level=info msg=" \"driver\": {"
time="2017-03-22T10:48:21Z" level=info msg=" \"name\": \"azureud\","
time="2017-03-22T10:48:21Z" level=info msg=" \"type\": \"block\","
time="2017-03-22T10:48:21Z" level=info msg=" \"nextDevice\": {"
time="2017-03-22T10:48:21Z" level=info msg=" \"ignore\": false,"
time="2017-03-22T10:48:21Z" level=info msg=" \"prefix\": \"sd\","
time="2017-03-22T10:48:21Z" level=info msg=" \"pattern\": \"[c-z]\""
time="2017-03-22T10:48:21Z" level=info msg=" }"
time="2017-03-22T10:48:21Z" level=info msg=" }"
time="2017-03-22T10:48:21Z" level=info msg=" }"
time="2017-03-22T10:48:21Z" level=info msg=" }"
time="2017-03-22T10:48:21Z" level=info msg="http request" host="unix:///var/run/libstorage/019760198.sock" route=executors server=foil-fish-ly time=1490179701037 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=executors server=foil-fish-ly time=1490179701037 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=local-devices-handler route=executors server=foil-fish-ly time=1490179701037 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=instanceIDs-handler route=executors server=foil-fish-ly time=1490179701037 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=error-handler route=executors server=foil-fish-ly time=1490179701037 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=executors server=foil-fish-ly time=1490179701037 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=logging-handler route=executors server=foil-fish-ly time=1490179701037 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=query-params-handler route=executors server=foil-fish-ly time=1490179701037 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="http header" Libstorage-Tx="txID=de2005b5-3953-4f48-74f2-f5daa761cafc, txCR=1490179701" host="unix:///var/run/libstorage/019760198.sock" route=executors server=foil-fish-ly time=1490179701037 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="http header" Libstorage-Instanceid=[] host="unix:///var/run/libstorage/019760198.sock" route=executors server=foil-fish-ly time=1490179701037 tls=false txCR=1490179701 txID=de2005b5-3953-4f48-74f2-f5daa761cafc
time="2017-03-22T10:48:21Z" level=debug msg="http header" Libstorage-Localdevices=[] host="unix:///var/run/libstorage/019760198.sock" route=executors server=foil-fish-ly time=1490179701037 tls=false txCR=1490179701 txID=de2005b5-3953-4f48-74f2-f5daa761cafc
time="2017-03-22T10:48:21Z" level=info msg="@ - - [22/Mar/2017:10:48:21 +0000] \"GET /executors HTTP/1.1\" 200 157"
time="2017-03-22T10:48:21Z" level=info
time="2017-03-22T10:48:21Z" level=info msg=" -------------------------- HTTP REQUEST (SERVER) --------------------------"
time="2017-03-22T10:48:21Z" level=info msg=" GET /executors HTTP/1.1"
time="2017-03-22T10:48:21Z" level=info msg=" Host: libstorage-server"
time="2017-03-22T10:48:21Z" level=info msg=" Accept-Encoding: gzip"
time="2017-03-22T10:48:21Z" level=info msg=" Libstorage-Tx: txID=de2005b5-3953-4f48-74f2-f5daa761cafc, txCR=1490179701"
time="2017-03-22T10:48:21Z" level=info msg=" User-Agent: Go-http-client/1.1"
time="2017-03-22T10:48:21Z" level=info msg=" "
time="2017-03-22T10:48:21Z" level=info msg=" -------------------------- HTTP RESPONSE (SERVER) -------------------------"
time="2017-03-22T10:48:21Z" level=info msg=" Content-Type=application/json"
time="2017-03-22T10:48:21Z" level=info
time="2017-03-22T10:48:21Z" level=info msg=" {"
time="2017-03-22T10:48:21Z" level=info msg=" \"lsx-linux\": {"
time="2017-03-22T10:48:21Z" level=info msg=" \"name\": \"lsx-linux\","
time="2017-03-22T10:48:21Z" level=info msg=" \"md5checksum\": \"00f00faf344d273ee0a08bc344c18e44\","
time="2017-03-22T10:48:21Z" level=info msg=" \"size\": 11028077,"
time="2017-03-22T10:48:21Z" level=info msg=" \"lastModified\": 1487991614"
time="2017-03-22T10:48:21Z" level=info msg=" }"
time="2017-03-22T10:48:21Z" level=info msg=" }"
time="2017-03-22T10:48:21Z" level=info msg="http request" host="unix:///var/run/libstorage/019760198.sock" route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=post-args-handler route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=schema-validator route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=storage-session-handler route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added route middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=service-validator route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=local-devices-handler route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=instanceIDs-handler route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=error-handler route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=transaction-handler route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=logging-handler route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="added global middleware" host="unix:///var/run/libstorage/019760198.sock" middleware=query-params-handler route=volumeCreate server=foil-fish-ly time=1490179701159 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="http header" Libstorage-Tx="txID=164c52a1-ef0f-46ab-4b08-22a256a90874, txCR=1490179701" host="unix:///var/run/libstorage/019760198.sock" route=volumeCreate server=foil-fish-ly time=1490179701160 tls=false
time="2017-03-22T10:48:21Z" level=debug msg="http header" Libstorage-Instanceid=[] host="unix:///var/run/libstorage/019760198.sock" route=volumeCreate server=foil-fish-ly time=1490179701160 tls=false txCR=1490179701 txID=164c52a1-ef0f-46ab-4b08-22a256a90874
time="2017-03-22T10:48:21Z" level=debug msg="http header" Libstorage-Localdevices=[] host="unix:///var/run/libstorage/019760198.sock" route=volumeCreate server=foil-fish-ly time=1490179701160 tls=false txCR=1490179701 txID=164c52a1-ef0f-46ab-4b08-22a256a90874
time="2017-03-22T10:48:21Z" level=debug msg="getting storage service" host="unix:///var/run/libstorage/019760198.sock" route=volumeCreate server=foil-fish-ly service=azureud time=1490179701160 tls=false txCR=1490179701 txID=164c52a1-ef0f-46ab-4b08-22a256a90874
time="2017-03-22T10:48:21Z" level=debug msg="login to azure storage driver" host="unix:///var/run/libstorage/019760198.sock" route=volumeCreate server=foil-fish-ly service=azureud storageDriver=azureud time=1490179701160 tls=false txCR=1490179701 txID=164c52a1-ef0f-46ab-4b08-22a256a90874
time="2017-03-22T10:48:21Z" level=info msg="Authenticating via clientSecret" host="unix:///var/run/libstorage/019760198.sock" route=volumeCreate server=foil-fish-ly service=azureud storageDriver=azureud time=1490179701160 tls=false txCR=1490179701 txID=164c52a1-ef0f-46ab-4b08-22a256a90874
time="2017-03-22T10:48:21Z" level=error msg="http: panic serving @: reflect: call of reflect.Value.Elem on struct Value\ngoroutine 238 [running]:\nnet/http.(*conn).serve.func1(0xc420826400)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:1491 +0x12a\npanic(0x1230140, 0xc420426280)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/runtime/panic.go:458 +0x243\nreflect.Value.Elem(0x13b7f40, 0xc42031c050, 0x99, 0xc42031c050, 0x99, 0x1514063)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/reflect/value.go:734 +0x114\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf.fore(0xc4205926f0, 0xb, 0x13b7f40, 0xc42031c050, 0xc420424420)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/golf.go:170 +0x4f1\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf.fore(0x1478348, 0x5, 0x14109c0, 0xc420426180, 0xc420424420)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/golf.go:215 +0x3d0\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf.Fore(0x1478348, 0x5, 0x14109c0, 0xc420426180, 0xc420312901)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/golf.go:146 +0xa8\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus.(*TextFormatter).appendKeyValue(0xc4201ab020, 0xc42029db90, 0x1478348, 0x5, 0x14109c0, 0xc420426180)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus/text_formatter.go:176 +0xce\ngithub.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus.(*TextFormatter).Format(0xc4201ab020, 0xc420340840, 0x0, 0xc420312c40, 0x4f064e, 0x12c26e0, 0xc42000d5c0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/akutz/golf/logrus/text_formatter.go:89 +0x4a6\ngithub.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus.(*Entry).Reader(0xc420340840, 0xc416abf102, 0xc420340840, 0x0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus/entry.go:44 +0x40\ngithub.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus.Entry.log(0xc42004c3c0, 0xc420424330, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x7010102, 0xc4204243f0, ...)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus/entry.go:94 +0x200\ngithub.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus.(*Entry).Error(0xc420340800, 0xc4205922f0, 0x1, 0x1)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/Sirupsen/logrus/entry.go:152 +0xd9\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context.(*entry).Error(0xc4205922e0, 0xc4205922f0, 0x1, 0x1)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context/context_logger.go:228 +0x7f\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context.WithStorageSession(0x1d81fe0, 0xc4202da180, 0xc4202da180, 0x1d81fe0, 0xc4202da180, 0x4f6060)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/context/context.go:136 +0x44c\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*storageSessionHandler).Handle(0xc420226530, 0x1d898c0, 0xc4202da180, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0xc42033a1f8, 0x120b7e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_storage_session.go:38 +0x5d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*storageSessionHandler).Handle-fm(0x1d898c0, 0xc4202da180, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x4555f0, 0xc4201f54c0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_storage_session.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*serviceValidator).Handle(0xc420226538, 0x1d898c0, 0xc4207e7f80, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0xc42004c3c0, 0xc4207e7f80)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_service_validator.go:50 +0x1a2\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*serviceValidator).Handle-fm(0x1d898c0, 0xc4207e7f80, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x0, 0x0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_service_validator.go:29 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*onRequestHandler).Handle(0xc420226548, 0x1d898c0, 0xc4207e7f80, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x1d898c0, 0xc4207e7f80)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_onrequest.go:43 +0x88\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*onRequestHandler).Handle-fm(0x1d898c0, 0xc4207e7f80, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x1413800, 0x214ef01)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_onrequest.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*localDevicesHandler).Handle(0xc420226550, 0x1d898c0, 0xc4207e7ec0, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x1d898c0, 0xc4207e7ec0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_local_devices.go:51 +0x477\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*localDevicesHandler).Handle-fm(0x1d898c0, 0xc4207e7ec0, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x1513e24, 0xc41ffd2555)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_local_devices.go:28 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*instanceIDHandler).Handle(0xc4204103f0, 0x1d898c0, 0xc4207e7e00, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x4f4e3a, 0x1413800)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_instanceid.go:88 +0x6d8\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*instanceIDHandler).Handle-fm(0x1d898c0, 0xc4207e7e00, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0xc4207e7380, 0x120b7e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_instanceid.go:35 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*errorHandler).Handle(0xc420226558, 0x1d898c0, 0xc4207e7e00, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x1d898c0, 0xc4207e7e00)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_errors.go:37 +0x8f\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*errorHandler).Handle-fm(0x1d898c0, 0xc4207e7e00, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0xc420313648, 0x4102be)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_errors.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*transactionHandler).Handle(0xc420226570, 0x1d898c0, 0xc4207e7380, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x113fa01, 0xc4201f4900)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_transaction.go:50 +0x26b\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*transactionHandler).Handle-fm(0x1d898c0, 0xc4207e7380, 0x1d80f20, 0xc4201f4900, 0xc420406d20, 0x1d87840, 0xc420341f00, 0xc420313760, 0x4102be)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_transaction.go:27 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*loggingHandler).Handle(0xc4203f7680, 0x1d898c0, 0xc4207e7380, 0x1d80ee0, 0xc42013de10, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x0, 0x0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_logging.go:76 +0x248\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*loggingHandler).Handle-fm(0x1d898c0, 0xc4207e7380, 0x1d80ee0, 0xc42013de10, 0xc420406d20, 0x1d87840, 0xc420341f00, 0xc400000000, 0x1)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_logging.go:52 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*queryParamsHandler).Handle(0xc420226578, 0x1d898c0, 0xc4207e7380, 0x1d80ee0, 0xc42013de10, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x0, 0xffffffffffffffff)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_query_params.go:64 +0x543\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers.(*queryParamsHandler).Handle-fm(0x1d898c0, 0xc4207e7380, 0x1d80ee0, 0xc42013de10, 0xc420406d20, 0x1d87840, 0xc420341f00, 0x4939ab, 0x14499e0)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/handlers/handlers_query_params.go:29 +0x7d\ngithub.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server.(*server).makeHTTPHandler.func1(0x1d80ee0, 0xc42013de10, 0xc420406d20)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/codedellemc/libstorage/api/server/server_http.go:193 +0x3d8\nnet/http.HandlerFunc.ServeHTTP(0xc42024c7b0, 0x1d80ee0, 0xc42013de10, 0xc420406d20)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:1726 +0x44\ngithub.com/codedellemc/rexray/vendor/github.com/gorilla/mux.(*Router).ServeHTTP(0xc42028b6d0, 0x1d80ee0, 0xc42013de10, 0xc420406d20)\n\t/home/travis/gopath/src/github.com/codedellemc/rexray/vendor/github.com/gorilla/mux/mux.go:114 +0x10d\nnet/http.serverHandler.ServeHTTP(0xc420194100, 0x1d80ee0, 0xc42013de10, 0xc42082a690)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:2202 +0x7d\nnet/http.(*conn).serve(0xc420826400, 0x1d81ce0, 0xc42039cb00)\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:1579 +0x4b7\ncreated by net/http.(*Server).Serve\n\t/home/travis/.gimme/versions/go1.7.5.linux.amd64/src/net/http/server.go:2293 +0x44d\n"
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment