Bug#940840: systemctl --no-ask-password restart apt-daily-upgrade.timer hangs indefinetely

Marc Haber mh+debian-bugs at zugschlus.de
Fri Sep 20 22:07:44 BST 2019


On Fri, Sep 20, 2019 at 08:11:48PM +0200, Michael Biebl wrote:
> This is strange. I can't reproduce the issue here.
> Then again, systemctl restart apt-daily-upgrade.timer does not pull in a
> sysinit.target/start (and all its dependencies).
> This looks almost like the system has not successfully booted yet.

Indeed, systemctl status says "starting".

> Is this from a system which is booted into multi-user.target or
> graphical.target?

/lib/systemd/system/default.target -> graphical.target, but the machine
doesn't have any graphics. The issue is new.

> What's the output of
> systemctl status

[51/5028]mh at banana:~ $ sudo systemctl status
● banana
    State: starting
     Jobs: 15 queued
   Failed: 0 units
    Since: Fri 2019-09-20 16:26:33 CEST; 6h ago
   CGroup: /
           ├─user.slice
           │ └─user-1001.slice
           │   ├─session-1.scope
           │   │ ├─  514 sshd: mh [priv]
           │   │ ├─  579 sshd: mh at pts/0,pts/1,pts/2
           │   │ ├─  580 -bash
           │   │ ├─ 1621 -bash
           │   │ ├─ 7651 -bash
           │   │ ├─26360 sudo apt upgrade
           │   │ ├─26361 apt upgrade
           │   │ ├─28099 /usr/bin/dpkg --status-fd 19 --configure --pending
           │   │ ├─28100 sh -c (test -x /usr/lib/needrestart/dpkg-status && /usr/lib/needrestart/dpkg-status || cat>
           │   │ ├─28101 sh -c (test -x /usr/lib/needrestart/dpkg-status && /usr/lib/needrestart/dpkg-status || cat>
           │   │ ├─28102 /bin/sh /usr/lib/needrestart/dpkg-status
           │   │ ├─28117 /bin/sh /var/lib/dpkg/info/logrotate.postinst configure 3.14.0-4
           │   │ ├─28139 /bin/systemctl restart logrotate.timer
           │   │ ├─28146 /bin/systemd-tty-ask-password-agent --watch
           │   │ ├─30215 sudo systemctl status
           │   │ ├─30216 systemctl status
           │   │ └─30217 less
           │   └─user at 1001.service
           │     └─init.scope
           │       ├─517 /lib/systemd/systemd --user
           │       └─518 (sd-pam)
           ├─init.scope
           │ └─1 /lib/systemd/systemd --system --deserialize 36
           └─system.slice
             ├─irqbalance.service
             │ └─279 /usr/sbin/irqbalance --foreground
             ├─systemd-time-wait-sync.service
             │ └─190 /lib/systemd/systemd-time-wait-sync
             ├─dbus.service
             │ └─286 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --s>
             ├─avahi-daemon.service
             │ ├─290 avahi-daemon: running [banana.local]
             │ └─294 avahi-daemon: chroot helper
             ├─system-serial\x2dgetty.slice
             │ └─serial-getty at ttyS0.service
             │   └─411 /sbin/agetty -o -p -- \u --keep-baud 115200,38400,9600 ttyS0 vt220
             ├─ntp.service
             │ └─345 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 108:113
             ├─system-getty.slice
             │ └─getty at tty1.service
             │   └─409 /sbin/agetty -o -p -- \u --noclear tty1 linux
             ├─smartd.service
             │ └─276 /usr/sbin/smartd -n
             ├─systemd-logind.service
             │ └─302 /lib/systemd/systemd-logind
             ├─systemd-resolved.service
             │ └─9596 /lib/systemd/systemd-resolved
             ├─mini-buildd.service
             │ ├─297 /usr/bin/python2 /usr/sbin/mini-buildd --verbose -W :::8066
             │ ├─707 gpg-agent --homedir /var/lib/mini-buildd/.gnupg --use-standard-socket --daemon
             │ └─716 gpg-agent --homedir /var/lib/mini-buildd/var/tmp/tmp6yZX2G --use-standard-socket --daemon
             ├─cron.service
             │ ├─  295 /usr/sbin/cron -f
             │ ├─30193 /usr/sbin/CRON -f
             │ ├─30194 /bin/sh -c    test -x /usr/sbin/cron-apt && /usr/sbin/cron-apt /etc/cron-apt/config /etc/cro>
             │ ├─30195 /bin/sh /usr/sbin/cron-apt /etc/cron-apt/config /etc/cron-apt/config-hourly
             │ └─30208 sleep 1856
             ├─systemd-udevd.service
             │ └─9720 /lib/systemd/systemd-udevd
             ├─rsyslog.service
             │ └─292 /usr/sbin/rsyslogd -n -iNONE
             ├─atop.service
             │ └─315 /usr/bin/atop -R -w /var/log/atop/atop_20190920 600
             ├─atd.service
             │ └─303 /usr/sbin/atd -f
             ├─systemd-journald.service
             │ └─9602 /lib/systemd/systemd-journald
             ├─atopacct.service
             │ └─283 /usr/sbin/atopacctd
             ├─haveged.service
             │ └─267 /usr/sbin/haveged --Foreground --verbose=1 -w 1024
             └─systemd-networkd.service
               └─9590 /lib/systemd/systemd-networkd
[52/5029]mh at banana:~ $ 

> systemd list-jobs

[52/5029]mh at banana:~ $ sudo systemctl list-jobs | cat
JOB UNIT                                 TYPE  STATE  
 65 timers.target                        start waiting
 70 exim4-base.timer                     start waiting
 44 time-sync.target                     start waiting
 72 fstrim.timer                         start waiting
 67 apt-daily.timer                      start waiting
118 ippl.service                         start waiting
103 anacron.service                      start waiting
101 systemd-update-utmp-runlevel.service start waiting
 68 anacron.timer                        start waiting
 69 apt-daily-upgrade.timer              start waiting
 73 e2scrub_all.timer                    start waiting
  2 multi-user.target                    start waiting
  1 graphical.target                     start waiting
 52 systemd-time-wait-sync.service       start running
 71 logrotate.timer                      start waiting

15 jobs listed.
[53/5030]mh at banana:~ $ 

3 [55/5032]mh at banana:~ $ sudo systemctl status systemd-time-wait-sync.service | cat
● systemd-time-wait-sync.service - Wait Until Kernel Time Synchronized
   Loaded: loaded (/lib/systemd/system/systemd-time-wait-sync.service; enabled; vendor preset: enabled)
   Active: activating (start) since Fri 2019-09-20 16:26:34 CEST; 6h ago
     Docs: man:systemd-time-wait-sync.service(8)
 Main PID: 190 (systemd-time-wa)
    Tasks: 1 (limit: 2367)
   Memory: 560.0K
   CGroup: /system.slice/systemd-time-wait-sync.service
           └─190 /lib/systemd/systemd-time-wait-sync

Sep 20 18:55:29 banana systemd[1]: systemd-time-wait-sync.service: Reinstalled deserialized job systemd-time-wait-sync.service/start as 52
Sep 20 18:55:29 banana systemd[1]: systemd-time-wait-sync.service: Changed dead -> start
Sep 20 18:55:36 banana systemd[1]: systemd-time-wait-sync.service: Reinstalled deserialized job systemd-time-wait-sync.service/start as 52
Sep 20 18:55:36 banana systemd[1]: systemd-time-wait-sync.service: Changed dead -> start
Sep 20 18:55:53 banana systemd[1]: systemd-time-wait-sync.service: Reinstalled deserialized job systemd-time-wait-sync.service/start as 52
Sep 20 18:55:53 banana systemd[1]: systemd-time-wait-sync.service: Changed dead -> start
Sep 20 18:55:59 banana systemd[1]: systemd-time-wait-sync.service: Reinstalled deserialized job systemd-time-wait-sync.service/start as 52
Sep 20 18:55:59 banana systemd[1]: systemd-time-wait-sync.service: Changed dead -> start
Sep 20 18:56:33 banana systemd[1]: systemd-time-wait-sync.service: Reinstalled deserialized job systemd-time-wait-sync.service/start as 52
Sep 20 18:56:34 banana systemd[1]: systemd-time-wait-sync.service: Changed dead -> start
[56/5033]mh at banana:~ $ 

What is going on here?

The machine has an ntpd running which says that it is synced to the network's ntp server.

> systemd-analyze dump

Attached, too long.

Greetings
Marc


-- 
-----------------------------------------------------------------------------
Marc Haber         | "I don't trust Computers. They | Mailadresse im Header
Leimen, Germany    |  lose things."    Winona Ryder | Fon: *49 6224 1600402
Nordisch by Nature |  How to make an American Quilt | Fax: *49 6224 1600421



More information about the Pkg-systemd-maintainers mailing list