Binding faild

[cloudfoundry]

Binding faild

[cf-gitbot]

We have created an issue in Pivotal Tracker to manage this:

https://www.pivotaltracker.com/story/show/143647365

The labels on this github issue will be updated when the story is started.

[ChaosEternal]

the app log of nfs:

2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] net/http.(*conn).serve.func1(0xc42024c400)
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] /var/vcap/data/packages/golang-nfsvolume/abfe2c31395310a3586c0344fb473361dd813439/src/net/http/server.go:1491 +0x12a
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] 2017/04/13 18:09:53 http: panic serving 192.168.2.78:57294: interface conversion: interface is float64, not string
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] goroutine 127 [running]:
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] code.cloudfoundry.org/nfsbroker/nfsbroker.(*Broker).Bind(0xc4200a2510, 0x9e2c40, 0xc420264240, 0xc42024c49a, 0x24, 0xc42024c4d0, 0x24, 0xc4202642d0, 0x24, 0xc4202351b0, ...)
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] github.com/pivotal-cf/brokerapi.(serviceBrokerHandler).(github.com/pivotal-cf/brokerapi.bind)-fm(0x9e2300, 0xc42022e340, 0xc420230e10)
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] /var/vcap/data/packages/golang-nfsvolume/abfe2c31395310a3586c0344fb473361dd813439/src/net/http/server.go:1726 +0x44
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] github.com/gorilla/mux.(*Router).ServeHTTP(0xc42006e870, 0x9e2300, 0xc42022e340, 0xc420230e10)
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] /var/vcap/packages/nfsbroker/src/code.cloudfoundry.org/nfsbroker/nfsbroker/nfsbroker.go:219 +0x827
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] /var/vcap/packages/nfsbroker/src/github.com/pivotal-cf/brokerapi/api.go:60 +0x69
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] net/http.HandlerFunc.ServeHTTP(0xc420160990, 0x9e2300, 0xc42022e340, 0xc420230e10)
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] /var/vcap/packages/nfsbroker/src/github.com/gorilla/mux/mux.go:114 +0x10d
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] /var/vcap/packages/nfsbroker/src/github.com/pivotal-cf/brokerapi/api.go:254 +0x4c4
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] /var/vcap/data/packages/golang-nfsvolume/abfe2c31395310a3586c0344fb473361dd813439/src/runtime/panic.go:458 +0x243
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] github.com/pivotal-cf/brokerapi.serviceBrokerHandler.bind(0x9e4b80, 0xc4200a2510, 0x9e4c40, 0xc420070960, 0x9e2300, 0xc42022e340, 0xc420230e10)
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] panic(0x7b6a60, 0xc42025e700)
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] github.com/pivotal-cf/brokerapi/auth.(*Wrapper).Wrap.func1(0x9e2300, 0xc42022e340, 0xc420230c30)
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] /var/vcap/packages/nfsbroker/src/github.com/pivotal-cf/brokerapi/auth/auth.go:26 +0x85
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] net/http.HandlerFunc.ServeHTTP(0xc4200dbe60, 0x9e2300, 0xc42022e340, 0xc420230c30)
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] net/http.serverHandler.ServeHTTP(0xc420178000, 0x9e2300, 0xc42022e340, 0xc420230c30)
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] /var/vcap/data/packages/golang-nfsvolume/abfe2c31395310a3586c0344fb473361dd813439/src/net/http/server.go:2202 +0x7d
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] net/http.(*conn).serve(0xc42024c400, 0x9e2b80, 0xc42025e500)
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] /var/vcap/data/packages/golang-nfsvolume/abfe2c31395310a3586c0344fb473361dd813439/src/net/http/server.go:1579 +0x4b7
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] /var/vcap/data/packages/golang-nfsvolume/abfe2c31395310a3586c0344fb473361dd813439/src/net/http/server.go:2293 +0x44d
2017-04-14T02:09:53.852+08:00 [APP/PROC/WEB/0] [ERR] created by net/http.(*Server).Serve
2017-04-14T02:09:54.922+08:00 [APP/PROC/WEB/0] [OUT] {"timestamp":"1492106994.922502518","source":"nfsbroker","message":"nfsbroker.unbind.start","log_level":1,"data":{"session":"26"}}
2017-04-14T02:09:54.922+08:00 [APP/PROC/WEB/0] [OUT] {"timestamp":"1492106994.922618389","source":"nfsbroker","message":"nfsbroker.unbind.save-state.start","log_level":1,"data":{"bindingId":"c81a6e37-5aa7-409d-83ba-28650eaa481a","instanceId":"","session":"26.1"}}
2017-04-14T02:09:54.924+08:00 [APP/PROC/WEB/0] [OUT] {"timestamp":"1492106994.924230337","source":"nfsbroker","message":"nfsbroker.unbind.save-state.end","log_level":1,"data":{"session":"26.1"}}
2017-04-14T02:09:54.924+08:00 [APP/PROC/WEB/0] [OUT] {"timestamp":"1492106994.924276590","source":"nfsbroker","message":"nfsbroker.unbind.end","log_level":1,"data":{"session":"26"}}
2017-04-14T02:10:20.308+08:00 [RTR/2] [OUT] nfsbroker.sys.pcfpoc.com - [2017-04-13T18:10:20.301+0000] "PUT /v2/service_instances/c0499800-ff21-429f-ab82-ee674f71407c/service_bindings/c81a6e37-5aa7-409d-83ba-28650eaa481a HTTP/1.1" 502 202 67 "-" "HTTPClient/1.0 (2.8.2.4, ruby 2.3.3 (2016-11-21))" "192.168.2.233:48336" "192.168.2.37:60136" x_forwarded_for:"192.168.2.51, 192.168.2.233" x_forwarded_proto:"https" vcap_request_id:"186f827e-596e-4957-4ca5-2efc7d9e691f" response_time:0.006691347 app_id:"82db340c-dc78-46da-9057-154da40ec696" app_index:"0" x_b3_traceid:"8053072dc77e7b76" x_b3_spanid:"8053072dc77e7b76" x_b3_parentspanid:"-"
2017-04-14T02:10:21.412+08:00 [RTR/0] [OUT] nfsbroker.sys.pcfpoc.com - [2017-04-13T18:10:21.407+0000] "DELETE /v2/service_instances/c0499800-ff21-429f-ab82-ee674f71407c/service_bindings/c81a6e37-5aa7-409d-83ba-28650eaa481a?plan_id=Existing&service_id=nfsbroker HTTP/1.1" 200 0 3 "-" "HTTPClient/1.0 (2.8.2.4, ruby 2.3.3 (2016-11-21))" "192.168.2.233:37928" "192.168.2.37:60136" x_forwarded_for:"192.168.2.32, 192.168.2.233" x_forwarded_proto:"https" vcap_request_id:"e449bd5f-1cd4-4e2c-7457-1a94646ff197" response_time:0.004253179 app_id:"82db340c-dc78-46da-9057-154da40ec696" app_index:"0" x_b3_traceid:"d3822d4bad11380f" x_b3_spanid:"d3822d4bad11380f" x_b3_parentspanid:"-"

[julian-hj]

I suspect that you are getting this issue because you are supplying UID and GID as integers, not strings in your bind config.

Try putting them in quotes and see if you don't get better results.

[ChaosEternal]

thanks.
this workaround solved the problem.
but uid and gid on UNIXes system are INTEGERs!

[julian-hj]

It is true. I am going to leave this open until we have fixed the behavior in the broker. Triggering a panic is clearly not the right thing to do.

Thanks for retesting and confirming the source of the issue!

[julian-hj]

This is now fixed in the master branch.