plugin v1 docker start hang

-- Logs begin at Mon 2017-07-03 21:43:18 CST, end at Tue 2017-07-04 10:17:01 CST. --
Jul 03 21:43:22 acs019 systemd[1]: Starting Docker Application Container Engine...
Jul 03 21:43:22 acs019 dockerd[1169]: time="2017-07-03T21:43:22.404024529+08:00" level=info msg="libcontainerd: new containerd process, pid: 1237"
Jul 03 21:43:23 acs019 dockerd[1169]: time="2017-07-03T21:43:23.670164212+08:00" level=warning msg="Unable to locate plugin: ossfs, retrying in 1s"
Jul 03 21:43:24 acs019 dockerd[1169]: time="2017-07-03T21:43:24.670570436+08:00" level=warning msg="Unable to locate plugin: ossfs, retrying in 2s"
Jul 03 21:43:26 acs019 dockerd[1169]: time="2017-07-03T21:43:26.670997155+08:00" level=warning msg="Unable to locate plugin: ossfs, retrying in 4s"
Jul 03 21:43:30 acs019 dockerd[1169]: time="2017-07-03T21:43:30.671547287+08:00" level=warning msg="Unable to locate plugin: ossfs, retrying in 8s"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.671995915+08:00" level=warning msg="Error restoring volume" driver=ossfs error="error while checking if volume \"\" exists in driver \"ossfs\": Error looking up volume plugin ossfs: legacy plugin: plugin not found" volume=
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.676230965+08:00" level=info msg="Graph migration to content-addressability took 0.00 seconds"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.676524848+08:00" level=info msg="Initializing discovery with TLS"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.712133599+08:00" level=warning msg="Your kernel does not support swap memory limit"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.712248826+08:00" level=warning msg="Your kernel does not support cgroup rt period"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.712336585+08:00" level=warning msg="Your kernel does not support cgroup rt runtime"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.713937396+08:00" level=info msg="Loading containers: start."
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.742201830+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (821d770aa59e0f77c6488be33a301d34fd8afaace0f8e71bc43670fd12899468)."
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.742895971+08:00" level=warning msg="libcontainerd: failed to retrieve container 821d770aa59e0f77c6488be33a301d34fd8afaace0f8e71bc43670fd12899468 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.743062517+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/821d770aa59e0f77c6488be33a301d34fd8afaace0f8e71bc43670fd12899468/shm: invalid argument"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.748927168+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (f78d444ddedd4d1660886da708071b0b0cf3a6a592704413260d7e07141d1815)."
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.749444811+08:00" level=warning msg="libcontainerd: failed to retrieve container f78d444ddedd4d1660886da708071b0b0cf3a6a592704413260d7e07141d1815 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.749600853+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/f78d444ddedd4d1660886da708071b0b0cf3a6a592704413260d7e07141d1815/shm: invalid argument"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.809201115+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (bd88ea1c66c29813b92864698caf520e39b2018f36cfca0df764c4201bf0a623)."
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.809774875+08:00" level=warning msg="libcontainerd: failed to retrieve container bd88ea1c66c29813b92864698caf520e39b2018f36cfca0df764c4201bf0a623 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.809883313+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/bd88ea1c66c29813b92864698caf520e39b2018f36cfca0df764c4201bf0a623/shm: invalid argument"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.832376491+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (d68523826c3ee802f008b5963f9da23b3ac6dc4246bdbe6455743ce4ae3d7671)."
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.834929205+08:00" level=warning msg="libcontainerd: failed to retrieve container d68523826c3ee802f008b5963f9da23b3ac6dc4246bdbe6455743ce4ae3d7671 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.835089280+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/d68523826c3ee802f008b5963f9da23b3ac6dc4246bdbe6455743ce4ae3d7671/shm: invalid argument"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.862722919+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (5c59f3778d5e273a1c12aaba42080816ac3a73a12beb502b192e321fff27f719)."
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.865355856+08:00" level=warning msg="libcontainerd: failed to retrieve container 5c59f3778d5e273a1c12aaba42080816ac3a73a12beb502b192e321fff27f719 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.865509681+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/5c59f3778d5e273a1c12aaba42080816ac3a73a12beb502b192e321fff27f719/shm: invalid argument"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.885480060+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (68ab6fa30d6446592825155158a223755d658531b90034112d561b8f9bf89930)."
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.886076907+08:00" level=warning msg="libcontainerd: failed to retrieve container 68ab6fa30d6446592825155158a223755d658531b90034112d561b8f9bf89930 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.886176505+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/68ab6fa30d6446592825155158a223755d658531b90034112d561b8f9bf89930/shm: invalid argument"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.904983240+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (9818e51b0f30e5088cfa4cf7d8089553223bee54c2f7b5317e9751f6a0c6ae19)."
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.905549277+08:00" level=warning msg="libcontainerd: failed to retrieve container 9818e51b0f30e5088cfa4cf7d8089553223bee54c2f7b5317e9751f6a0c6ae19 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.905666935+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/9818e51b0f30e5088cfa4cf7d8089553223bee54c2f7b5317e9751f6a0c6ae19/shm: invalid argument"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.928363713+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (7341f166c062500d77c3e71d6e2c614ac2e5f6ade9cb3d4d6ccd56a705c7a822)."
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.930982613+08:00" level=warning msg="libcontainerd: failed to retrieve container 7341f166c062500d77c3e71d6e2c614ac2e5f6ade9cb3d4d6ccd56a705c7a822 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.931104697+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/7341f166c062500d77c3e71d6e2c614ac2e5f6ade9cb3d4d6ccd56a705c7a822/shm: invalid argument"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.957158716+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (6b24b9d74540e61339b4db50d32bfa7f8ddcee6bd45feb67e42173e310442458)."
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.957693667+08:00" level=warning msg="libcontainerd: failed to retrieve container 6b24b9d74540e61339b4db50d32bfa7f8ddcee6bd45feb67e42173e310442458 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.958804458+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/6b24b9d74540e61339b4db50d32bfa7f8ddcee6bd45feb67e42173e310442458/shm: invalid argument"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.970926009+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (ed81a87122dd5810301b106366471b7b1c4c7a1e1dd93964028c93df747dfc7a)."
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.973526148+08:00" level=warning msg="libcontainerd: failed to retrieve container ed81a87122dd5810301b106366471b7b1c4c7a1e1dd93964028c93df747dfc7a state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:38 acs019 dockerd[1169]: time="2017-07-03T21:43:38.973660868+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/ed81a87122dd5810301b106366471b7b1c4c7a1e1dd93964028c93df747dfc7a/shm: invalid argument"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.003354597+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (635872d88f11926f721f39928573bc5def9b134818d33a086b349f5dc1d65e17)."
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.003768342+08:00" level=warning msg="libcontainerd: failed to retrieve container 635872d88f11926f721f39928573bc5def9b134818d33a086b349f5dc1d65e17 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.003855399+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/635872d88f11926f721f39928573bc5def9b134818d33a086b349f5dc1d65e17/shm: invalid argument"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.040294744+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (c08eba3dbd4fe73a6ef1ebd2ed737123c060b999dda7ee1f28b1a4c4ea04154d)."
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.041682936+08:00" level=warning msg="libcontainerd: failed to retrieve container c08eba3dbd4fe73a6ef1ebd2ed737123c060b999dda7ee1f28b1a4c4ea04154d state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.067041739+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (15ad8c305b39421327a7930efa7ad03a3be99542716eeb1f52eb9d6b62163c29)."
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.067792827+08:00" level=warning msg="libcontainerd: failed to retrieve container 15ad8c305b39421327a7930efa7ad03a3be99542716eeb1f52eb9d6b62163c29 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.068041738+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/15ad8c305b39421327a7930efa7ad03a3be99542716eeb1f52eb9d6b62163c29/shm: invalid argument"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.098002435+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (1e0e8267a8f59c182bb5ee39d1e82d671c744b7680698648795082af3a0b094d)."
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.098620378+08:00" level=warning msg="libcontainerd: failed to retrieve container 1e0e8267a8f59c182bb5ee39d1e82d671c744b7680698648795082af3a0b094d state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.098778493+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/1e0e8267a8f59c182bb5ee39d1e82d671c744b7680698648795082af3a0b094d/shm: invalid argument"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.129520958+08:00" level=warning msg="libcontainerd: client is out of sync, restore was called on a fully synced container (301dcbd3dd6f8d5ac691207e2a9b0a7c924fa3dca666c742ea6d68aa55c76d19)."
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.130232113+08:00" level=warning msg="libcontainerd: failed to retrieve container 301dcbd3dd6f8d5ac691207e2a9b0a7c924fa3dca666c742ea6d68aa55c76d19 state: rpc error: code = 2 desc = containerd: container not found"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.130447035+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/301dcbd3dd6f8d5ac691207e2a9b0a7c924fa3dca666c742ea6d68aa55c76d19/shm: invalid argument"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.165358086+08:00" level=info msg="Option Initializing KV with TLS"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.244298457+08:00" level=info msg="Firewalld running: false"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.670106412+08:00" level=warning msg="Fail to get vpc subnet config"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.670224524+08:00" level=warning msg="ipam config: []"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.670261575+08:00" level=warning msg="init vpc ipam plugin error: can not find vpc ipam label 'com.docker.network.driver.vpc.subnet'"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.724027806+08:00" level=info msg="2017/07/03 21:43:39 [INFO] serf: EventMemberJoin: acs019 192.168.199.19\n"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.725783143+08:00" level=info msg="2017/07/03 21:43:39 [INFO] serf: EventMemberJoin: acs018 192.168.199.18\n"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.725962206+08:00" level=info msg="2017/07/03 21:43:39 [INFO] serf: EventMemberJoin: dfarm30 192.168.199.30\n"
Jul 03 21:43:39 acs019 dockerd[1169]: time="2017-07-03T21:43:39.913444488+08:00" level=info msg="Removing stale sandbox 4e0968a2086a6bbce14c6c8b1f145d30daec0f189a62c99b2efd9327c6bb44ef (635872d88f11926f721f39928573bc5def9b134818d33a086b349f5dc1d65e17)"
Jul 03 21:43:40 acs019 dockerd[1169]: time="2017-07-03T21:43:40.027711635+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 1s"
Jul 03 21:43:41 acs019 dockerd[1169]: time="2017-07-03T21:43:41.028169169+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 2s"
Jul 03 21:43:43 acs019 dockerd[1169]: time="2017-07-03T21:43:43.028610131+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 4s"
Jul 03 21:43:47 acs019 dockerd[1169]: time="2017-07-03T21:43:47.028907369+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 8s"
Jul 03 21:43:55 acs019 dockerd[1169]: time="2017-07-03T21:43:55.029269431+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 1s"
Jul 03 21:43:56 acs019 dockerd[1169]: time="2017-07-03T21:43:56.029572601+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 2s"
Jul 03 21:43:58 acs019 dockerd[1169]: time="2017-07-03T21:43:58.029871194+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 4s"
Jul 03 21:44:02 acs019 dockerd[1169]: time="2017-07-03T21:44:02.030166881+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 8s"
Jul 03 21:44:10 acs019 dockerd[1169]: time="2017-07-03T21:44:10.114185931+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 1s"
Jul 03 21:44:11 acs019 dockerd[1169]: time="2017-07-03T21:44:11.114465855+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 2s"
Jul 03 21:44:13 acs019 dockerd[1169]: time="2017-07-03T21:44:13.114756560+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 4s"
Jul 03 21:44:17 acs019 dockerd[1169]: time="2017-07-03T21:44:17.115028811+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 8s"
Jul 03 21:44:25 acs019 dockerd[1169]: time="2017-07-03T21:44:25.115368834+08:00" level=warning msg="Failed to retrieve ipam driver to release interface address on delete of endpoint wechat-web-test4_wechat-web_1 (a46aa61a0e69c15cf80c10cc216ad3411501b93652f8034964d77fc3a0aff0b3): legacy plugin: plugin not found"
Jul 03 21:44:25 acs019 dockerd[1169]: time="2017-07-03T21:44:25.139169470+08:00" level=warning msg="Failed deleting endpoint a0c5e8b716aad3cd02749cb23ec5fc3d7824d7a31691b2a4f2c2e1120c827288: failed to get endpoint from store during Delete: could not find endpoint a0c5e8b716aad3cd02749cb23ec5fc3d7824d7a31691b2a4f2c2e1120c827288: []\n"
Jul 03 21:44:25 acs019 dockerd[1169]: time="2017-07-03T21:44:25.340677399+08:00" level=info msg="Removing stale sandbox d52b4009e68e4ac49275a04ebb72eb65bd2ac5a2743ef356467e6088136ca398 (68ab6fa30d6446592825155158a223755d658531b90034112d561b8f9bf89930)"
Jul 03 21:44:25 acs019 dockerd[1169]: time="2017-07-03T21:44:25.457360258+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 1s"
Jul 03 21:44:26 acs019 dockerd[1169]: time="2017-07-03T21:44:26.457655231+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 2s"
Jul 03 21:44:28 acs019 dockerd[1169]: time="2017-07-03T21:44:28.458035032+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 4s"
Jul 03 21:44:32 acs019 dockerd[1169]: time="2017-07-03T21:44:32.458500452+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 8s"
Jul 03 21:44:40 acs019 dockerd[1169]: time="2017-07-03T21:44:40.458849682+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 1s"
Jul 03 21:44:41 acs019 dockerd[1169]: time="2017-07-03T21:44:41.459161934+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 2s"
Jul 03 21:44:43 acs019 dockerd[1169]: time="2017-07-03T21:44:43.459427049+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 4s"
Jul 03 21:44:47 acs019 dockerd[1169]: time="2017-07-03T21:44:47.459712615+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 8s"
Jul 03 21:44:55 acs019 dockerd[1169]: time="2017-07-03T21:44:55.533574211+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 1s"
Jul 03 21:44:56 acs019 dockerd[1169]: time="2017-07-03T21:44:56.533830415+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 2s"
Jul 03 21:44:58 acs019 dockerd[1169]: time="2017-07-03T21:44:58.534086341+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 4s"
Jul 03 21:45:02 acs019 dockerd[1169]: time="2017-07-03T21:45:02.534338300+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 8s"
Jul 03 21:45:10 acs019 dockerd[1169]: time="2017-07-03T21:45:10.534578015+08:00" level=warning msg="Failed to retrieve ipam driver to release interface address on delete of endpoint wechat-web-test1_wechat-web_1 (14c183838ae5acfa42d51cdc1714de6a9be4901aca02ded44c64006c1ac6bcc4): legacy plugin: plugin not found"
Jul 03 21:45:10 acs019 dockerd[1169]: time="2017-07-03T21:45:10.565827304+08:00" level=warning msg="Failed deleting endpoint 05230d9ce450b02e8946dda2c1d5c0a504aa28923fdc84a26a331bfea55575a2: failed to get endpoint from store during Delete: could not find endpoint 05230d9ce450b02e8946dda2c1d5c0a504aa28923fdc84a26a331bfea55575a2: []\n"
Jul 03 21:45:10 acs019 dockerd[1169]: time="2017-07-03T21:45:10.720482017+08:00" level=info msg="Removing stale sandbox 07b955ec788f5aa51ef69af14bc975fc353e9ce15898296be40d96fd1969e372 (bd88ea1c66c29813b92864698caf520e39b2018f36cfca0df764c4201bf0a623)"
Jul 03 21:45:10 acs019 dockerd[1169]: time="2017-07-03T21:45:10.917444986+08:00" level=info msg="Removing stale sandbox 21fdac81435ff2ee08a628c331c5e8bfabc45c344781ae6638dd33464427c58b (6b24b9d74540e61339b4db50d32bfa7f8ddcee6bd45feb67e42173e310442458)"
Jul 03 21:45:11 acs019 dockerd[1169]: time="2017-07-03T21:45:11.054708623+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 1s"
Jul 03 21:45:12 acs019 dockerd[1169]: time="2017-07-03T21:45:12.055069527+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 2s"
Jul 03 21:45:14 acs019 dockerd[1169]: time="2017-07-03T21:45:14.055404004+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 4s"
Jul 03 21:45:18 acs019 dockerd[1169]: time="2017-07-03T21:45:18.055697699+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 8s"
Jul 03 21:45:26 acs019 dockerd[1169]: time="2017-07-03T21:45:26.056148419+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 1s"
Jul 03 21:45:27 acs019 dockerd[1169]: time="2017-07-03T21:45:27.056446445+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 2s"
Jul 03 21:45:29 acs019 dockerd[1169]: time="2017-07-03T21:45:29.056790758+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 4s"
Jul 03 21:45:33 acs019 dockerd[1169]: time="2017-07-03T21:45:33.057107042+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 8s"
Jul 03 21:45:41 acs019 dockerd[1169]: time="2017-07-03T21:45:41.098882553+08:00" level=warning msg="Failed deleting endpoint 27e2bd9ee593cad3f74b44daf90dd5cfbd303d1dbdf9a4cf71546f225509d762: Key not found in store\n"
Jul 03 21:45:41 acs019 dockerd[1169]: time="2017-07-03T21:45:41.111913768+08:00" level=warning msg="Failed deleting endpoint ddfd1e666753387bcf8807c25252b19ef2b1782a91f3b4643cf8c9d70104bb0e: failed to get endpoint from store during Delete: could not find endpoint ddfd1e666753387bcf8807c25252b19ef2b1782a91f3b4643cf8c9d70104bb0e: []\n"
Jul 03 21:45:41 acs019 dockerd[1169]: time="2017-07-03T21:45:41.308115456+08:00" level=error msg="getEndpointFromStore for eid 3291e8acd3672ea80e388cf8753e57f2ef7191d4cfd1c6ef83bfd47fa85d8cd0 failed while trying to build sandbox for cleanup: could not find endpoint 3291e8acd3672ea80e388cf8753e57f2ef7191d4cfd1c6ef83bfd47fa85d8cd0: []"
Jul 03 21:45:41 acs019 dockerd[1169]: time="2017-07-03T21:45:41.308236029+08:00" level=info msg="Removing stale sandbox 5409cea708b5758c9a140d74d8cf77ebff12f5fa2143cab152d22e240064951d (ed81a87122dd5810301b106366471b7b1c4c7a1e1dd93964028c93df747dfc7a)"
Jul 03 21:45:41 acs019 dockerd[1169]: time="2017-07-03T21:45:41.382179622+08:00" level=warning msg="Failed deleting endpoint 3291e8acd3672ea80e388cf8753e57f2ef7191d4cfd1c6ef83bfd47fa85d8cd0: failed to get endpoint from store during Delete: could not find endpoint 3291e8acd3672ea80e388cf8753e57f2ef7191d4cfd1c6ef83bfd47fa85d8cd0: []\n"
Jul 03 21:45:41 acs019 dockerd[1169]: time="2017-07-03T21:45:41.559328681+08:00" level=info msg="Removing stale sandbox 789496e8dde73af70de39e80f3758380a3f16b2495122fa3e7bbeccec1473f0e (821d770aa59e0f77c6488be33a301d34fd8afaace0f8e71bc43670fd12899468)"
Jul 03 21:45:41 acs019 dockerd[1169]: time="2017-07-03T21:45:41.719799978+08:00" level=info msg="Removing stale sandbox 18d0734f1fdbbfeba1d44054434ee27b04ac4320d3e49afdcdf4b693ff01b25c (5c59f3778d5e273a1c12aaba42080816ac3a73a12beb502b192e321fff27f719)"
Jul 03 21:45:41 acs019 dockerd[1169]: time="2017-07-03T21:45:41.943530336+08:00" level=info msg="Removing stale sandbox 415f14ac2bc8c6e4603239c694f87a0e34eee5432c2727d764e1ee9bcfde8e3b (9818e51b0f30e5088cfa4cf7d8089553223bee54c2f7b5317e9751f6a0c6ae19)"
Jul 03 21:45:42 acs019 dockerd[1169]: time="2017-07-03T21:45:42.094544706+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 1s"
Jul 03 21:45:43 acs019 dockerd[1169]: time="2017-07-03T21:45:43.094848027+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 2s"
Jul 03 21:45:45 acs019 dockerd[1169]: time="2017-07-03T21:45:45.095152146+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 4s"
Jul 03 21:45:49 acs019 dockerd[1169]: time="2017-07-03T21:45:49.095440081+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 8s"
Jul 03 21:45:57 acs019 dockerd[1169]: time="2017-07-03T21:45:57.095883611+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 1s"
Jul 03 21:45:58 acs019 dockerd[1169]: time="2017-07-03T21:45:58.096190684+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 2s"
Jul 03 21:46:00 acs019 dockerd[1169]: time="2017-07-03T21:46:00.096517048+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 4s"
Jul 03 21:46:04 acs019 dockerd[1169]: time="2017-07-03T21:46:04.096845537+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 8s"
Jul 03 21:46:12 acs019 dockerd[1169]: time="2017-07-03T21:46:12.165036610+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 1s"
Jul 03 21:46:13 acs019 dockerd[1169]: time="2017-07-03T21:46:13.165303264+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 2s"
Jul 03 21:46:15 acs019 dockerd[1169]: time="2017-07-03T21:46:15.165631964+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 4s"
Jul 03 21:46:19 acs019 dockerd[1169]: time="2017-07-03T21:46:19.165919768+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 8s"
Jul 03 21:46:27 acs019 dockerd[1169]: time="2017-07-03T21:46:27.166230238+08:00" level=warning msg="Failed to retrieve ipam driver to release interface address on delete of endpoint acsrouting_routing_3 (fdd1857e611850b692646d845208d3a97300c97a4e20e6153bdc291ec3155dcb): legacy plugin: plugin not found"
Jul 03 21:46:27 acs019 dockerd[1169]: time="2017-07-03T21:46:27.321102423+08:00" level=warning msg="Failed deleting endpoint abcbcc7fb2f447a8f56d819a01fca7b1b29312343a9d6a826f8d109b1b6c3dcd: failed to get endpoint from store during Delete: could not find endpoint abcbcc7fb2f447a8f56d819a01fca7b1b29312343a9d6a826f8d109b1b6c3dcd: []\n"
Jul 03 21:46:27 acs019 dockerd[1169]: time="2017-07-03T21:46:27.497901105+08:00" level=info msg="Removing stale sandbox 46aa905340890ece67ffe9c98f30988aefb6f7119db56ffe66a0f3a5da2bcae0 (1e0e8267a8f59c182bb5ee39d1e82d671c744b7680698648795082af3a0b094d)"
Jul 03 21:46:27 acs019 dockerd[1169]: time="2017-07-03T21:46:27.712224164+08:00" level=error msg="getEndpointFromStore for eid e00a9e2368106543dc5fa100adcb1b6e4caf149342a5ea91f4609e23264c3048 failed while trying to build sandbox for cleanup: could not find endpoint e00a9e2368106543dc5fa100adcb1b6e4caf149342a5ea91f4609e23264c3048: []"
Jul 03 21:46:27 acs019 dockerd[1169]: time="2017-07-03T21:46:27.712363990+08:00" level=info msg="Removing stale sandbox 7dcdc4aad049d0ed5d7e626e603a4955216e9f96e91cea03e4483b7518a6a20a (15ad8c305b39421327a7930efa7ad03a3be99542716eeb1f52eb9d6b62163c29)"
Jul 03 21:46:27 acs019 dockerd[1169]: time="2017-07-03T21:46:27.780784095+08:00" level=warning msg="Failed deleting endpoint e00a9e2368106543dc5fa100adcb1b6e4caf149342a5ea91f4609e23264c3048: failed to get endpoint from store during Delete: could not find endpoint e00a9e2368106543dc5fa100adcb1b6e4caf149342a5ea91f4609e23264c3048: []\n"
Jul 03 21:46:27 acs019 dockerd[1169]: time="2017-07-03T21:46:27.994618346+08:00" level=info msg="Removing stale sandbox ae86b07f26bdab1be1219a498e4d65ae590145514013d474fd4925e3b2ad010b (c08eba3dbd4fe73a6ef1ebd2ed737123c060b999dda7ee1f28b1a4c4ea04154d)"
Jul 03 21:46:28 acs019 dockerd[1169]: time="2017-07-03T21:46:28.162629931+08:00" level=info msg="Removing stale sandbox da2d8652ad22bf4f3594d7bb844d11cb5870acad314d68366c04e55b74865e14 (d68523826c3ee802f008b5963f9da23b3ac6dc4246bdbe6455743ce4ae3d7671)"
Jul 03 21:46:28 acs019 dockerd[1169]: time="2017-07-03T21:46:28.377304104+08:00" level=error msg="getEndpointFromStore for eid 76a5a67aad257c81e01a9a9cbaf1d8e980dc5643a97f4e57dd62bafa192ced20 failed while trying to build sandbox for cleanup: could not find endpoint 76a5a67aad257c81e01a9a9cbaf1d8e980dc5643a97f4e57dd62bafa192ced20: []"
Jul 03 21:46:28 acs019 dockerd[1169]: time="2017-07-03T21:46:28.377457567+08:00" level=info msg="Removing stale sandbox 163cf5f78163910253ef08f44297d0f10800c5fd45c89cb35542df30d7d66540 (7341f166c062500d77c3e71d6e2c614ac2e5f6ade9cb3d4d6ccd56a705c7a822)"
Jul 03 21:46:28 acs019 dockerd[1169]: time="2017-07-03T21:46:28.460402040+08:00" level=warning msg="Failed deleting endpoint 76a5a67aad257c81e01a9a9cbaf1d8e980dc5643a97f4e57dd62bafa192ced20: failed to get endpoint from store during Delete: could not find endpoint 76a5a67aad257c81e01a9a9cbaf1d8e980dc5643a97f4e57dd62bafa192ced20: []\n"
Jul 03 21:46:28 acs019 dockerd[1169]: time="2017-07-03T21:46:28.664481836+08:00" level=info msg="Removing stale sandbox 42ba941510fee956441ad2d0a48f5fe2dbf1d20c5b737a7ace8e0fa2929af312 (f78d444ddedd4d1660886da708071b0b0cf3a6a592704413260d7e07141d1815)"
Jul 03 21:46:28 acs019 dockerd[1169]: time="2017-07-03T21:46:28.880362051+08:00" level=info msg="Removing stale sandbox 6385c9ee7c2275d8e137eaba1387e396c1bb827aa70b58a2e3ae871dae595a99 (301dcbd3dd6f8d5ac691207e2a9b0a7c924fa3dca666c742ea6d68aa55c76d19)"
Jul 03 21:46:29 acs019 dockerd[1169]: time="2017-07-03T21:46:29.020022149+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 1s"
Jul 03 21:46:30 acs019 dockerd[1169]: time="2017-07-03T21:46:30.020353141+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 2s"
Jul 03 21:46:32 acs019 dockerd[1169]: time="2017-07-03T21:46:32.020666173+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 4s"
Jul 03 21:46:36 acs019 dockerd[1169]: time="2017-07-03T21:46:36.020970473+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 8s"
Jul 03 21:46:44 acs019 dockerd[1169]: time="2017-07-03T21:46:44.021408044+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 1s"
Jul 03 21:46:45 acs019 dockerd[1169]: time="2017-07-03T21:46:45.021730186+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 2s"
Jul 03 21:46:47 acs019 dockerd[1169]: time="2017-07-03T21:46:47.021972221+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 4s"
Jul 03 21:46:51 acs019 dockerd[1169]: time="2017-07-03T21:46:51.022255247+08:00" level=warning msg="Unable to locate plugin: calico, retrying in 8s"
Jul 03 21:46:59 acs019 dockerd[1169]: time="2017-07-03T21:46:59.096006649+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 1s"
Jul 03 21:47:00 acs019 dockerd[1169]: time="2017-07-03T21:47:00.096312623+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 2s"
Jul 03 21:47:02 acs019 dockerd[1169]: time="2017-07-03T21:47:02.096634405+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 4s"
Jul 03 21:47:06 acs019 dockerd[1169]: time="2017-07-03T21:47:06.096960208+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 8s"
Jul 03 21:47:14 acs019 dockerd[1169]: time="2017-07-03T21:47:14.097264730+08:00" level=warning msg="Failed to retrieve ipam driver to release interface address on delete of endpoint jf-mobi-test_jf-mobi_1 (46d78fce590ab87b33e1b582b936ceb5517a5c5213e126d62b234876c5447c44): legacy plugin: plugin not found"
Jul 03 21:47:14 acs019 dockerd[1169]: time="2017-07-03T21:47:14.143646271+08:00" level=warning msg="Failed deleting endpoint e0497414bd9208b783978e8e625be709a7a955702a7845fb8c7cfe3fc0cba3f4: failed to get endpoint from store during Delete: could not find endpoint e0497414bd9208b783978e8e625be709a7a955702a7845fb8c7cfe3fc0cba3f4: []\n"
Jul 03 21:47:14 acs019 dockerd[1169]: time="2017-07-03T21:47:14.256991213+08:00" level=info msg="Removing stale endpoint gateway_264429059e0b (1b46ee9cb56ddb4587e355d71b9f103c809087d4a097dae774cb9ce24e845385)"
Jul 03 21:47:14 acs019 dockerd[1169]: time="2017-07-03T21:47:14.311866810+08:00" level=info msg="Fixing inconsistent endpoint_cnt for network docker_gwbridge. Expected=0, Actual=1"
Jul 03 21:47:14 acs019 dockerd[1169]: time="2017-07-03T21:47:14.677865732+08:00" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address"
Jul 03 21:47:15 acs019 dockerd[1169]: time="2017-07-03T21:47:15.283978143+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 1s"
Jul 03 21:47:15 acs019 dockerd[1169]: time="2017-07-03T21:47:15.293030762+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 1s"
Jul 03 21:47:15 acs019 dockerd[1169]: time="2017-07-03T21:47:15.307071436+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 1s"
Jul 03 21:47:15 acs019 dockerd[1169]: time="2017-07-03T21:47:15.313551227+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 1s"
Jul 03 21:47:15 acs019 dockerd[1169]: time="2017-07-03T21:47:15.315326363+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 1s"
Jul 03 21:47:15 acs019 dockerd[1169]: time="2017-07-03T21:47:15.316319435+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 1s"
Jul 03 21:47:15 acs019 dockerd[1169]: time="2017-07-03T21:47:15.323462127+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 1s"
Jul 03 21:47:15 acs019 dockerd[1169]: time="2017-07-03T21:47:15.336630834+08:00" level=warning msg="error locating sandbox id 21fdac81435ff2ee08a628c331c5e8bfabc45c344781ae6638dd33464427c58b: sandbox 21fdac81435ff2ee08a628c331c5e8bfabc45c344781ae6638dd33464427c58b not found"
Jul 03 21:47:15 acs019 dockerd[1169]: time="2017-07-03T21:47:15.336717239+08:00" level=warning msg="failed to cleanup ipc mounts:\nfailed to umount /var/lib/docker/containers/6b24b9d74540e61339b4db50d32bfa7f8ddcee6bd45feb67e42173e310442458/shm: invalid argument"
Jul 03 21:47:15 acs019 dockerd[1169]: time="2017-07-03T21:47:15.367853208+08:00" level=error msg="Failed to start container 6b24b9d74540e61339b4db50d32bfa7f8ddcee6bd45feb67e42173e310442458: service endpoint with name pos-test_pos_1 already exists"
Jul 03 21:47:16 acs019 dockerd[1169]: time="2017-07-03T21:47:16.284288095+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 2s"
Jul 03 21:47:16 acs019 dockerd[1169]: time="2017-07-03T21:47:16.293273726+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 2s"
Jul 03 21:47:16 acs019 dockerd[1169]: time="2017-07-03T21:47:16.307371255+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 2s"
Jul 03 21:47:16 acs019 dockerd[1169]: time="2017-07-03T21:47:16.313806102+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 2s"
Jul 03 21:47:16 acs019 dockerd[1169]: time="2017-07-03T21:47:16.315544594+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 2s"
Jul 03 21:47:16 acs019 dockerd[1169]: time="2017-07-03T21:47:16.316521415+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 2s"
Jul 03 21:47:16 acs019 dockerd[1169]: time="2017-07-03T21:47:16.323662964+08:00" level=warning msg="Unable to locate plugin: calico-ipam, retrying in 2s"
Jul 04 09:44:09 acs019 dockerd[1169]: time="2017-07-04T09:44:09.754148329+08:00" level=info msg="Processing signal 'terminated'"
Jul 04 09:45:39 acs019 systemd[1]: docker.service: State 'stop-sigterm' timed out. Killing.
Jul 04 09:45:39 acs019 systemd[1]: docker.service: Main process exited, code=killed, status=9/KILL
Jul 04 09:45:39 acs019 systemd[1]: Stopped Docker Application Container Engine.
Jul 04 09:45:39 acs019 systemd[1]: docker.service: Unit entered failed state.
Jul 04 09:45:39 acs019 systemd[1]: docker.service: Failed with result 'signal'.