Navigation Menu

Skip to content

Instantly share code, notes, and snippets.

@clintkitson
Created October 13, 2017 15:45
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save clintkitson/941d7100ff0942cd51e2303034df1f2b to your computer and use it in GitHub Desktop.
Save clintkitson/941d7100ff0942cd51e2303034df1f2b to your computer and use it in GitHub Desktop.
root@ip-172-31-24-81:/home/ubuntu# REXRAY_DEBUG=true rexray start -s ebs &
[1] 11271
root@ip-172-31-24-81:/home/ubuntu# INFO[0000] DockerLegacyMode enabled=false time=1507909297072
DEBU[0000] trapped signals time=1507909297072
DEBU[0000] loading config buildType=client+agent+controller configFileName=rexray globalConfigFileExists=false globalConfigFilePath=/etc/rexray/rexray.yml ignoreExists=false time=1507909297072 userConfigFileExists=false userConfigFilePath=/root/.rexray/rexray.yml
DEBU[0000] cannot find global or user file buildType=client+agent+controller configFileName=rexray globalConfigFileExists=false globalConfigFilePath=/etc/rexray/rexray.yml ignoreExists=false time=1507909297072 userConfigFileExists=false userConfigFilePath=/root/.rexray/rexray.yml
DEBU[0000] loading config buildType=client+agent+controller configFileName=config globalConfigFileExists=false globalConfigFilePath=/etc/rexray/config.yml ignoreExists=true time=1507909297072 userConfigFileExists=false userConfigFilePath=/root/.rexray/config.yml
DEBU[0000] disabled config file exist check buildType=client+agent+controller configFileName=config globalConfigFileExists=false globalConfigFilePath=/etc/rexray/config.yml ignoreExists=true time=1507909297072 userConfigFileExists=false userConfigFilePath=/root/.rexray/config.yml
DEBU[0000] created new config buildType=client+agent+controller configFileName=config globalConfigFileExists=false globalConfigFilePath=/etc/rexray/config.yml ignoreExists=true time=1507909297072 userConfigFileExists=false userConfigFilePath=/root/.rexray/config.yml
DEBU[0000] initializing configuration
DEBU[0000] loading yaml for Global
DEBU[0000] loading yaml for CSI
DEBU[0000] usage template path path=/root/.rexray/usage.template time=1507909297074
DEBU[0000] loaded usage template source=UsageTemplate time=1507909297074
DEBU[0000] updating log level level=debug time=1507909297075
INFO[0000] updated log level logLevel=debug
DEBU[0000] created scoped scope new=rexray.cli parentScopes=rexray,
DEBU[0000] os.args time=1507909297079 val=[rexray start -s ebs]
INFO[0000] created pid file path=/var/run/rexray/rexray.pid pid=11271 time=1507909297079
DEBU[0000] host is empty; initiliazing default services time=1507909297094
INFO[0000] libStorage auto service mode enabled driver=ebs time=1507909297096
DEBU[0000] starting embedded libStorage server time=1507909297096
DEBU[0000] created scoped scope new=libstorage.server parentScopes=rexray.cli,rexray,
INFO[0000] configured logging libstorage.logging.httpRequests=false libstorage.logging.httpResponses=false libstorage.logging.level=debug server=misty-drop-ae time=1507909297175
DEBU[0000] parsed server auth property libstorage.server.auth.disabled=false server=misty-drop-ae time=1507909297199
DEBU[0000] parsed server auth property libstorage.server.auth.key= server=misty-drop-ae time=1507909297210
DEBU[0000] parsed server auth property libstorage.server.auth.alg=HS256 server=misty-drop-ae time=1507909297220
DEBU[0000] parsed server auth property libstorage.server.auth.allow=[] server=misty-drop-ae time=1507909297232
DEBU[0000] parsed server auth property libstorage.server.auth.deny=[] server=misty-drop-ae time=1507909297247
INFO[0000] configured global auth libstorage.server.auth.alg=HS256 libstorage.server.auth.allow=[] libstorage.server.auth.deny=[] libstorage.server.auth.disabled=false libstorage.server.auth.key= server=misty-drop-ae time=1507909297248
INFO[0000] initializing server server=misty-drop-ae time=1507909297248
INFO[0000] initializing server services server=misty-drop-ae time=1507909297248
DEBU[0000] configured result schema validation enabled=false server=misty-drop-ae time=1507909297252
DEBU[0000] got services map count=1 server=misty-drop-ae time=1507909297256
DEBU[0000] processing service config server=misty-drop-ae service=ebs time=1507909297256
DEBU[0000] getting scoped config for service scope=libstorage.server.services.ebs server=misty-drop-ae service=ebs time=1507909297256
DEBU[0000] created scoped scope new=libstorage.server.services.ebs parentScopes=libstorage.server,rexray.cli,rexray,
DEBU[0000] got driver name driverName=ebs server=misty-drop-ae service=ebs time=1507909297263
INFO[0000] storage driver initialized, using large device range: false
DEBU[0000] parsed server auth property libstorage.server.auth.disabled=false server=misty-drop-ae service=ebs time=1507909297664
DEBU[0000] parsed server auth property libstorage.server.auth.key= server=misty-drop-ae service=ebs time=1507909297703
DEBU[0000] parsed server auth property libstorage.server.auth.alg=HS256 server=misty-drop-ae service=ebs time=1507909297740
DEBU[0000] parsed server auth property libstorage.server.auth.allow=[] server=misty-drop-ae service=ebs time=1507909297780
DEBU[0000] parsed server auth property libstorage.server.auth.deny=[] server=misty-drop-ae service=ebs time=1507909297818
INFO[0000] configured service auth libstorage.server.auth.alg=HS256 libstorage.server.auth.allow=[] libstorage.server.auth.deny=[] libstorage.server.auth.disabled=false libstorage.server.auth.key= server=misty-drop-ae service=ebs time=1507909297818
INFO[0000] created new service server=misty-drop-ae service=ebs time=1507909297818
INFO[0000] initialized services server=misty-drop-ae time=1507909297818
INFO[0000] initializing default endpoint autoEndpointMode=unix server=misty-drop-ae time=1507909297833
INFO[0000] initializing auto unix endpoint endpoint=libstorage.server.endpoints.localhost server=misty-drop-ae time=1507909297840
DEBU[0000] endpoint info address=unix:///var/run/rexray/988235148.sock endpoint=libstorage.server.endpoints.localhost server=misty-drop-ae time=1507909297841
DEBU[0000] disabling tls for unix sockets server=misty-drop-ae time=1507909297841
INFO[0000] configured endpoint address=unix:///var/run/rexray/988235148.sock endpoint=localhost server=misty-drop-ae time=1507909297841
INFO[0000] server created server=misty-drop-ae time=1507909297841
INFO[0000] initialized endpoints server=misty-drop-ae time=1507909297841
INFO[0000] initialized router len(routes)=4 router=help-router server=misty-drop-ae time=1507909297841
INFO[0000] initialized router len(routes)=1 router=root-router server=misty-drop-ae time=1507909297841
INFO[0000] initialized router len(routes)=2 router=service-router server=misty-drop-ae time=1507909297841
INFO[0000] initialized router len(routes)=11 router=volume-router server=misty-drop-ae time=1507909297841
INFO[0000] initialized router len(routes)=6 router=snapshot-router server=misty-drop-ae time=1507909297841
INFO[0000] initialized router len(routes)=2 router=tasks-router server=misty-drop-ae time=1507909297841
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/help queries=[] route=version server=misty-drop-ae time=1507909297841 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/help/config queries=[] route=version server=misty-drop-ae time=1507909297842 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/help/env queries=[] route=version server=misty-drop-ae time=1507909297842 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/help/version queries=[] route=version server=misty-drop-ae time=1507909297842 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/ queries=[] route=root server=misty-drop-ae time=1507909297842 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/services queries=[] route=services server=misty-drop-ae time=1507909297842 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/services/{service} queries=[] route=serviceInspect server=misty-drop-ae time=1507909297842 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/volumes queries=[] route=volumes server=misty-drop-ae time=1507909297842 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/volumes/{service} queries=[] route=volumesForService server=misty-drop-ae time=1507909297842 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/volumes/{service}/{volumeID} queries=[] route=volumeInspect server=misty-drop-ae time=1507909297842 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=2 method=POST path=/volumes/{service} queries=[detach ] route=volumesDetachForService server=misty-drop-ae time=1507909297842 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=POST path=/volumes/{service} queries=[] route=volumeCreate server=misty-drop-ae time=1507909297843 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=2 method=POST path=/volumes/{service}/{volumeID} queries=[copy ] route=volumeCopy server=misty-drop-ae time=1507909297843 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=2 method=POST path=/volumes/{service}/{volumeID} queries=[snapshot ] route=volumeSnapshot server=misty-drop-ae time=1507909297843 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=2 method=POST path=/volumes/{service}/{volumeID} queries=[attach ] route=volumeAttach server=misty-drop-ae time=1507909297843 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=2 method=POST path=/volumes queries=[detach ] route=volumesDetachAll server=misty-drop-ae time=1507909297843 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=2 method=POST path=/volumes/{service}/{volumeID} queries=[detach ] route=volumeDetach server=misty-drop-ae time=1507909297843 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=DELETE path=/volumes/{service}/{volumeID} queries=[] route=volumeRemove server=misty-drop-ae time=1507909297843 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/snapshots queries=[] route=snapshots server=misty-drop-ae time=1507909297843 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/snapshots/{service} queries=[] route=snapshotsForService server=misty-drop-ae time=1507909297843 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/snapshots/{service}/{snapshotID} queries=[] route=snapshotInspect server=misty-drop-ae time=1507909297844 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=2 method=POST path=/snapshots/{service}/{snapshotID} queries=[create ] route=snapshotCreate server=misty-drop-ae time=1507909297844 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=2 method=POST path=/snapshots/{service}/{snapshotID} queries=[copy ] route=snapshotCopy server=misty-drop-ae time=1507909297844 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=DELETE path=/snapshots/{service}/{snapshotID} queries=[] route=snapshotRemove server=misty-drop-ae time=1507909297844 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/tasks queries=[] route=tasks server=misty-drop-ae time=1507909297844 tls=false
DEBU[0000] registered route host=unix:///var/run/rexray/988235148.sock len(queries)=0 method=GET path=/tasks/{taskID} queries=[] route=taskInspect server=misty-drop-ae time=1507909297844 tls=false
INFO[0000] api listening host=unix:///var/run/rexray/988235148.sock server=misty-drop-ae time=1507909297844 tls=false
INFO[0000] waiting for err or close signal server=misty-drop-ae time=1507909297844
INFO[0001] server started server=misty-drop-ae time=1507909298845
DEBU[0001] got host from new server address server.Addrs()[0] specHost=unix:///var/run/rexray/988235148.sock time=1507909298845
DEBU[0001] parseSafeHost - no change postParse=unix:///var/run/rexray/988235148.sock preParse=unix:///var/run/rexray/988235148.sock time=1507909298845
DEBU[0001] got host from new server address; updated specHost=unix:///var/run/rexray/988235148.sock time=1507909298845
DEBU[0001] set host in context host=unix:///var/run/rexray/988235148.sock time=1507909298845
DEBU[0001] set host in config host=unix:///var/run/rexray/988235148.sock time=1507909298845
DEBU[0001] created spec file host=unix:///var/run/rexray/988235148.sock path=/var/run/rexray/rexray.spec time=1507909298845
DEBU[0001] created scoped scope new=rexray.agent parentScopes=rexray.cli,rexray,
DEBU[0001] not starting embedded server; embedded mode disabled host=unix:///var/run/rexray/988235148.sock time=1507909298867
DEBU[0001] got modules map count=1 host=unix:///var/run/rexray/988235148.sock time=1507909298871
INFO[0001] rexray modules host=unix:///var/run/rexray/988235148.sock map=map[default-csi:map[type:csi desc:The default CSI module. host:/run/docker/plugins/rexray.sock disabled:false]] time=1507909298871
DEBU[0001] processing module config host=unix:///var/run/rexray/988235148.sock name=default-csi time=1507909298871
DEBU[0001] created scoped scope new=rexray.modules.default-csi parentScopes=rexray.agent,rexray.cli,rexray,
INFO[0001] libStorage context cancellation - waiting host=unix:///var/run/rexray/988235148.sock time=1507909298856
INFO[0001] created new mod config addr=/run/docker/plugins/rexray.sock desc=The default CSI module. host=unix:///var/run/rexray/988235148.sock name=default-csi time=1507909298906 type=csi
DEBU[0001] got configured modules host=unix:///var/run/rexray/988235148.sock len(modConfigs)=1 time=1507909298906
DEBU[0001] creating libStorage client for module instance host=unix:///var/run/rexray/988235148.sock name=default-csi time=1507909298914
DEBU[0001] created scoped scope new=libstorage.client parentScopes=rexray.modules.default-csi,rexray.agent,rexray.cli,rexray,
INFO[0002] configured logging host=unix:///var/run/rexray/988235148.sock libstorage.logging.httpRequests=false libstorage.logging.httpResponses=false libstorage.logging.level=debug time=1507909299448
INFO[0002] set client service name host=unix:///var/run/rexray/988235148.sock service=ebs serviceName=ebs time=1507909299464
DEBU[0002] got configured host address host=unix:///var/run/rexray/988235148.sock service=ebs storageDriver=libstorage time=1507909299494
DEBU[0002] disabling tls for unix sockets host=unix:///var/run/rexray/988235148.sock service=ebs storageDriver=libstorage time=1507909299518
DEBU[0002] getHost proto == "unix" getHost=libstorage-server host=unix:///var/run/rexray/988235148.sock service=ebs storageDriver=libstorage time=1507909299518
INFO[0002] created libStorage client clientType=integration disableKeepAlive=false enableInstanceIDHeaders=true enableLocalDevicesHeaders=true host=unix:///var/run/rexray/988235148.sock lAddr=libstorage-server logRequests=false logResponses=false lsxPath= service=ebs storageDriver=libstorage time=1507909299627
INFO[0002] registered custom context key externalID=Libstorage-Tx internalID=2 keyBitmask=2
INFO[0002] registered custom context key externalID=Libstorage-Instanceid internalID=3 keyBitmask=2
INFO[0002] registered custom context key externalID=Libstorage-Localdevices internalID=4 keyBitmask=2
INFO[0002] registered custom context key externalID=Authorization internalID=5 keyBitmask=2
DEBU[0002] successful connection host=unix:///var/run/rexray/988235148.sock service=ebs storageDriver=libstorage time=1507909299629
INFO[0002] http request host=unix:///var/run/rexray/988235148.sock route=services server=misty-drop-ae time=1507909299629 tls=false
DEBU[0002] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=schema-validator route=services server=misty-drop-ae time=1507909299629 tls=false
DEBU[0002] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=auth-svc-handler route=services server=misty-drop-ae time=1507909299629 tls=false
DEBU[0002] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=transaction-handler route=services server=misty-drop-ae time=1507909299629 tls=false
DEBU[0002] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=local-devices-handler route=services server=misty-drop-ae time=1507909299629 tls=false
DEBU[0002] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=instanceIDs-handler route=services server=misty-drop-ae time=1507909299629 tls=false
DEBU[0002] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=auth-global-handler route=services server=misty-drop-ae time=1507909299629 tls=false
DEBU[0002] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=error-handler route=services server=misty-drop-ae time=1507909299629 tls=false
DEBU[0002] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=transaction-handler route=services server=misty-drop-ae time=1507909299629 tls=false
DEBU[0002] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=query-params-handler route=services server=misty-drop-ae time=1507909299630 tls=false
DEBU[0002] http header Libstorage-Tx=txID=45ae4008-ca1f-403e-513c-b85d7f2b56f5, txCR=1507909299 host=unix:///var/run/rexray/988235148.sock route=services server=misty-drop-ae time=1507909299630 tls=false
DEBU[0002] skipping global auth handler; empty allow & deny lists host=unix:///var/run/rexray/988235148.sock route=services server=misty-drop-ae time=1507909299630 tls=false txCR=1507909299 txID=45ae4008-ca1f-403e-513c-b85d7f2b56f5
DEBU[0002] http header Libstorage-Instanceid=[] host=unix:///var/run/rexray/988235148.sock route=services server=misty-drop-ae time=1507909299630 tls=false txCR=1507909299 txID=45ae4008-ca1f-403e-513c-b85d7f2b56f5
DEBU[0002] http header Libstorage-Localdevices=[] host=unix:///var/run/rexray/988235148.sock route=services server=misty-drop-ae time=1507909299630 tls=false txCR=1507909299 txID=45ae4008-ca1f-403e-513c-b85d7f2b56f5
DEBU[0002] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/rexray/988235148.sock route=services server=misty-drop-ae time=1507909299630 tls=false txCR=1507909299 txID=45ae4008-ca1f-403e-513c-b85d7f2b56f5
DEBU[0002] validated all services access host=unix:///var/run/rexray/988235148.sock route=services server=misty-drop-ae time=1507909299630 tls=false txCR=1507909299 txID=45ae4008-ca1f-403e-513c-b85d7f2b56f5
INFO[0002] initializing supported cache host=unix:///var/run/rexray/988235148.sock server=misty-drop-ae service=ebs storageDriver=libstorage time=1507909299630
DEBU[0002] executor using large device range: false
DEBU[0002] cached supported flag host=unix:///var/run/rexray/988235148.sock server=misty-drop-ae service=ebs storageDriver=libstorage supported=15 time=1507909299973 txCR=1507909299 txID=c6374ebc-2719-408c-59ca-5c88960cda26
INFO[0002] initializing instance ID cache host=unix:///var/run/rexray/988235148.sock server=misty-drop-ae service=ebs storageDriver=libstorage time=1507909299974
DEBU[0003] executor using large device range: false
DEBU[0003] cached instanceID host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 server=misty-drop-ae service=ebs storageDriver=libstorage time=1507909300302 txCR=1507909299 txID=cefb70be-94ac-4f28-574f-8d2bbb9c8ba2
DEBU[0003] xli instanceID success host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 server=misty-drop-ae service=ebs storageDriver=libstorage time=1507909300302 txCR=1507909299 txID=cefb70be-94ac-4f28-574f-8d2bbb9c8ba2
INFO[0003] successefully dialed libStorage server host=unix:///var/run/rexray/988235148.sock server=misty-drop-ae service=ebs storageDriver=libstorage time=1507909300303
INFO[0003] storage driver initialized host=unix:///var/run/rexray/988235148.sock service=ebs storageDriver=libstorage time=1507909300303
INFO[0003] os driver initialized host=unix:///var/run/rexray/988235148.sock osDriver=linux service=ebs storageDriver=libstorage time=1507909300318
INFO[0003] linux integration driver successfully initialized host=unix:///var/run/rexray/988235148.sock integrationDriver=linux libstorage.integration.volume.operations.create.default.IOPS=0 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/rexray/volumes libstorage.integration.volume.operations.mount.rootPath=/data osDriver=linux service=ebs storageDriver=libstorage time=1507909300475
INFO[0003] initializing the path cache async=true host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909300564
DEBU[0003] listing volumes host=unix:///var/run/rexray/988235148.sock integrationDriver=linux opts=map[attachments:true] osDriver=linux service=ebs storageDriver=libstorage time=1507909300564
INFO[0003] libStorage integration driver successfully initialized host=unix:///var/run/rexray/988235148.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=ebs storageDriver=libstorage time=1507909300724
INFO[0003] integration driver initialized host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909300726
INFO[0003] created libStorage client host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909300726
DEBU[0003] initializing module instance host=unix:///var/run/rexray/988235148.sock integrationDriver=linux name=default-csi osDriver=linux storageDriver=libstorage time=1507909300726
INFO[0003] set mod service name host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs serviceName=ebs storageDriver=libstorage time=1507909300732
INFO[0003] configuring csi module's driver csi.driver=libstorage host=unix:///var/run/rexray/988235148.sock integrationDriver=linux mod.name=default-csi osDriver=linux service=ebs storageDriver=libstorage time=1507909300760
INFO[0003] initialized module instance address=/run/docker/plugins/rexray.sock host=unix:///var/run/rexray/988235148.sock integrationDriver=linux name=default-csi osDriver=linux service=ebs storageDriver=libstorage time=1507909300760 typeName=csi
INFO[0003] multiplexed csi+docker endpoint host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs sockFile=/run/docker/plugins/rexray.sock storageDriver=libstorage time=1507909300792
INFO[0003] started module address=/run/docker/plugins/rexray.sock host=unix:///var/run/rexray/988235148.sock name=default-csi time=1507909300794 typeName=csi
INFO[0003] agent successfully initialized, waiting on stop signal host=unix:///var/run/rexray/988235148.sock time=1507909300794
⌐▄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.11.0-rc2
OsArch: Linux-x86_64
Commit: 071d5b3c21dc0e5372c29236560a08ad791ea360
Formed: Fri, 13 Oct 2017 00:05:27 UTC
INFO[0003] init csi libstorage bridge w ctx.config host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909300795
DEBU[0003] found cached instance ID host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux server=misty-drop-ae service=ebs storageDriver=libstorage time=1507909300802 txCR=1507909300 txID=4e000c93-68dd-4a39-4f2e-add5653c4fec
INFO[0003] agent context cancellation - waiting host=unix:///var/run/rexray/988235148.sock time=1507909300825
INFO[0003] /csi.Controller/ListVolumes: REQ 0001: MaxEntries=0 host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909300889
DEBU[0004] executor using large device range: false
DEBU[0004] xli localdevices success host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 integrationDriver=linux osDriver=linux server=misty-drop-ae service=ebs storageDriver=libstorage time=1507909301297 txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
INFO[0004] http request host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909301312 tls=false
DEBU[0004] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=schema-validator route=volumesForService server=misty-drop-ae time=1507909301312 tls=false
DEBU[0004] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=storage-session-handler route=volumesForService server=misty-drop-ae time=1507909301312 tls=false
DEBU[0004] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=auth-svc-handler route=volumesForService server=misty-drop-ae time=1507909301332 tls=false
DEBU[0004] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=service-validator route=volumesForService server=misty-drop-ae time=1507909301332 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=transaction-handler route=volumesForService server=misty-drop-ae time=1507909301332 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=local-devices-handler route=volumesForService server=misty-drop-ae time=1507909301332 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=instanceIDs-handler route=volumesForService server=misty-drop-ae time=1507909301332 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=auth-global-handler route=volumesForService server=misty-drop-ae time=1507909301332 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=error-handler route=volumesForService server=misty-drop-ae time=1507909301332 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=transaction-handler route=volumesForService server=misty-drop-ae time=1507909301333 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=query-params-handler route=volumesForService server=misty-drop-ae time=1507909301333 tls=false
DEBU[0004] query param host=unix:///var/run/rexray/988235148.sock key=attachments len(value)=1 route=volumesForService server=misty-drop-ae time=1507909301333 tls=false value=[15]
DEBU[0004] http header Libstorage-Tx=txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2, txCR=1507909300 host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909301333 tls=false
DEBU[0004] skipping global auth handler; empty allow & deny lists host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909301333 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
DEBU[0004] http header Libstorage-Instanceid=[ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1] host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909301333 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
DEBU[0004] http header Libstorage-Localdevices=[ebs=] host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909301333 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
DEBU[0004] getting storage service host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae service=ebs time=1507909301333 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
DEBU[0004] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs time=1507909301333 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
DEBU[0004] ebs service connetion attempt accessKey= cacheKey=82e2e20fdd4dde71b0c658060ed53dba endpoint=ec2.us-west-1.amazonaws.com region=us-west-1 secretKey= tag=
INFO[0004] ebs service connetion created & cached accessKey= cacheKey=82e2e20fdd4dde71b0c658060ed53dba endpoint=ec2.us-west-1.amazonaws.com region=us-west-1 secretKey= tag=
DEBU[0004] getting task service host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs time=1507909301430 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
DEBU[0004] getting task service host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs time=1507909301436 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
INFO[0004] executing task host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301437 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
DEBU[0004] querying volumes attachments=15 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301437 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
DEBU[0004] executor using large device range: false
DEBU[0004] xli localdevices success host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 integrationDriver=linux osDriver=linux server=misty-drop-ae service=ebs storageDriver=libstorage time=1507909301495 txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] successful connection host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux server=misty-drop-ae service=ebs storageDriver=libstorage time=1507909301495
INFO[0004] http request host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909301496 tls=false
DEBU[0004] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=schema-validator route=volumesForService server=misty-drop-ae time=1507909301496 tls=false
DEBU[0004] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=storage-session-handler route=volumesForService server=misty-drop-ae time=1507909301496 tls=false
DEBU[0004] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=auth-svc-handler route=volumesForService server=misty-drop-ae time=1507909301496 tls=false
DEBU[0004] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=service-validator route=volumesForService server=misty-drop-ae time=1507909301496 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=transaction-handler route=volumesForService server=misty-drop-ae time=1507909301496 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=local-devices-handler route=volumesForService server=misty-drop-ae time=1507909301496 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=instanceIDs-handler route=volumesForService server=misty-drop-ae time=1507909301496 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=auth-global-handler route=volumesForService server=misty-drop-ae time=1507909301496 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=error-handler route=volumesForService server=misty-drop-ae time=1507909301496 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=transaction-handler route=volumesForService server=misty-drop-ae time=1507909301496 tls=false
DEBU[0004] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=query-params-handler route=volumesForService server=misty-drop-ae time=1507909301497 tls=false
DEBU[0004] query param host=unix:///var/run/rexray/988235148.sock key=attachments len(value)=1 route=volumesForService server=misty-drop-ae time=1507909301497 tls=false value=[0]
DEBU[0004] http header Libstorage-Tx=txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae, txCR=1507909300 host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909301497 tls=false
DEBU[0004] skipping global auth handler; empty allow & deny lists host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909301497 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] http header Libstorage-Instanceid=[ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1] host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909301497 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] http header Libstorage-Localdevices=[ebs=] host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909301497 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] getting storage service host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae service=ebs time=1507909301497 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs time=1507909301497 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] using cached ebs service cacheKey=82e2e20fdd4dde71b0c658060ed53dba
DEBU[0004] getting task service host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs time=1507909301497 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] getting task service host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs time=1507909301501 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] manually calculating attachment state attachments=15 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301652 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2 volumeID=vol-0e3f1ba2d5053a994 volumeName=test
DEBU[0004] omitting unavailable volume attachmentState=unavailable attachments=15 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301652 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2 volumeID=vol-0e3f1ba2d5053a994 volumeName=test
DEBU[0004] manually calculating attachment state attachments=15 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301652 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2 volumeID=vol-0822112f81dcfc57a volumeName=
DEBU[0004] omitting unavailable volume attachmentState=unavailable attachments=15 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301652 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2 volumeID=vol-0822112f81dcfc57a volumeName=
DEBU[0004] manually calculating attachment state attachments=15 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301653 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2 volumeID=vol-02e1bf5e1ca350b39 volumeName=
DEBU[0004] including volume attachmentState=attached attachments=15 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301653 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2 volumeID=vol-02e1bf5e1ca350b39 volumeName=
DEBU[0004] manually calculating attachment state attachments=15 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301653 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2 volumeID=vol-07961e54f3cd9c9d8 volumeName=test1
DEBU[0004] omitting unattached volume attachmentState=available attachments=15 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301653 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2 volumeID=vol-07961e54f3cd9c9d8 volumeName=test1
DEBU[0004] manually calculating attachment state attachments=15 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301653 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2 volumeID=vol-02b61cc6e6e244c80 volumeName=test2
DEBU[0004] omitting unattached volume attachmentState=available attachments=15 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301657 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2 volumeID=vol-02b61cc6e6e244c80 volumeName=test2
DEBU[0004] skipping response schema validation; disabled host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301657 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
DEBU[0004] task completed host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909301657 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
INFO[0004] executing task host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=1 time=1507909301657 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] querying volumes attachments=0 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=1 time=1507909301657 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] removing task host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 removedAfter=0s route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 tasksLen=2 time=1507909301662 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
DEBU[0004] removed task host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 tasksLen=1 time=1507909301662 tls=false txCR=1507909300 txID=1f03c1f5-316d-41f2-71e2-7c527cf7c8c2
DEBU[0004] initialized the path cache async=true host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909301673
DEBU[0004] skipping response schema validation; disabled host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=1 time=1507909301698 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] task completed host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=1 time=1507909301698 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] removing task host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 removedAfter=0s route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=1 tasksLen=1 time=1507909301703 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
DEBU[0004] removed task host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=1 tasksLen=0 time=1507909301703 tls=false txCR=1507909300 txID=4411e271-1672-4d3e-72bb-cb09bd3d2cae
INFO[0004] /csi.Controller/ListVolumes: REP 0001: Reply=&{entries:<volume_info:<capacity_bytes:17179869184 id:<values:<key:"id" value:"vol-02b61cc6e6e244c80" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"name" value:"test2" > values:<key:"status" value:"available" > values:<key:"type" value:"standard" > > > > entries:<volume_info:<capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-02e1bf5e1ca350b39" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > > > > entries:<volume_info:<capacity_bytes:17179869184 id:<values:<key:"id" value:"vol-07961e54f3cd9c9d8" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"name" value:"test1" > values:<key:"status" value:"available" > values:<key:"type" value:"standard" > > > > entries:<volume_info:<capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-0822112f81dcfc57a" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > > > > entries:<volume_info:<capacity_bytes:1073741824 id:<values:<key:"id" value:"vol-0e3f1ba2d5053a994" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"name" value:"test" > values:<key:"status" value:"in-use" > values:<key:"type" value:"standard" > > > > } host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909301703
DEBU[0004] docker-csi-bridge: List: found volume host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909301704 volume=map[id:vol-02b61cc6e6e244c80]
WARN[0004] docker-csi-bridge: List: skipped volume w missing id and name host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909301704 volume=capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-02e1bf5e1ca350b39" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > >
DEBU[0004] docker-csi-bridge: List: found volume host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909301704 volume=map[id:vol-07961e54f3cd9c9d8]
WARN[0004] docker-csi-bridge: List: skipped volume w missing id and name host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909301704 volume=capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-0822112f81dcfc57a" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > >
DEBU[0004] docker-csi-bridge: List: found volume host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909301704 volume=map[id:vol-0e3f1ba2d5053a994]
DEBU[0004] docker-csi-bridge: failed to cache id/name: capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-02e1bf5e1ca350b39" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > > host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909301704
DEBU[0004] docker-csi-bridge: failed to cache id/name: capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-0822112f81dcfc57a" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > > host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909301704
root@ip-172-31-24-81:/home/ubuntu#
root@ip-172-31-24-81:/home/ubuntu#
root@ip-172-31-24-81:/home/ubuntu#
root@ip-172-31-24-81:/home/ubuntu# docker volume ls
INFO[0009] Entering go-plugins-helpers listPath time=1507909306604
INFO[0009] /csi.Controller/ListVolumes: REQ 0002: MaxEntries=0 host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909306604
DEBU[0009] executor using large device range: false
DEBU[0009] xli localdevices success host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 integrationDriver=linux osDriver=linux server=misty-drop-ae service=ebs storageDriver=libstorage time=1507909306971 txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
INFO[0009] http request host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909306971 tls=false
DEBU[0009] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=schema-validator route=volumesForService server=misty-drop-ae time=1507909306971 tls=false
DEBU[0009] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=storage-session-handler route=volumesForService server=misty-drop-ae time=1507909306971 tls=false
DEBU[0009] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=auth-svc-handler route=volumesForService server=misty-drop-ae time=1507909306971 tls=false
DEBU[0009] added route middleware host=unix:///var/run/rexray/988235148.sock middleware=service-validator route=volumesForService server=misty-drop-ae time=1507909306972 tls=false
DEBU[0009] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=transaction-handler route=volumesForService server=misty-drop-ae time=1507909306972 tls=false
DEBU[0009] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=local-devices-handler route=volumesForService server=misty-drop-ae time=1507909306972 tls=false
DEBU[0009] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=instanceIDs-handler route=volumesForService server=misty-drop-ae time=1507909306972 tls=false
DEBU[0009] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=auth-global-handler route=volumesForService server=misty-drop-ae time=1507909306972 tls=false
DEBU[0009] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=error-handler route=volumesForService server=misty-drop-ae time=1507909306972 tls=false
DEBU[0009] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=transaction-handler route=volumesForService server=misty-drop-ae time=1507909306972 tls=false
DEBU[0009] added global middleware host=unix:///var/run/rexray/988235148.sock middleware=query-params-handler route=volumesForService server=misty-drop-ae time=1507909306972 tls=false
DEBU[0009] query param host=unix:///var/run/rexray/988235148.sock key=attachments len(value)=1 route=volumesForService server=misty-drop-ae time=1507909306972 tls=false value=[0]
DEBU[0009] http header Libstorage-Tx=txID=96be04c1-6b6b-4084-60f3-65ea067ef437, txCR=1507909306 host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909306972 tls=false
DEBU[0009] skipping global auth handler; empty allow & deny lists host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909306972 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
DEBU[0009] http header Libstorage-Instanceid=[ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1] host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909306972 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
DEBU[0009] http header Libstorage-Localdevices=[ebs=] host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae time=1507909306972 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
DEBU[0009] getting storage service host=unix:///var/run/rexray/988235148.sock route=volumesForService server=misty-drop-ae service=ebs time=1507909306973 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
DEBU[0009] skipping svc auth handler; empty allow & deny lists host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs time=1507909306973 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
DEBU[0009] using cached ebs service cacheKey=82e2e20fdd4dde71b0c658060ed53dba
DEBU[0009] getting task service host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs time=1507909306973 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
DEBU[0009] getting task service host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs time=1507909306977 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
INFO[0009] executing task host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909306977 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
DEBU[0009] querying volumes attachments=0 host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909306977 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
DEBU[0010] skipping response schema validation; disabled host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909307163 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
DEBU[0010] task completed host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 time=1507909307164 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
DEBU[0010] removing task host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 removedAfter=0s route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 tasksLen=1 time=1507909307168 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
DEBU[0010] removed task host=unix:///var/run/rexray/988235148.sock instanceID=ebs=i-0a0b15a7edd48739f,availabilityZone=us-west-1c&region=us-west-1 route=volumesForService server=misty-drop-ae service=ebs storageDriver=ebs task=0 tasksLen=0 time=1507909307168 tls=false txCR=1507909306 txID=96be04c1-6b6b-4084-60f3-65ea067ef437
INFO[0010] /csi.Controller/ListVolumes: REP 0002: Reply=&{entries:<volume_info:<capacity_bytes:17179869184 id:<values:<key:"id" value:"vol-02b61cc6e6e244c80" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"name" value:"test2" > values:<key:"status" value:"available" > values:<key:"type" value:"standard" > > > > entries:<volume_info:<capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-02e1bf5e1ca350b39" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > > > > entries:<volume_info:<capacity_bytes:17179869184 id:<values:<key:"id" value:"vol-07961e54f3cd9c9d8" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"name" value:"test1" > values:<key:"status" value:"available" > values:<key:"type" value:"standard" > > > > entries:<volume_info:<capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-0822112f81dcfc57a" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > > > > entries:<volume_info:<capacity_bytes:1073741824 id:<values:<key:"id" value:"vol-0e3f1ba2d5053a994" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"name" value:"test" > values:<key:"status" value:"in-use" > values:<key:"type" value:"standard" > > > > } host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909307169
DEBU[0010] docker-csi-bridge: List: found volume host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909307169 volume=map[id:vol-02b61cc6e6e244c80]
WARN[0010] docker-csi-bridge: List: skipped volume w missing id and name host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909307169 volume=capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-02e1bf5e1ca350b39" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > >
DEBU[0010] docker-csi-bridge: List: found volume host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909307169 volume=map[id:vol-07961e54f3cd9c9d8]
WARN[0010] docker-csi-bridge: List: skipped volume w missing id and name host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909307169 volume=capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-0822112f81dcfc57a" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > >
DEBU[0010] docker-csi-bridge: List: found volume host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909307169 volume=map[id:vol-0e3f1ba2d5053a994]
DEBU[0010] docker-csi-bridge: failed to cache id/name: capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-02e1bf5e1ca350b39" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > > host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909307170
DEBU[0010] docker-csi-bridge: failed to cache id/name: capacity_bytes:8589934592 id:<values:<key:"id" value:"vol-0822112f81dcfc57a" > > metadata:<values:<key:"availabilityzone" value:"us-west-1c" > values:<key:"iops" value:"100" > values:<key:"status" value:"in-use" > values:<key:"type" value:"gp2" > > host=unix:///var/run/rexray/988235148.sock integrationDriver=linux osDriver=linux service=ebs storageDriver=libstorage time=1507909307170
error during connect: Get http://%2Fvar%2Frun%2Fdocker.sock/v1.32/volumes: read unix @->/var/run/docker.sock: read: connection reset by peer
root@ip-172-31-24-81:/home/ubuntu# INFO[0011] Entering go-plugins-helpers capabilitiesPath time=1507909308362
INFO[0011] Entering go-plugins-helpers getPath time=1507909308362
INFO[0011] Entering go-plugins-helpers getPath time=1507909308363
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment