• If you are still using CentOS 7.9, it's time to convert to Alma 8 with the free centos2alma tool by Plesk or Plesk Migrator. Please let us know your experiences or concerns in this thread:
    CentOS2Alma discussion
  • Inviting everyone to the UX test of a new security feature in the WP Toolkit
    For WordPress site owners, threats posed by hackers are ever-present. Because of this, we are developing a new security feature for the WP Toolkit. If the topic of WordPress website security is relevant to you, we would be grateful if you could share your experience and help us test the usability of this feature. We invite you to join us for a 1-hour online session via Google Meet. Select a convenient meeting time with our friendly UX staff here.

Issue Docker containers don't start after plesk/extension upgrades

kassi

New Pleskian
Server operating system version
Ubuntu 22.04.3 LTS
Plesk version and microupdate number
18.0.57 #5
Last night the docker related packages have been updated on the system automatically (containerd.io docker-compose-plugin docker-ce-cli docker-ce).
This morning no container was running, although every container should restart automatically.

I can see in the syslog around 01:17, that containerd is being stopped and started. However there are a lot of errors listed.

If I try to start a container manually, it fails with the message:
Code:
{"message":"failed to create task for container: task bb5f956df7a59461df6ae8dfb0f110e737c75dc73fea1b1d5174d8341bcb7310: already exists"}

Since I had comparable problems last time, where 1 out of 4 containers won't start and it was a manual hassle to get it all running again, I'd like to understand:
1. How can I fix it so that the containers will start again?
2. How can I prevent this happening again on the next package upgrade?

Thank you for helping.

Here's an excerpt of the egrep '(containerd|docker)' /var/log/syslog.1 | grep 'Feb 4'. Full log from this day (docker|containerd) attached.
Code:
Feb  4 01:17:18 myserver systemd[1]: Stopping containerd container runtime...
Feb  4 01:17:18 myserver systemd[1]: containerd.service: Deactivated successfully.
Feb  4 01:17:18 myserver systemd[1]: containerd.service: Unit process 3400 (containerd-shim) remains running after unit stopped.
Feb  4 01:17:18 myserver systemd[1]: containerd.service: Unit process 3452 (containerd-shim) remains running after unit stopped.
...
Feb  4 01:17:18 myserver dockerd[1477]: time="2024-02-04T01:17:18.909092432+01:00" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby
Feb  4 01:17:18 myserver dockerd[1477]: time="2024-02-04T01:17:18.909254276+01:00" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby
Feb  4 01:17:18 myserver dockerd[1477]: time="2024-02-04T01:17:18.909878626+01:00" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby
Feb  4 01:17:18 myserver dockerd[1477]: time="2024-02-04T01:17:18.909886941+01:00" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby
Feb  4 01:17:20 myserver dockerd[1477]: time="2024-02-04T01:17:20.322437303+01:00" level=warning msg="Health check for container 05dae063affeed5dafeca0280b3528647d016758e3ad4c274b5a6726185226a3 error: connection error: desc = \"transport: Error while dialing: dial unix:///run/containerd/containerd.sock: timeout\": unavailable"
Feb  4 01:17:21 myserver systemd[1]: Stopped containerd container runtime.
Feb  4 01:17:21 myserver systemd[1]: containerd.service: Found left-over process 3400 (containerd-shim) in control group while starting unit. Ignoring.
...
Feb  4 01:17:25 myserver dockerd[1477]: time="2024-02-04T01:17:25.323687421+01:00" level=warning msg="Health check for container 05dae063affeed5dafeca0280b3528647d016758e3ad4c274b5a6726185226a3 error: connection error: desc = \"transport: Error while dialing: dial unix:///run/containerd/containerd.sock: timeout\": unavailable"
Feb  4 01:17:26 myserver containerd[346095]: time="2024-02-04T01:17:26+01:00" level=warning msg="containerd config version `1` has been deprecated and will be removed in containerd v2.0, please switch to version `2`, see https://github.com/containerd/containerd/blob/main/docs/PLUGINS.md#version-header"
Feb  4 01:17:26 myserver containerd[346095]: time="2024-02-04T01:17:26.398395667+01:00" level=info msg="starting containerd" revision=ae07eda36dd25f8a1b98dfbf587313b99c0190bb version=1.6.28
Feb  4 01:17:26 myserver containerd[346095]: time="2024-02-04T01:17:26.422873554+01:00" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Feb  4 01:17:26 myserver containerd[346095]: time="2024-02-04T01:17:26.422963032+01:00" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Feb  4 01:17:26 myserver containerd[346095]: time="2024-02-04T01:17:26.425584596+01:00" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.2.0\\n\"): skip plugin" type=io.containerd.snapshotter.v1
...
Feb  4 01:17:26 myserver containerd[346095]: time="2024-02-04T01:17:26.516440761+01:00" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin"
Feb  4 01:17:26 myserver containerd[346095]: time="2024-02-04T01:17:26.516826024+01:00" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Feb  4 01:17:26 myserver containerd[346095]: time="2024-02-04T01:17:26.516887569+01:00" level=info msg=serving... address=/run/containerd/containerd.sock
Feb  4 01:17:26 myserver containerd[346095]: time="2024-02-04T01:17:26.516952991+01:00" level=info msg="containerd successfully booted in 0.121445s"
Feb  4 01:17:26 myserver systemd[1]: Started containerd container runtime.
Feb  4 01:18:08 myserver dockerd[1477]: time="2024-02-04T01:18:08.730475718+01:00" level=info msg="Processing signal 'terminated'"
Feb  4 01:18:19 myserver dockerd[1477]: time="2024-02-04T01:18:19.146783303+01:00" level=info msg="Container failed to exit within 10s of signal 15 - using the force" container=2616af3cf113c404d29d34f239816b778a1afc27907b0708ce3bb7ce37b77ea2
...
Feb  4 01:18:23 myserver dockerd[1477]: time="2024-02-04T01:18:23.916394785+01:00" level=error msg="Force shutdown daemon"
Feb  4 01:18:23 myserver dockerd[1477]: time="2024-02-04T01:18:23.921558984+01:00" level=info msg="Daemon shutdown complete"
Feb  4 01:18:23 myserver dockerd[1477]: time="2024-02-04T01:18:23.921727029+01:00" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=plugins.moby
Feb  4 01:18:23 myserver dockerd[1477]: time="2024-02-04T01:18:23.921769018+01:00" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=moby
Feb  4 01:18:24 myserver systemd[1]: docker.service: Deactivated successfully.
Feb  4 01:18:29 myserver systemd[1]: docker.socket: Deactivated successfully.
Feb  4 01:18:36 myserver dockerd[347660]: time="2024-02-04T01:18:36.344623745+01:00" level=info msg="Starting up"
Feb  4 01:18:36 myserver dockerd[347660]: time="2024-02-04T01:18:36.354365744+01:00" level=info msg="detected 127.0.0.53 nameserver, assuming systemd-resolved, so using resolv.conf: /run/systemd/resolve/resolv.conf"
Feb  4 01:18:38 myserver systemd[1]: var-lib-docker-overlay2-metacopy\x2dcheck1477200603-merged.mount: Deactivated successfully.
Feb  4 01:18:38 myserver dockerd[347660]: time="2024-02-04T01:18:38.446822596+01:00" level=info msg="[graphdriver] using prior storage driver: overlay2"
Feb  4 01:18:38 myserver dockerd[347660]: time="2024-02-04T01:18:38.525768941+01:00" level=info msg="Loading containers: start."
Feb  4 01:18:38 myserver systemd[1]: var-lib-docker-overlay2-9e89cfd7fe2534b920d678e541930ad0243337915ad18e272a48270054a3de29-merged.mount: Deactivated successfully.
...
Feb  4 01:19:18 myserver systemd[1]: run-docker-netns-001e2f44e8d5.mount: Deactivated successfully.
Feb  4 01:19:18 myserver dockerd[347660]: time="2024-02-04T01:19:18.659468754+01:00" level=info msg="Removing stale sandbox 001e2f44e8d5fd7ae7b6170e963ecdc58c5359789d6d191c249f92e251966888 (2325f38a1471dcb4b13d5fb47281fa48d642ab2883d57f8215eba83af4025f9d)"
Feb  4 01:19:18 myserver dockerd[347660]: time="2024-02-04T01:19:18.777075618+01:00" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 44c340998ad181ca3a3d44c5c18ab4d8138db1ae5e5cb546a2e78138a2323d8d 289d638cbc715edc85b56887bce7a044bb2c52740e1e347247fb578196544058], retrying...."
Feb  4 01:19:18 myserver systemd[1]: run-docker-netns-001e2f44e8d5.mount: Deactivated successfully.
...
Feb  4 01:20:14 myserver dockerd[347660]: time="2024-02-04T01:20:14.713645699+01:00" level=error msg="2325f38a1471dcb4b13d5fb47281fa48d642ab2883d57f8215eba83af4025f9d cleanup: failed to delete container from containerd: container \"2325f38a1471dcb4b13d5fb47281fa48d642ab2883d57f8215eba83af4025f9d\" in namespace \"moby\": not found"
...
Feb  4 01:20:15 myserver systemd[1]: var-lib-docker-overlay2-2fa4d5c9490bb064222243fa793ca4b3a801bc578061fc56f94cd88ea77a8a03-merged.mount: Deactivated successfully.
Feb  4 01:20:15 myserver dockerd[347660]: time="2024-02-04T01:20:15.863382455+01:00" level=error msg="failed to start container" container=2325f38a1471dcb4b13d5fb47281fa48d642ab2883d57f8215eba83af4025f9d error="failed to create task for container: task 2325f38a1471dcb4b13d5fb47281fa48d642ab2883d57f8215eba83af4025f9d: already exists"
Feb  4 01:20:16 myserver systemd[1]: var-lib-docker-overlay2-8c5dc98fe07c5f22c34076339e9b3985dc60172a7cbecf23b60eb11b50d6349b-merged.mount: Deactivated successfully.
Feb  4 01:20:17 myserver dockerd[347660]: time="2024-02-04T01:20:17.120507735+01:00" level=warning msg="xtables contention detected while running [--wait -t nat -A DOCKER -p tcp -d 0/0 --dport 9001 -j DNAT --to-destination 192.168.0.2:9001 ! -i br-f2231fdee7e6]: Waited for 2.69 seconds and received \"\""
Feb  4 01:20:17 myserver systemd[1]: var-lib-docker-overlay2-4528cebfe1e019d91664e63bf6a24ea49280a771a52852565dea320971c3acfe-merged.mount: Deactivated successfully.
Feb  4 01:20:17 myserver dockerd[347660]: time="2024-02-04T01:20:17.620815879+01:00" level=error msg="failed to start container" container=9bdcd03834eb8a6c230016530da692d20f057da10475c936f040fb936639fae5 error="failed to create task for container: task 9bdcd03834eb8a6c230016530da692d20f057da10475c936f040fb936639fae5: already exists"
...
 

Attachments

  • syslog.docker.log.zip
    8.2 KB · Views: 0
Back
Top