What we see from the trace is:
2018-11-20 09:55:05 DEBUG juju.worker.uniter.remotestate watcher.go:451 got storage change: [osd-devices/38 osd-devices/39] ok=true 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-id":78,"type":"Uniter","version":8,"request":"StorageAttachmentLife","params":{"ids":[{"storage-tag":"storage-osd-devices-38","unit-tag":"uni t-testing-osd-0"},{"storage-tag":"storage-osd-devices-39","unit-tag":"unit-testing-osd-0"}]}} 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:120 <- {"request-id":78,"response":{"results":[{"life":"alive"},{"life":"alive"}]}} 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-id":79,"type":"Uniter","version":8,"request":"WatchStorageAttachments","params":{"ids":[{"storage-tag":"storage-osd-devices-38","unit-tag":"u nit-testing-osd-0"}]}} 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:120 <- {"request-id":79,"response":{"results":[{"NotifyWatcherId":"20"}]}} 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-id":80,"type":"Uniter","version":8,"request":"StorageAttachments","params":{"ids":[{"storage-tag":"storage-osd-devices-38","unit-tag":"unit-t esting-osd-0"}]}} 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-id":81,"type":"NotifyWatcher","version":1,"id":"20","request":"Next","params":{}} 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:120 <- {"request-id":80,"response":{"results":[{"result":{"storage-tag":"","owner-tag":"","unit-tag":"","kind":0,"location":"","life":""},"error":{"message":" storage osd-devices/38 not provisioned","code":"not provisioned"}}]}} 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-id":82,"type":"Uniter","version":8,"request":"WatchStorageAttachments","params":{"ids":[{"storage-tag":"storage-osd-devices-39","unit-tag":"unit-testing-osd-0"}]}} 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:120 <- {"request-id":82,"response":{"results":[{"NotifyWatcherId":"21"}]}} 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-id":83,"type":"Uniter","version":8,"request":"StorageAttachments","params":{"ids":[{"storage-tag":"storage-osd-devices-39","unit-tag":"unit-testing-osd-0"}]}} 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-id":84,"type":"NotifyWatcher","version":1,"id":"21","request":"Next","params":{}} 2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:120 <- {"request-id":83,"response":{"results":[{"result":{"storage-tag":"","owner-tag":"","unit-tag":"","kind":0,"location":"","life":""},"error":{"message":"storage osd-devices/39 not provisioned","code":"not provisioned"}}]}}
What we see from the trace is:
2018-11-20 09:55:05 DEBUG juju.worker. uniter. remotestate watcher.go:451 got storage change: [osd-devices/38 osd-devices/39] ok=true id":78, "type": "Uniter" ,"version" :8,"request" :"StorageAttach mentLife" ,"params" :{"ids" :[{"storage- tag":"storage- osd-devices- 38","unit- tag":"uni osd-0"} ,{"storage- tag":"storage- osd-devices- 39","unit- tag":"unit- testing- osd-0"} ]}} id":78, "response" :{"results" :[{"life" :"alive" },{"life" :"alive" }]}} id":79, "type": "Uniter" ,"version" :8,"request" :"WatchStorageA ttachments" ,"params" :{"ids" :[{"storage- tag":"storage- osd-devices- 38","unit- tag":"u osd-0"} ]}} id":79, "response" :{"results" :[{"NotifyWatch erId":" 20"}]}} id":80, "type": "Uniter" ,"version" :8,"request" :"StorageAttach ments", "params" :{"ids" :[{"storage- tag":"storage- osd-devices- 38","unit- tag":"unit- t id":81, "type": "NotifyWatcher" ,"version" :1,"id" :"20"," request" :"Next" ,"params" :{}} id":80, "response" :{"results" :[{"result" :{"storage- tag":"" ,"owner- tag":"" ,"unit- tag":"" ,"kind" :0,"location" :"","life" :""},"error" :{"message" :" ,"code" :"not provisioned"}}]}} id":82, "type": "Uniter" ,"version" :8,"request" :"WatchStorageA ttachments" ,"params" :{"ids" :[{"storage- tag":"storage- osd-devices- 39","unit- tag":"unit- testing- osd-0"} ]}} id":82, "response" :{"results" :[{"NotifyWatch erId":" 21"}]}} id":83, "type": "Uniter" ,"version" :8,"request" :"StorageAttach ments", "params" :{"ids" :[{"storage- tag":"storage- osd-devices- 39","unit- tag":"unit- testing- osd-0"} ]}} id":84, "type": "NotifyWatcher" ,"version" :1,"id" :"21"," request" :"Next" ,"params" :{}} id":83, "response" :{"results" :[{"result" :{"storage- tag":"" ,"owner- tag":"" ,"unit- tag":"" ,"kind" :0,"location" :"","life" :""},"error" :{"message" :"storage osd-devices/39 not provisioned" ,"code" :"not provisioned"}}]}}
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-
t-testing-
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:120 <- {"request-
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-
nit-testing-
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:120 <- {"request-
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-
esting-osd-0"}]}}
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:120 <- {"request-
storage osd-devices/38 not provisioned"
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:120 <- {"request-
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:225 -> {"request-
2018-11-20 09:55:05 TRACE juju.rpc.jsoncodec codec.go:120 <- {"request-