[systemd-devel] systemd hangs for few to approx. 90 seconds on shutdown terminating bash

[Andrey Borzenkov]

On Sat, Feb 12, 2011 at 6:40 PM, Lucian Muresan
<lucianm at users.sourceforge.net> wrote:
> Hi there,
>
> I very often have to wonder why this PC running systemd-17 on Gentoo
> hangs that long on reboot (issued in a ssh console as "systemctl reboot"
> for example). I set the kernel command line option
> "systemd.log_level=debug" and could find the following pattern (in this
> case with only a gap of approx. 8 seconds between the timestamps
> containing the "bash" process and the following ones, but very often
> approx. 90 seconds.
>

Those two events are rather unrelated. The first one is final stage of
stopping some service and the second one is request to start some
unit. Most likely after former has finished systemd waits for
something else. Request comes asynchronously so there is no specific
timing.

> Any idea how to debug this?
>

Watch for timeout messages in full debug log; this is the first suspect.

BTW your configuration does not look clean, far too many service that
do not exist ...

> Regards,
> Lucian
>
>
> 2011-02-12T16:29:17.022410+01:00 htpc2 kernel: [ 1745.917543]
> systemd[1]: Received SIGCHLD
> 2011-02-12T16:29:17.022455+01:00 htpc2 kernel: [ 1745.917719]
> systemd[1]: Got SIGCHLD for process 5437 (bash)
> 2011-02-12T16:29:17.022463+01:00 htpc2 kernel: [ 1745.917878]
> systemd[1]: Child 5437 died (code=killed, status=1/HUP)
> 2011-02-12T16:29:25.399891+01:00 htpc2 kernel: [ 1754.294019]
> systemd[1]: Accepted connection on private bus.
> 2011-02-12T16:29:25.399933+01:00 htpc2 kernel: [ 1754.295478]
> systemd[1]: Got D-Bus request:
> org.freedesktop.systemd1.Manager.StartUnit() on /org/freedesktop/systemd1
> 2011-02-12T16:29:25.430078+01:00 htpc2 kernel: [ 1754.325033]
> systemd[1]: Failed to load configuration for
> console-kit-system-start.service: No such file or directory
> 2011-02-12T16:29:25.437113+01:00 htpc2 kernel: [ 1754.332793]
> systemd[1]: Failed to load configuration for prefdm.service: No such
> file or directory
> 2011-02-12T16:29:25.437143+01:00 htpc2 kernel: [ 1754.332814]
> systemd[1]: Trying to enqueue job reboot.target/start/replace
> 2011-02-12T16:29:25.439752+01:00 htpc2 kernel: [ 1754.334273]
> systemd[1]: Fixing conflicting jobs by deleting job reboot.target/stop

Which if course does not tell anything about what really caused this conflict :(


> 2011-02-12T16:29:25.439772+01:00 htpc2 kernel: [ 1754.334290]
> systemd[1]: Deleting job reboot.service/stop as dependency of job
> reboot.target/stop
> 2011-02-12T16:29:25.439780+01:00 htpc2 kernel: [ 1754.334303]
> systemd[1]: Deleting job shutdown.target/stop as dependency of job
> reboot.service/stop
> 2011-02-12T16:29:25.439790+01:00 htpc2 kernel: [ 1754.334331]
> systemd[1]: Deleting job alsasound-store.service/start as dependency of
> job shutdown.target/stop
> _______________________________________________
> systemd-devel mailing list
> systemd-devel at lists.freedesktop.org
> http://lists.freedesktop.org/mailman/listinfo/systemd-devel
>
>