controller_manager.log

(...)
I1220 19:31:45.955524   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:31:55.956002   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:32:00.534234   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:32:00.534273   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:32:05.956494   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:32:15.534538   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:32:15.534566   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:32:15.956918   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:32:25.957438   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:32:30.536245   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:32:30.536272   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:32:35.957934   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:32:45.535003   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:32:45.535031   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:32:45.958423   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:32:55.958974   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:33:00.535001   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:33:00.535028   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:33:05.959472   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:33:15.535377   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:33:15.535407   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:33:15.959939   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:33:25.960530   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:33:30.535602   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:33:30.535630   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:33:35.961036   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:33:45.535696   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:33:45.535727   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:33:45.961452   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:33:55.961900   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:34:00.536049   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:34:00.536076   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:34:05.962380   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:34:15.536205   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:34:15.536236   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:34:15.962728   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:34:25.963166   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:34:30.536241   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:34:30.536269   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:34:35.963630   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:34:45.536334   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:34:45.536365   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:34:45.964089   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:34:55.964458   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:35:00.536519   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:35:00.536549   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:35:05.964875   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:35:15.536775   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:35:15.536805   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:35:15.965374   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:35:25.965841   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:35:30.536954   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:35:30.536981   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:35:35.966319   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:35:45.537326   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:35:45.537373   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:35:45.966827   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:35:55.967327   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:36:00.537236   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:36:00.537266   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:36:05.967803   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:36:15.537459   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:36:15.537488   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:36:15.968259   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:36:25.968710   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:36:30.537529   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:36:30.537559   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:36:35.969106   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:36:45.537816   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:36:45.537844   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:36:45.969597   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:36:55.970060   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:37:00.537882   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:37:00.537909   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:37:05.970417   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:37:15.538121   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:37:15.538151   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:37:15.970877   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:37:25.971343   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:37:30.538253   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:37:30.538286   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:37:35.971799   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:37:45.538500   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:37:45.538527   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:37:45.972273   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:37:55.972971   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:38:00.538519   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:38:00.538547   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:38:05.973433   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:38:15.539062   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:38:15.539090   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:38:15.973905   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:38:25.974397   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:38:30.538769   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:38:30.538797   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:38:35.974878   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:38:45.539124   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:38:45.539156   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:38:45.975346   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:38:55.975870   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:39:00.538733   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:39:00.538775   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:39:05.976341   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:39:15.539435   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:39:15.539464   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:39:15.976781   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:39:25.977269   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:39:30.539485   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:39:30.539514   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:39:35.977728   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:39:45.539978   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:39:45.540007   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:39:45.978126   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:39:55.978584   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:40:00.539825   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:40:00.539855   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:40:05.979059   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:40:15.540353   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:40:15.540382   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:40:15.979532   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:40:25.980071   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:40:30.539373   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:40:30.539402   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:40:35.980597   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:40:45.540194   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:40:45.540221   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:40:45.981041   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:40:55.981454   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:41:00.540555   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:41:00.540582   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:41:05.981919   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:41:15.540929   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:41:15.540962   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:41:15.982370   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:41:25.982824   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:41:30.540848   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:41:30.540876   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:41:35.983239   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:41:45.542599   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:41:45.542626   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:41:45.983707   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:41:55.984212   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:42:00.541216   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:42:00.541248   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:42:05.984672   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:42:15.541499   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:42:15.541526   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:42:15.985126   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:42:25.985559   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:42:30.541467   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:42:30.541494   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:42:35.985996   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:42:45.541392   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:42:45.541418   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:42:45.986401   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:42:55.986836   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:43:00.543290   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:43:00.543318   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:43:05.987211   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:43:15.541942   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:43:15.541970   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:43:15.987638   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:43:25.988100   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:43:30.542817   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:43:30.542846   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:43:35.988607   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:43:45.542946   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:43:45.542974   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:43:45.989027   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:43:55.990814   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:44:00.543109   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:44:00.543138   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:44:05.991301   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:44:15.543243   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:44:15.543273   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:44:15.991762   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:44:25.992481   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:44:30.543460   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:44:30.543490   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:44:35.992979   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:44:45.543532   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:44:45.543560   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:44:45.993457   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:44:55.993852   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:45:00.543785   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:45:00.543812   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:45:05.994221   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:45:15.543987   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:45:15.544016   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:45:15.994680   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:45:25.995159   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:45:30.549602   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:45:30.549628   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:45:35.995638   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:45:45.544425   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:45:45.544458   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:45:45.996097   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:45:55.996618   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:46:00.544267   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:46:00.544295   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:46:05.997117   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:46:15.544665   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:46:15.544693   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:46:15.997611   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:46:25.998075   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:46:30.544645   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:46:30.544673   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:46:35.998418   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:46:45.544989   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:46:45.545016   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:46:45.998894   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:46:55.999416   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:47:00.545029   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:47:00.545075   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:47:05.999894   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:47:15.545381   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:47:15.545426   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:47:16.000380   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:47:26.000873   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:47:30.547139   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:47:30.547167   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:47:36.001378   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:47:45.547137   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:47:45.547179   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:47:46.001861   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:47:56.002340   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:48:00.547389   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:48:00.547417   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:48:06.002823   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:48:15.547482   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:48:15.547510   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:48:16.003205   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:48:26.003702   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:48:30.547636   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:48:30.547662   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:48:36.004192   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:48:45.549279   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:48:45.549305   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:48:46.004604   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:48:56.005107   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:49:00.547801   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:49:00.547827   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:49:06.005586   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:49:15.548032   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:49:15.548062   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:49:16.006085   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:49:26.006580   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:49:30.548371   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:49:30.548402   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:49:36.007042   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:49:45.548333   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:49:45.548360   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:49:46.007406   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:49:56.007902   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:50:00.550828   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:50:00.550855   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:50:06.008396   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:50:15.548651   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:50:15.548681   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:50:16.008860   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:50:26.009188   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:50:30.548900   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:50:30.548930   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:50:36.009599   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:50:45.549166   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:50:45.549196   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:50:46.010025   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:50:56.010338   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:51:00.549458   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:51:00.549485   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:51:06.010752   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:51:15.549659   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:51:15.549688   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:51:16.011194   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:51:26.011636   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:51:30.549592   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:51:30.549618   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:51:36.011964   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:51:45.549922   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:51:45.549949   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:51:46.012416   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:51:56.012767   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:52:00.549981   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:52:00.550010   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:52:06.013220   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:52:09.562550   17571 service_controller.go:729] Service has been deleted default/csi-attacher. Attempting to cleanup load balancer resources
I1220 19:52:09.572105   17571 garbagecollector.go:408] processing item [v1/Pod, namespace: default, name: csi-attacher-0, uid: 02732ca1-0477-11e9-8dad-0e240628beaa]
I1220 19:52:09.572924   17571 stateful_set.go:427] StatefulSet has been deleted default/csi-attacher
I1220 19:52:09.572953   17571 garbagecollector.go:408] processing item [apps/v1/ControllerRevision, namespace: default, name: csi-attacher-fc6d57f8, uid: 0271f1ab-0477-11e9-8dad-0e240628beaa]
I1220 19:52:09.576231   17571 garbagecollector.go:521] delete object [apps/v1/ControllerRevision, namespace: default, name: csi-attacher-fc6d57f8, uid: 0271f1ab-0477-11e9-8dad-0e240628beaa] with propagation policy Background
I1220 19:52:09.576652   17571 garbagecollector.go:521] delete object [v1/Pod, namespace: default, name: csi-attacher-0, uid: 02732ca1-0477-11e9-8dad-0e240628beaa] with propagation policy Background
I1220 19:52:09.626506   17571 garbagecollector.go:408] processing item [apps/v1/ControllerRevision, namespace: default, name: csi-node-6786568dbf, uid: 02b6b990-0477-11e9-8dad-0e240628beaa]
I1220 19:52:09.626718   17571 garbagecollector.go:408] processing item [v1/Pod, namespace: default, name: csi-node-bh4gr, uid: 02b816f1-0477-11e9-8dad-0e240628beaa]
I1220 19:52:09.626844   17571 daemon_controller.go:1233] daemon set has been deleted default/csi-node
I1220 19:52:09.628543   17571 garbagecollector.go:521] delete object [apps/v1/ControllerRevision, namespace: default, name: csi-node-6786568dbf, uid: 02b6b990-0477-11e9-8dad-0e240628beaa] with propagation policy Background
I1220 19:52:09.629019   17571 garbagecollector.go:521] delete object [v1/Pod, namespace: default, name: csi-node-bh4gr, uid: 02b816f1-0477-11e9-8dad-0e240628beaa] with propagation policy Background
I1220 19:52:09.657026   17571 service_controller.go:729] Service has been deleted default/csi-provisioner. Attempting to cleanup load balancer resources
I1220 19:52:09.668282   17571 garbagecollector.go:408] processing item [apps/v1/ControllerRevision, namespace: default, name: csi-provisioner-dbd78984b, uid: 02785414-0477-11e9-8dad-0e240628beaa]
I1220 19:52:09.668504   17571 stateful_set.go:427] StatefulSet has been deleted default/csi-provisioner
I1220 19:52:09.668523   17571 garbagecollector.go:408] processing item [v1/Pod, namespace: default, name: csi-provisioner-0, uid: 027ce289-0477-11e9-8dad-0e240628beaa]
I1220 19:52:09.673270   17571 garbagecollector.go:521] delete object [v1/Pod, namespace: default, name: csi-provisioner-0, uid: 027ce289-0477-11e9-8dad-0e240628beaa] with propagation policy Background
I1220 19:52:09.673572   17571 garbagecollector.go:521] delete object [apps/v1/ControllerRevision, namespace: default, name: csi-provisioner-dbd78984b, uid: 02785414-0477-11e9-8dad-0e240628beaa] with propagation policy Background
I1220 19:52:15.550400   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:52:15.550443   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:52:16.013675   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:52:26.014174   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:52:30.551567   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:52:30.551600   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:52:36.014672   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:52:45.550596   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:52:45.550624   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:52:46.015123   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:52:56.015586   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:53:00.550754   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:53:00.550783   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:53:06.016046   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:53:15.551069   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:53:15.551099   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:53:16.016496   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:53:26.016949   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:53:30.551183   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:53:30.551211   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:53:36.017360   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:53:45.551364   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:53:45.551392   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:53:46.017815   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:53:56.018268   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:54:00.551508   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:54:00.551533   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:54:06.018725   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:54:15.551680   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:54:15.551707   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:54:16.019156   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:54:26.019615   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:54:30.551640   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:54:30.551669   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:54:36.028599   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:54:45.551805   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:54:45.551832   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:54:46.029072   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:54:56.032549   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:55:00.552035   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:55:00.552065   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:55:06.034034   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:55:15.553571   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:55:15.553597   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:55:16.034427   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:55:26.034923   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:55:30.552441   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:55:30.552469   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:55:36.035381   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:55:45.552876   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:55:45.552904   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:55:46.035870   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:55:50.831338   17571 service_controller.go:330] Not persisting unchanged LoadBalancerStatus for service default/csi-attacher to registry.
I1220 19:55:50.833876   17571 event.go:221] Event(v1.ObjectReference{Kind:"StatefulSet", Namespace:"default", Name:"csi-attacher", UID:"405b3a51-0491-11e9-8dad-0e240628beaa", APIVersion:"apps/v1", ResourceVersion:"2943", FieldPath:""}): type: 'Normal' reason: 'SuccessfulCreate' create Pod csi-attacher-0 in StatefulSet csi-attacher successful
I1220 19:55:50.867230   17571 event.go:221] Event(v1.ObjectReference{Kind:"DaemonSet", Namespace:"default", Name:"csi-node", UID:"405eb2ee-0491-11e9-8dad-0e240628beaa", APIVersion:"apps/v1", ResourceVersion:"2950", FieldPath:""}): type: 'Normal' reason: 'SuccessfulCreate' Created pod: csi-node-4c2dc
I1220 19:55:50.906163   17571 event.go:221] Event(v1.ObjectReference{Kind:"StatefulSet", Namespace:"default", Name:"csi-provisioner", UID:"4069a652-0491-11e9-8dad-0e240628beaa", APIVersion:"apps/v1", ResourceVersion:"2973", FieldPath:""}): type: 'Normal' reason: 'SuccessfulCreate' create Pod csi-provisioner-0 in StatefulSet csi-provisioner successful
I1220 19:55:50.922757   17571 service_controller.go:330] Not persisting unchanged LoadBalancerStatus for service default/csi-provisioner to registry.
I1220 19:55:53.671018   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:55:53.671057   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:55:53.674520   17571 pv_protection_controller.go:181] Removed protection finalizer from PV pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa
I1220 19:55:53.675512   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:55:53.675533   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:55:56.036418   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:56:00.552687   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:56:00.552715   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:56:06.040001   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:56:15.553183   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:56:15.553212   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:56:16.040464   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:56:26.041024   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:56:30.553179   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:56:30.553207   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:56:36.052415   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:56:38.104505   17571 pv_controller.go:1391] error finding provisioning plugin for claim default/claim1: storageclass.storage.k8s.io "slow" not found
E1220 19:56:38.104540   17571 goroutinemap.go:150] Operation for "provision-default/claim1[5c8ce6d0-0491-11e9-8dad-0e240628beaa]" failed. No retries permitted until 2018-12-20 19:56:38.604525261 +0000 UTC m=+11325.991638820 (durationBeforeRetry 500ms). Error: "storageclass.storage.k8s.io \"slow\" not found"
I1220 19:56:38.104556   17571 event.go:221] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"claim1", UID:"5c8ce6d0-0491-11e9-8dad-0e240628beaa", APIVersion:"v1", ResourceVersion:"3027", FieldPath:""}): type: 'Warning' reason: 'ProvisioningFailed' storageclass.storage.k8s.io "slow" not found
I1220 19:56:45.553563   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:56:45.553587   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:56:45.554217   17571 pv_controller.go:1417] provisioning claim "default/claim1": waiting for a volume to be created, either by external provisioner "ebs.csi.aws.com" or manually created by system administrator
I1220 19:56:45.554244   17571 event.go:221] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"claim1", UID:"5c8ce6d0-0491-11e9-8dad-0e240628beaa", APIVersion:"v1", ResourceVersion:"3035", FieldPath:""}): type: 'Normal' reason: 'ExternalProvisioning' waiting for a volume to be created, either by external provisioner "ebs.csi.aws.com" or manually created by system administrator
I1220 19:56:45.554555   17571 pv_controller.go:1417] provisioning claim "default/claim1": waiting for a volume to be created, either by external provisioner "ebs.csi.aws.com" or manually created by system administrator
I1220 19:56:45.554574   17571 event.go:221] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"claim1", UID:"5c8ce6d0-0491-11e9-8dad-0e240628beaa", APIVersion:"v1", ResourceVersion:"3035", FieldPath:""}): type: 'Normal' reason: 'ExternalProvisioning' waiting for a volume to be created, either by external provisioner "ebs.csi.aws.com" or manually created by system administrator
I1220 19:56:46.052947   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:56:46.089087   17571 pv_controller.go:824] volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" entered phase "Bound"
I1220 19:56:46.089114   17571 pv_controller.go:963] volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" bound to claim "default/claim1"
I1220 19:56:46.094945   17571 pv_controller.go:768] claim "default/claim1" entered phase "Bound"
I1220 19:56:46.105160   17571 reconciler.go:289] attacherDetacher.AttachVolume started for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" (UniqueName: "kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb") from node "ip-172-18-9-153.ec2.internal" 
E1220 19:56:46.704693   17571 csi_attacher.go:212] kubernetes.io/csi: attachment for vol-06c2abab004734deb failed: rpc error: code = Internal desc = Could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": IncorrectState: vol-06c2abab004734deb is not 'available'.
	status code: 400, request id: fd9db985-5349-4957-bcbd-93da319fab2b
E1220 19:56:46.704787   17571 nestedpendingoperations.go:267] Operation for "\"kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb\"" failed. No retries permitted until 2018-12-20 19:56:47.204761666 +0000 UTC m=+11334.591875245 (durationBeforeRetry 500ms). Error: "AttachVolume.Attach failed for volume \"pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa\" (UniqueName: \"kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb\") from node \"ip-172-18-9-153.ec2.internal\" : rpc error: code = Internal desc = Could not attach volume \"vol-06c2abab004734deb\" to node \"i-0d232e894f76acadc\": could not attach volume \"vol-06c2abab004734deb\" to node \"i-0d232e894f76acadc\": IncorrectState: vol-06c2abab004734deb is not 'available'.\n\tstatus code: 400, request id: fd9db985-5349-4957-bcbd-93da319fab2b"
I1220 19:56:46.704969   17571 event.go:221] Event(v1.ObjectReference{Kind:"Pod", Namespace:"default", Name:"app", UID:"5c8e022f-0491-11e9-8dad-0e240628beaa", APIVersion:"v1", ResourceVersion:"3047", FieldPath:""}): type: 'Warning' reason: 'FailedAttachVolume' AttachVolume.Attach failed for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" : rpc error: code = Internal desc = Could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": IncorrectState: vol-06c2abab004734deb is not 'available'.
	status code: 400, request id: fd9db985-5349-4957-bcbd-93da319fab2b
I1220 19:56:47.213054   17571 reconciler.go:289] attacherDetacher.AttachVolume started for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" (UniqueName: "kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb") from node "ip-172-18-9-153.ec2.internal" 
E1220 19:56:47.216642   17571 csi_attacher.go:177] kubernetes.io/csi: attachment for vol-06c2abab004734deb failed: rpc error: code = Internal desc = Could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": IncorrectState: vol-06c2abab004734deb is not 'available'.
	status code: 400, request id: e58b36f7-242f-4cbe-8213-e00e090e26fe
E1220 19:56:47.216708   17571 nestedpendingoperations.go:267] Operation for "\"kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb\"" failed. No retries permitted until 2018-12-20 19:56:48.216683125 +0000 UTC m=+11335.603796684 (durationBeforeRetry 1s). Error: "AttachVolume.Attach failed for volume \"pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa\" (UniqueName: \"kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb\") from node \"ip-172-18-9-153.ec2.internal\" : rpc error: code = Internal desc = Could not attach volume \"vol-06c2abab004734deb\" to node \"i-0d232e894f76acadc\": could not attach volume \"vol-06c2abab004734deb\" to node \"i-0d232e894f76acadc\": IncorrectState: vol-06c2abab004734deb is not 'available'.\n\tstatus code: 400, request id: e58b36f7-242f-4cbe-8213-e00e090e26fe"
I1220 19:56:47.216855   17571 event.go:221] Event(v1.ObjectReference{Kind:"Pod", Namespace:"default", Name:"app", UID:"5c8e022f-0491-11e9-8dad-0e240628beaa", APIVersion:"v1", ResourceVersion:"3047", FieldPath:""}): type: 'Warning' reason: 'FailedAttachVolume' AttachVolume.Attach failed for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" : rpc error: code = Internal desc = Could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": IncorrectState: vol-06c2abab004734deb is not 'available'.
	status code: 400, request id: e58b36f7-242f-4cbe-8213-e00e090e26fe
I1220 19:56:48.221734   17571 reconciler.go:289] attacherDetacher.AttachVolume started for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" (UniqueName: "kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb") from node "ip-172-18-9-153.ec2.internal" 
E1220 19:56:48.225184   17571 csi_attacher.go:177] kubernetes.io/csi: attachment for vol-06c2abab004734deb failed: rpc error: code = Internal desc = Could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": IncorrectState: vol-06c2abab004734deb is not 'available'.
	status code: 400, request id: d2c774e5-ce82-41d2-ba0e-afdc63a3e0b2
E1220 19:56:48.225250   17571 nestedpendingoperations.go:267] Operation for "\"kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb\"" failed. No retries permitted until 2018-12-20 19:56:50.225225771 +0000 UTC m=+11337.612339322 (durationBeforeRetry 2s). Error: "AttachVolume.Attach failed for volume \"pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa\" (UniqueName: \"kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb\") from node \"ip-172-18-9-153.ec2.internal\" : rpc error: code = Internal desc = Could not attach volume \"vol-06c2abab004734deb\" to node \"i-0d232e894f76acadc\": could not attach volume \"vol-06c2abab004734deb\" to node \"i-0d232e894f76acadc\": IncorrectState: vol-06c2abab004734deb is not 'available'.\n\tstatus code: 400, request id: d2c774e5-ce82-41d2-ba0e-afdc63a3e0b2"
I1220 19:56:48.225392   17571 event.go:221] Event(v1.ObjectReference{Kind:"Pod", Namespace:"default", Name:"app", UID:"5c8e022f-0491-11e9-8dad-0e240628beaa", APIVersion:"v1", ResourceVersion:"3047", FieldPath:""}): type: 'Warning' reason: 'FailedAttachVolume' AttachVolume.Attach failed for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" : rpc error: code = Internal desc = Could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": IncorrectState: vol-06c2abab004734deb is not 'available'.
	status code: 400, request id: d2c774e5-ce82-41d2-ba0e-afdc63a3e0b2
I1220 19:56:50.225616   17571 reconciler.go:289] attacherDetacher.AttachVolume started for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" (UniqueName: "kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb") from node "ip-172-18-9-153.ec2.internal" 
E1220 19:56:50.229082   17571 csi_attacher.go:177] kubernetes.io/csi: attachment for vol-06c2abab004734deb failed: rpc error: code = Internal desc = Could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": IncorrectState: vol-06c2abab004734deb is not 'available'.
	status code: 400, request id: a6f3a43e-84d7-4cd0-b74c-3f31f1b169e8
E1220 19:56:50.229172   17571 nestedpendingoperations.go:267] Operation for "\"kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb\"" failed. No retries permitted until 2018-12-20 19:56:54.229146517 +0000 UTC m=+11341.616260073 (durationBeforeRetry 4s). Error: "AttachVolume.Attach failed for volume \"pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa\" (UniqueName: \"kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb\") from node \"ip-172-18-9-153.ec2.internal\" : rpc error: code = Internal desc = Could not attach volume \"vol-06c2abab004734deb\" to node \"i-0d232e894f76acadc\": could not attach volume \"vol-06c2abab004734deb\" to node \"i-0d232e894f76acadc\": IncorrectState: vol-06c2abab004734deb is not 'available'.\n\tstatus code: 400, request id: a6f3a43e-84d7-4cd0-b74c-3f31f1b169e8"
I1220 19:56:50.229323   17571 event.go:221] Event(v1.ObjectReference{Kind:"Pod", Namespace:"default", Name:"app", UID:"5c8e022f-0491-11e9-8dad-0e240628beaa", APIVersion:"v1", ResourceVersion:"3047", FieldPath:""}): type: 'Warning' reason: 'FailedAttachVolume' AttachVolume.Attach failed for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" : rpc error: code = Internal desc = Could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": IncorrectState: vol-06c2abab004734deb is not 'available'.
	status code: 400, request id: a6f3a43e-84d7-4cd0-b74c-3f31f1b169e8
I1220 19:56:54.234153   17571 reconciler.go:289] attacherDetacher.AttachVolume started for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" (UniqueName: "kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb") from node "ip-172-18-9-153.ec2.internal" 
E1220 19:56:54.238429   17571 csi_attacher.go:177] kubernetes.io/csi: attachment for vol-06c2abab004734deb failed: rpc error: code = Internal desc = Could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": IncorrectState: vol-06c2abab004734deb is not 'available'.
	status code: 400, request id: 428b5822-7860-490e-a438-ed16241c78d1
E1220 19:56:54.238502   17571 nestedpendingoperations.go:267] Operation for "\"kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb\"" failed. No retries permitted until 2018-12-20 19:57:02.238477112 +0000 UTC m=+11349.625590668 (durationBeforeRetry 8s). Error: "AttachVolume.Attach failed for volume \"pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa\" (UniqueName: \"kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb\") from node \"ip-172-18-9-153.ec2.internal\" : rpc error: code = Internal desc = Could not attach volume \"vol-06c2abab004734deb\" to node \"i-0d232e894f76acadc\": could not attach volume \"vol-06c2abab004734deb\" to node \"i-0d232e894f76acadc\": IncorrectState: vol-06c2abab004734deb is not 'available'.\n\tstatus code: 400, request id: 428b5822-7860-490e-a438-ed16241c78d1"
I1220 19:56:54.238535   17571 event.go:221] Event(v1.ObjectReference{Kind:"Pod", Namespace:"default", Name:"app", UID:"5c8e022f-0491-11e9-8dad-0e240628beaa", APIVersion:"v1", ResourceVersion:"3047", FieldPath:""}): type: 'Warning' reason: 'FailedAttachVolume' AttachVolume.Attach failed for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" : rpc error: code = Internal desc = Could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": could not attach volume "vol-06c2abab004734deb" to node "i-0d232e894f76acadc": IncorrectState: vol-06c2abab004734deb is not 'available'.
	status code: 400, request id: 428b5822-7860-490e-a438-ed16241c78d1
I1220 19:56:56.053264   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:57:00.553086   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:57:00.553114   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:57:02.249036   17571 reconciler.go:289] attacherDetacher.AttachVolume started for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" (UniqueName: "kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb") from node "ip-172-18-9-153.ec2.internal" 
I1220 19:57:02.256671   17571 operation_generator.go:336] AttachVolume.Attach succeeded for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" (UniqueName: "kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb") from node "ip-172-18-9-153.ec2.internal" 
I1220 19:57:02.256843   17571 event.go:221] Event(v1.ObjectReference{Kind:"Pod", Namespace:"default", Name:"app", UID:"5c8e022f-0491-11e9-8dad-0e240628beaa", APIVersion:"v1", ResourceVersion:"3047", FieldPath:""}): type: 'Normal' reason: 'SuccessfulAttachVolume' AttachVolume.Attach succeeded for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" 
I1220 19:57:06.053684   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:57:15.553922   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:57:15.553950   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:57:16.054179   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:57:22.284707   17571 pvc_protection_controller.go:229] Keeping PVC default/claim1, it is used by pod default/app
I1220 19:57:26.054614   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:57:30.557711   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:57:30.557739   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:57:36.055161   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:57:45.554659   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:57:45.554686   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:57:46.055673   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:57:53.228314   17571 pvc_protection_controller.go:229] Keeping PVC default/claim1, it is used by pod default/app
I1220 19:57:54.235226   17571 pvc_protection_controller.go:229] Keeping PVC default/claim1, it is used by pod default/app
I1220 19:57:54.238105   17571 pvc_protection_controller.go:235] PVC default/claim1 is unused
I1220 19:57:54.243251   17571 pvc_protection_controller.go:206] Removed protection finalizer from PVC default/claim1
I1220 19:57:54.243683   17571 pv_controller.go:601] volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" is released and reclaim policy "Delete" will be executed
I1220 19:57:54.246383   17571 pv_controller.go:824] volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" entered phase "Released"
I1220 19:57:54.250042   17571 pv_controller.go:1294] isVolumeReleased[pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa]: volume is released
I1220 19:57:54.250067   17571 pv_controller.go:1338] external deleter for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:57:56.056154   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:58:00.555085   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:58:00.555120   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:58:00.555085   17571 pv_controller.go:1294] isVolumeReleased[pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa]: volume is released
I1220 19:58:00.555141   17571 pv_controller.go:1338] external deleter for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:58:02.299425   17571 reconciler.go:230] attacherDetacher.DetachVolume started for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" (UniqueName: "kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb") on node "ip-172-18-9-153.ec2.internal" 
I1220 19:58:02.301229   17571 operation_generator.go:1256] Verified volume is safe to detach for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" (UniqueName: "kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb") on node "ip-172-18-9-153.ec2.internal" 
I1220 19:58:06.056598   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:58:09.138031   17571 operation_generator.go:420] DetachVolume.Detach succeeded for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" (UniqueName: "kubernetes.io/csi/ebs.csi.aws.com^vol-06c2abab004734deb") on node "ip-172-18-9-153.ec2.internal" 
I1220 19:58:09.472069   17571 pv_controller.go:1294] isVolumeReleased[pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa]: volume is released
I1220 19:58:09.472114   17571 pv_controller.go:1338] external deleter for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:58:09.475022   17571 pv_protection_controller.go:178] Error removing protection finalizer from PV pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa: Operation cannot be fulfilled on persistentvolumes "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa": the object has been modified; please apply your changes to the latest version and try again
E1220 19:58:09.475042   17571 pv_protection_controller.go:116] PV pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa failed with : Operation cannot be fulfilled on persistentvolumes "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa": the object has been modified; please apply your changes to the latest version and try again
I1220 19:58:09.476963   17571 pv_controller.go:1294] isVolumeReleased[pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa]: volume is released
I1220 19:58:09.476998   17571 pv_controller.go:1338] external deleter for volume "pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:58:09.477923   17571 pv_controller_base.go:385] deletion of claim "default/claim1" was already processed
I1220 19:58:09.478021   17571 pv_protection_controller.go:181] Removed protection finalizer from PV pvc-5c8ce6d0-0491-11e9-8dad-0e240628beaa
I1220 19:58:15.554924   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:58:15.554956   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:58:16.057042   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:58:26.057530   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:58:30.555044   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:58:30.555072   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:58:36.057995   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:58:45.555323   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:58:45.555351   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:58:46.058474   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:58:56.058944   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:59:00.555612   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:59:00.555640   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:59:06.059475   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:59:15.555580   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:59:15.555636   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:59:16.059963   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:59:26.060401   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:59:30.555973   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:59:30.556003   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:59:36.060876   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:59:45.556052   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 19:59:45.556080   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 19:59:46.061239   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 19:59:56.061716   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 20:00:00.561719   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 20:00:00.561745   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 20:00:06.062201   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 20:00:15.561365   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 20:00:15.561393   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 20:00:16.062607   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 20:00:26.062946   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 20:00:30.561475   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 20:00:30.561520   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 20:00:36.063359   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 20:00:45.561500   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 20:00:45.561530   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 20:00:46.063831   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 20:00:56.064208   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 20:01:00.561707   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 20:01:00.561735   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 20:01:06.064636   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 20:01:15.561879   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 20:01:15.561906   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 20:01:16.065079   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 20:01:26.065580   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 20:01:30.562059   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 20:01:30.562087   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 20:01:36.066048   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.
I1220 20:01:45.562223   17571 pv_controller.go:1294] isVolumeReleased[pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa]: volume is released
I1220 20:01:45.562250   17571 pv_controller.go:1338] external deleter for volume "pvc-9e7d94a9-0487-11e9-8dad-0e240628beaa" requested, ignoring
I1220 20:01:46.067292   17571 node_lifecycle_controller.go:886] Node ip-172-18-9-153.ec2.internal ReadyCondition updated. Updating timestamp.