Docker won't start

lplcleary · 19 April 2023 10:13

running sudo systemctl start docker

produces

Job for docker.service failed because the control process exited with error code.
See "systemctl status docker.service" and "journalctl -xeu docker.service" for details.

output of systemctl status docker.service

× docker.service - Docker Application Container Engine
     Loaded: loaded (/usr/lib/systemd/system/docker.service; disabled; preset: disabled)
     Active: failed (Result: exit-code) since Wed 2023-04-19 11:55:51 CEST; 1min 2s ago
TriggeredBy: × docker.socket
       Docs: https://docs.docker.com
    Process: 102689 ExecStart=/usr/bin/dockerd -H fd:// --containerd=/run/containerd/containerd.sock (code=exited, status=1/FAILURE)
   Main PID: 102689 (code=exited, status=1/FAILURE)
        CPU: 91ms

apr 19 11:55:51 luke-30bgs2s000 systemd[1]: docker.service: Scheduled restart job, restart counter is at 3.
apr 19 11:55:51 luke-30bgs2s000 systemd[1]: Stopped Docker Application Container Engine.
apr 19 11:55:51 luke-30bgs2s000 systemd[1]: docker.service: Start request repeated too quickly.
apr 19 11:55:51 luke-30bgs2s000 systemd[1]: docker.service: Failed with result 'exit-code'.
apr 19 11:55:51 luke-30bgs2s000 systemd[1]: Failed to start Docker Application Container Engine.

output of journalctl -xeu docker.service

░░ Subject: A start job for unit docker.service has failed
░░ Defined-By: systemd
░░ Support: https://forum.manjaro.org/c/support
░░ 
░░ A start job for unit docker.service has finished with a failure.
░░ 
░░ The job identifier is 9014 and the job result is failed.
apr 19 12:03:56 luke-30bgs2s000 systemd[1]: docker.service: Scheduled restart job, restart counter is at 3.
░░ Subject: Automatic restarting of a unit has been scheduled
░░ Defined-By: systemd
░░ Support: https://forum.manjaro.org/c/support
░░ 
░░ Automatic restarting of the unit docker.service has been scheduled, as the result for
░░ the configured Restart= setting for the unit.
apr 19 12:03:56 luke-30bgs2s000 systemd[1]: Stopped Docker Application Container Engine.
░░ Subject: A stop job for unit docker.service has finished
░░ Defined-By: systemd
░░ Support: https://forum.manjaro.org/c/support
░░ 
░░ A stop job for unit docker.service has finished.
░░ 
░░ The job identifier is 9101 and the job result is done.
apr 19 12:03:56 luke-30bgs2s000 systemd[1]: docker.service: Start request repeated too quickly.
apr 19 12:03:56 luke-30bgs2s000 systemd[1]: docker.service: Failed with result 'exit-code'.
░░ Subject: Unit failed
░░ Defined-By: systemd
░░ Support: https://forum.manjaro.org/c/support
░░ 
░░ The unit docker.service has entered the 'failed' state with result 'exit-code'.
apr 19 12:03:56 luke-30bgs2s000 systemd[1]: Failed to start Docker Application Container Engine.
░░ Subject: A start job for unit docker.service has failed
░░ Defined-By: systemd
░░ Support: https://forum.manjaro.org/c/support
░░ 
░░ A start job for unit docker.service has finished with a failure.
░░ 
░░ The job identifier is 9101 and the job result is failed.

output of journalctl --no-hostname --no-pager -u docker.service

apr 19 12:03:50 dockerd[103167]: time="2023-04-19T12:03:50.216124831+02:00" level=info msg="[core] [Channel #1] Channel deleted" module=grpc
apr 19 12:03:50 dockerd[103167]: failed to start daemon: error initializing graphdriver: driver not supported
apr 19 12:03:50 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
apr 19 12:03:50 systemd[1]: docker.service: Failed with result 'exit-code'.
apr 19 12:03:50 systemd[1]: Failed to start Docker Application Container Engine.
apr 19 12:03:52 systemd[1]: docker.service: Scheduled restart job, restart counter is at 1.
apr 19 12:03:52 systemd[1]: Stopped Docker Application Container Engine.
apr 19 12:03:52 systemd[1]: Starting Docker Application Container Engine...
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.562939796+02:00" level=info msg="Starting up"
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.563783245+02:00" level=info msg="[core] [Channel #1] Channel created" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.563798942+02:00" level=info msg="[core] [Channel #1] original dial target is: \"unix:///run/containerd/containerd.sock\"" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.563818196+02:00" level=info msg="[core] [Channel #1] parsed dial target is: {Scheme:unix Authority: Endpoint:run/containerd/containerd.sock URL:{Scheme:unix Opaque: User: Host: Path:/run/containerd/containerd.sock RawPath: OmitHost:false ForceQuery:false RawQuery: Fragment: RawFragment:}}" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.563827679+02:00" level=info msg="[core] [Channel #1] Channel authority set to \"localhost\"" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.563891058+02:00" level=info msg="[core] [Channel #1] Resolver state updated: {\n  \"Addresses\": [\n    {\n      \"Addr\": \"/run/containerd/containerd.sock\",\n      \"ServerName\": \"\",\n      \"Attributes\": {},\n      \"BalancerAttributes\": null,\n      \"Type\": 0,\n      \"Metadata\": null\n    }\n  ],\n  \"ServiceConfig\": null,\n  \"Attributes\": null\n} (resolver returned new addresses)" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.563931403+02:00" level=info msg="[core] [Channel #1] Channel switches to new LB policy \"pick_first\"" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.563975235+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel created" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564008297+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel Connectivity change to CONNECTING" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564038921+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel picks a new address \"/run/containerd/containerd.sock\" to connect" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564076098+02:00" level=info msg="[core] [Channel #1] Channel Connectivity change to CONNECTING" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564240896+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel Connectivity change to READY" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564263582+02:00" level=info msg="[core] [Channel #1] Channel Connectivity change to READY" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564703862+02:00" level=info msg="[core] [Channel #4] Channel created" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564716827+02:00" level=info msg="[core] [Channel #4] original dial target is: \"unix:///run/containerd/containerd.sock\"" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564738252+02:00" level=info msg="[core] [Channel #4] parsed dial target is: {Scheme:unix Authority: Endpoint:run/containerd/containerd.sock URL:{Scheme:unix Opaque: User: Host: Path:/run/containerd/containerd.sock RawPath: OmitHost:false ForceQuery:false RawQuery: Fragment: RawFragment:}}" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564753006+02:00" level=info msg="[core] [Channel #4] Channel authority set to \"localhost\"" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564780362+02:00" level=info msg="[core] [Channel #4] Resolver state updated: {\n  \"Addresses\": [\n    {\n      \"Addr\": \"/run/containerd/containerd.sock\",\n      \"ServerName\": \"\",\n      \"Attributes\": {},\n      \"BalancerAttributes\": null,\n      \"Type\": 0,\n      \"Metadata\": null\n    }\n  ],\n  \"ServiceConfig\": null,\n  \"Attributes\": null\n} (resolver returned new addresses)" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564805722+02:00" level=info msg="[core] [Channel #4] Channel switches to new LB policy \"pick_first\"" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564827360+02:00" level=info msg="[core] [Channel #4 SubChannel #5] Subchannel created" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564852477+02:00" level=info msg="[core] [Channel #4 SubChannel #5] Subchannel Connectivity change to CONNECTING" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564876326+02:00" level=info msg="[core] [Channel #4 SubChannel #5] Subchannel picks a new address \"/run/containerd/containerd.sock\" to connect" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.564885650+02:00" level=info msg="[core] [Channel #4] Channel Connectivity change to CONNECTING" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.565039602+02:00" level=info msg="[core] [Channel #4 SubChannel #5] Subchannel Connectivity change to READY" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.565066329+02:00" level=info msg="[core] [Channel #4] Channel Connectivity change to READY" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.565325137+02:00" level=info msg="[graphdriver] trying configured driver: overlay2"
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.567149388+02:00" level=error msg="failed to mount overlay: no such device" storage-driver=overlay2
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.567327923+02:00" level=info msg="[core] [Channel #1] Channel Connectivity change to SHUTDOWN" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.567348138+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel Connectivity change to SHUTDOWN" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.567359588+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel deleted" module=grpc
apr 19 12:03:52 dockerd[103186]: time="2023-04-19T12:03:52.567368048+02:00" level=info msg="[core] [Channel #1] Channel deleted" module=grpc
apr 19 12:03:52 dockerd[103186]: failed to start daemon: error initializing graphdriver: driver not supported
apr 19 12:03:52 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
apr 19 12:03:52 systemd[1]: docker.service: Failed with result 'exit-code'.
apr 19 12:03:52 systemd[1]: Failed to start Docker Application Container Engine.
apr 19 12:03:54 systemd[1]: docker.service: Scheduled restart job, restart counter is at 2.
apr 19 12:03:54 systemd[1]: Stopped Docker Application Container Engine.
apr 19 12:03:54 systemd[1]: Starting Docker Application Container Engine...
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.787340828+02:00" level=info msg="Starting up"
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.788518657+02:00" level=info msg="[core] [Channel #1] Channel created" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.788533513+02:00" level=info msg="[core] [Channel #1] original dial target is: \"unix:///run/containerd/containerd.sock\"" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.788553280+02:00" level=info msg="[core] [Channel #1] parsed dial target is: {Scheme:unix Authority: Endpoint:run/containerd/containerd.sock URL:{Scheme:unix Opaque: User: Host: Path:/run/containerd/containerd.sock RawPath: OmitHost:false ForceQuery:false RawQuery: Fragment: RawFragment:}}" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.788563586+02:00" level=info msg="[core] [Channel #1] Channel authority set to \"localhost\"" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.788628685+02:00" level=info msg="[core] [Channel #1] Resolver state updated: {\n  \"Addresses\": [\n    {\n      \"Addr\": \"/run/containerd/containerd.sock\",\n      \"ServerName\": \"\",\n      \"Attributes\": {},\n      \"BalancerAttributes\": null,\n      \"Type\": 0,\n      \"Metadata\": null\n    }\n  ],\n  \"ServiceConfig\": null,\n  \"Attributes\": null\n} (resolver returned new addresses)" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.788664970+02:00" level=info msg="[core] [Channel #1] Channel switches to new LB policy \"pick_first\"" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.788710068+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel created" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.788732288+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel Connectivity change to CONNECTING" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.788751475+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel picks a new address \"/run/containerd/containerd.sock\" to connect" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.788773693+02:00" level=info msg="[core] [Channel #1] Channel Connectivity change to CONNECTING" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789003753+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel Connectivity change to READY" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789036514+02:00" level=info msg="[core] [Channel #1] Channel Connectivity change to READY" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789559568+02:00" level=info msg="[core] [Channel #4] Channel created" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789574895+02:00" level=info msg="[core] [Channel #4] original dial target is: \"unix:///run/containerd/containerd.sock\"" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789604127+02:00" level=info msg="[core] [Channel #4] parsed dial target is: {Scheme:unix Authority: Endpoint:run/containerd/containerd.sock URL:{Scheme:unix Opaque: User: Host: Path:/run/containerd/containerd.sock RawPath: OmitHost:false ForceQuery:false RawQuery: Fragment: RawFragment:}}" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789624984+02:00" level=info msg="[core] [Channel #4] Channel authority set to \"localhost\"" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789662149+02:00" level=info msg="[core] [Channel #4] Resolver state updated: {\n  \"Addresses\": [\n    {\n      \"Addr\": \"/run/containerd/containerd.sock\",\n      \"ServerName\": \"\",\n      \"Attributes\": {},\n      \"BalancerAttributes\": null,\n      \"Type\": 0,\n      \"Metadata\": null\n    }\n  ],\n  \"ServiceConfig\": null,\n  \"Attributes\": null\n} (resolver returned new addresses)" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789691965+02:00" level=info msg="[core] [Channel #4] Channel switches to new LB policy \"pick_first\"" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789717680+02:00" level=info msg="[core] [Channel #4 SubChannel #5] Subchannel created" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789740622+02:00" level=info msg="[core] [Channel #4 SubChannel #5] Subchannel Connectivity change to CONNECTING" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789763361+02:00" level=info msg="[core] [Channel #4 SubChannel #5] Subchannel picks a new address \"/run/containerd/containerd.sock\" to connect" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789773201+02:00" level=info msg="[core] [Channel #4] Channel Connectivity change to CONNECTING" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789971916+02:00" level=info msg="[core] [Channel #4 SubChannel #5] Subchannel Connectivity change to READY" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.789994370+02:00" level=info msg="[core] [Channel #4] Channel Connectivity change to READY" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.790266968+02:00" level=info msg="[graphdriver] trying configured driver: overlay2"
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.792211265+02:00" level=error msg="failed to mount overlay: no such device" storage-driver=overlay2
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.792371907+02:00" level=info msg="[core] [Channel #1] Channel Connectivity change to SHUTDOWN" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.792391577+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel Connectivity change to SHUTDOWN" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.792408028+02:00" level=info msg="[core] [Channel #1 SubChannel #2] Subchannel deleted" module=grpc
apr 19 12:03:54 dockerd[103200]: time="2023-04-19T12:03:54.792417041+02:00" level=info msg="[core] [Channel #1] Channel deleted" module=grpc
apr 19 12:03:54 dockerd[103200]: failed to start daemon: error initializing graphdriver: driver not supported
apr 19 12:03:54 systemd[1]: docker.service: Main process exited, code=exited, status=1/FAILURE
apr 19 12:03:54 systemd[1]: docker.service: Failed with result 'exit-code'.
apr 19 12:03:54 systemd[1]: Failed to start Docker Application Container Engine.
apr 19 12:03:56 systemd[1]: docker.service: Scheduled restart job, restart counter is at 3.
apr 19 12:03:56 systemd[1]: Stopped Docker Application Container Engine.
apr 19 12:03:56 systemd[1]: docker.service: Start request repeated too quickly.
apr 19 12:03:56 systemd[1]: docker.service: Failed with result 'exit-code'.
apr 19 12:03:56 systemd[1]: Failed to start Docker Application Container Engine.

I followed the instructions here to switch to overlay but it did not help.

Any ideas?

mithrial · 19 April 2023 12:53

Did you reboot after the installation? (or rather after the last update of your kernel)

megavolt · 19 April 2023 13:23

Most likely, you installed a new kernel (upgrade) in the meantime. No wonder the overlay2 cannot be loaded. A reboot is needed, therefore.

lplcleary · 19 April 2023 14:27

sigh… this worked. Thanks.

Should’ve remembered the first rule of diagnosis – turning it off and back on again.

system · 22 April 2023 04:27

This topic was automatically closed 2 days after the last reply. New replies are no longer allowed.