[systemd-devel] KillUserProcesses timeout

Mikhail Morfikov mmorfikov at gmail.com
Mon Apr 27 09:57:53 PDT 2015


This is the log when my system works as usual:

(loginctl session-status)

1 - morfik (1000)
	   Since: Sun 2015-04-26 23:19:01 CEST; 18h ago
	  Leader: 1720 (lightdm)
	    Seat: seat0; vc7
	 Display: :0
	 Service: lightdm; type x11; class user
	   State: online
	    Unit: session-1.scope
		  ├─ 1720 lightdm --session-child 12 19
		  ├─ 1764 /usr/bin/gnome-keyring-daemon --daemonize --login
		  ├─ 1766 /usr/bin/openbox --startup /usr/lib/x86_64-linux-gnu/openbox-autostart OPENBOX
		  ├─ 1808 /usr/bin/ssh-agent /usr/bin/gpg-agent --daemon --sh --write-env-file=/home/morfik/.gnupg/gpg-agent-info-morfikownia /usr/bin/dbus-launch --exit-with-session /usr/bin/openbox-session
		  ├─ 1809 /usr/bin/gpg-agent --daemon --sh --write-env-file=/home/morfik/.gnupg/gpg-agent-info-morfikownia /usr/bin/dbus-launch --exit-with-session /usr/bin/openbox-session
		  ├─ 1812 /usr/bin/dbus-launch --exit-with-session /usr/bin/openbox-session
		  ├─ 1813 /usr/bin/dbus-daemon --fork --print-pid 5 --print-address 7 --session
		  ├─ 1829 compton --config /home/morfik/.config/compton.conf -b
		  ├─ 1844 /usr/bin/urxvtd -q -f -o
		  ├─ 1845 /usr/bin/urxvtd -q -f -o
		  ├─ 1848 tint2 -c /home/morfik/.config/tint2/tint2rc_up
		  ├─ 1849 tint2 -c /home/morfik/.config/tint2/tint2rc_down
		  ├─ 1880 sg p2p -c megasync
		  ├─ 1881 claws-mail
		  ├─ 1883 volumeicon
		  ├─ 1887 megasync
		  ├─ 1888 xfce4-volumed
		  ├─ 1890 /usr/lib/x86_64-linux-gnu/gconf/gconfd-2
		  ├─ 1911 /usr/lib/x86_64-linux-gnu/xfce4/xfconf/xfconfd
		  ├─ 1912 tmux attach-session -t system-logs
		  ├─ 1972 tmux attach-session -t system-logs
		  ├─ 2000 zsh -c journalctl -b --no-pager --since -10m | ccze -m ansi && systemctl --failed --no-pager | ccze -m ansi && journalctl -n 0 -f | ccze -m ansi
		  ├─ 2003 zsh -c cat /dev/log-lxc | ccze -m ansi -p syslog -C
		  ├─ 2004 newsbeuter
		  ├─ 2056 light-locker
		  ├─ 2129 cat /dev/log-lxc
		  ├─ 2131 ccze -m ansi -p syslog -C
		  ├─ 2177 /usr/lib/at-spi2-core/at-spi-bus-launcher
		  ├─ 2180 /usr/lib/dconf/dconf-service
		  ├─ 2184 /usr/bin/dbus-daemon --config-file=/etc/at-spi2/accessibility.conf --nofork --print-address 3
		  ├─ 2194 /usr/lib/at-spi2-core/at-spi2-registryd --use-gnome-session
		  ├─ 2546 /usr/bin/pulseaudio --start --log-target=syslog
		  ├─ 2637 journalctl -n 0 -f
		  ├─ 2638 ccze -m ansi
		  ├─ 2640 /usr/lib/pulseaudio/pulse/gconf-helper
		  ├─ 2691 tmux attach-session -t tmux
		  ├─ 2794 -zsh
		  ├─ 2881 su -
		  ├─ 2898 -su
		  ├─ 3557 -zsh
		  ├─15023 conky -c /home/morfik/.conky/.conkyrc_fs
		  ├─15060 conky -c /home/morfik/.conky/.conkyrc
		  ├─15899 conky -c /home/morfik/.conky/1c/.conkyrc_1c
		  └─15900 conky -c /home/morfik/.conky/1b1/.conkyrc_1b1_int

Apr 27 13:11:51 morfikownia su[68365]: pam_unix(su:session): session closed for user debian-security-support
Apr 27 13:11:51 morfikownia su[68388]: Successful su for debian-security-support by root
Apr 27 13:11:51 morfikownia su[68388]: + ??? root:debian-security-support
Apr 27 13:11:51 morfikownia su[68388]: pam_unix(su:session): session opened for user debian-security-support by (uid=0)
Apr 27 13:11:51 morfikownia su[68388]: pam_unix(su:session): session closed for user debian-security-support
Apr 27 13:12:10 morfikownia su[69569]: Successful su for morfik by root
Apr 27 13:12:10 morfikownia su[69569]: + ??? root:morfik
Apr 27 13:12:10 morfikownia su[69569]: pam_unix(su:session): session opened for user morfik by (uid=0)
Apr 27 13:12:10 morfikownia org.freedesktop.Notifications[1813]: (xfce4-notifyd:69577): Gtk-WARNING **: Failed to set text from markup due to error parsing markup: Unknown tag 'p' on line 1 char 20
Apr 27 13:12:12 morfikownia su[69569]: pam_unix(su:session): session closed for user morfik

21 - root (0)
	   Since: Mon 2015-04-27 18:00:08 CEST; 6min ago
	  Leader: 41244 (login)
	    Seat: seat0; vc1
	     TTY: /dev/tty1
	 Service: login; type tty; class user
	   State: active
	    Unit: session-21.scope
		  ├─12773 -zsh
		  ├─15435 loginctl session-status 1 21 c1
		  └─41244 /bin/login --     

Apr 27 18:00:08 morfikownia systemd[1]: Started Session 21 of user root.
Apr 27 18:00:08 morfikownia systemd[1]: Starting Session 21 of user root.
Apr 27 18:00:08 morfikownia login[12773]: ROOT LOGIN  on '/dev/tty1'

c1 - lightdm (108)
	   Since: Sun 2015-04-26 23:18:52 CEST; 18h ago
	  Leader: 1693
	    Seat: seat0; vc7
	 Display: :0
	 Service: lightdm-greeter; type x11; class greeter
	   State: closing
	    Unit: session-c1.scope
		  ├─1703 /usr/bin/dbus-launch --autolaunch 159815709bbc46c29ef786cfc497afd4 --binary-syntax --close-stderr
		  ├─1704 /usr/bin/dbus-daemon --fork --print-pid 5 --print-address 7 --session
		  ├─1706 /usr/lib/at-spi2-core/at-spi-bus-launcher
		  ├─1710 /usr/bin/dbus-daemon --config-file=/etc/at-spi2/accessibility.conf --nofork --print-address 3
		  └─1713 /usr/lib/at-spi2-core/at-spi2-registryd --use-gnome-session

Apr 26 23:18:52 morfikownia systemd[1]: Started Session c1 of user lightdm.
Apr 26 23:18:52 morfikownia systemd[1]: Starting Session c1 of user lightdm.
Apr 26 23:18:53 morfikownia org.a11y.Bus[1704]: Activating service name='org.a11y.atspi.Registry'
Apr 26 23:18:54 morfikownia org.a11y.Bus[1704]: Successfully activated service 'org.a11y.atspi.Registry'
Apr 26 23:18:54 morfikownia org.a11y.atspi.Registry[1710]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry
Apr 26 23:19:01 morfikownia lightdm[1693]: pam_unix(lightdm-greeter:session): session closed for user lightdm


(loginctl user-status 1000)

morfik (1000)
	   Since: Sun 2015-04-26 23:19:01 CEST; 18h ago
	   State: online
	Sessions: *1
	    Unit: user-1000.slice
		  ├─session-1.scope
		  │ ├─ 1720 lightdm --session-child 12 19
		  │ ├─ 1764 /usr/bin/gnome-keyring-daemon --daemonize --login
		  │ ├─ 1766 /usr/bin/openbox --startup /usr/lib/x86_64-linux-gnu/openbox-autostart OPENBOX
		  │ ├─ 1808 /usr/bin/ssh-agent /usr/bin/gpg-agent --daemon --sh --write-env-file=/home/morfik/.gnupg/gpg-agent-info-morfikownia /usr/bin/dbus-launch --exit-with-session /usr/bin/openbox-session
		  │ ├─ 1809 /usr/bin/gpg-agent --daemon --sh --write-env-file=/home/morfik/.gnupg/gpg-agent-info-morfikownia /usr/bin/dbus-launch --exit-with-session /usr/bin/openbox-session
		  │ ├─ 1812 /usr/bin/dbus-launch --exit-with-session /usr/bin/openbox-session
		  │ ├─ 1813 /usr/bin/dbus-daemon --fork --print-pid 5 --print-address 7 --session
		  │ ├─ 1829 compton --config /home/morfik/.config/compton.conf -b
		  │ ├─ 1844 /usr/bin/urxvtd -q -f -o
		  │ ├─ 1845 /usr/bin/urxvtd -q -f -o
		  │ ├─ 1848 tint2 -c /home/morfik/.config/tint2/tint2rc_up
		  │ ├─ 1849 tint2 -c /home/morfik/.config/tint2/tint2rc_down
		  │ ├─ 1880 sg p2p -c megasync
		  │ ├─ 1881 claws-mail
		  │ ├─ 1883 volumeicon
		  │ ├─ 1887 megasync
		  │ ├─ 1888 xfce4-volumed
		  │ ├─ 1890 /usr/lib/x86_64-linux-gnu/gconf/gconfd-2
		  │ ├─ 1911 /usr/lib/x86_64-linux-gnu/xfce4/xfconf/xfconfd
		  │ ├─ 1912 tmux attach-session -t system-logs
		  │ ├─ 1972 tmux attach-session -t system-logs
		  │ ├─ 2000 zsh -c journalctl -b --no-pager --since -10m | ccze -m ansi && systemctl --failed --no-pager | ccze -m ansi && journalctl -n 0 -f | ccze -m ansi
		  │ ├─ 2003 zsh -c cat /dev/log-lxc | ccze -m ansi -p syslog -C
		  │ ├─ 2004 newsbeuter
		  │ ├─ 2056 light-locker
		  │ ├─ 2129 cat /dev/log-lxc
		  │ ├─ 2131 ccze -m ansi -p syslog -C
		  │ ├─ 2177 /usr/lib/at-spi2-core/at-spi-bus-launcher
		  │ ├─ 2180 /usr/lib/dconf/dconf-service
		  │ ├─ 2184 /usr/bin/dbus-daemon --config-file=/etc/at-spi2/accessibility.conf --nofork --print-address 3
		  │ ├─ 2194 /usr/lib/at-spi2-core/at-spi2-registryd --use-gnome-session
		  │ ├─ 2546 /usr/bin/pulseaudio --start --log-target=syslog
		  │ ├─ 2637 journalctl -n 0 -f
		  │ ├─ 2638 ccze -m ansi
		  │ ├─ 2640 /usr/lib/pulseaudio/pulse/gconf-helper
		  │ ├─ 2691 tmux attach-session -t tmux
		  │ ├─ 2794 -zsh
		  │ ├─ 2881 su -
		  │ ├─ 2898 -su
		  │ ├─ 3557 -zsh
		  │ ├─15023 conky -c /home/morfik/.conky/.conkyrc_fs
		  │ ├─15060 conky -c /home/morfik/.conky/.conkyrc
		  │ ├─15899 conky -c /home/morfik/.conky/1c/.conkyrc_1c
		  │ └─15900 conky -c /home/morfik/.conky/1b1/.conkyrc_1b1_int
		  └─user at 1000.service
		    ├─1759 /lib/systemd/systemd --user
		    └─1760 (sd-pam)         

Apr 27 13:11:51 morfikownia su[68388]: + ??? root:debian-security-support
Apr 27 13:11:51 morfikownia su[68388]: pam_unix(su:session): session opened for user debian-security-support by (uid=0)
Apr 27 13:11:51 morfikownia su[68388]: pam_unix(su:session): session closed for user debian-security-support
Apr 27 13:12:10 morfikownia su[69569]: Successful su for morfik by root
Apr 27 13:12:10 morfikownia su[69569]: + ??? root:morfik
Apr 27 13:12:10 morfikownia su[69569]: pam_unix(su:session): session opened for user morfik by (uid=0)
Apr 27 13:12:10 morfikownia org.freedesktop.Notifications[1813]: (xfce4-notifyd:69577): Gtk-WARNING **: Failed to set text from markup due to error parsing markup: Unknown tag 'p' on line 1 char 20
Apr 27 13:12:12 morfikownia su[69569]: pam_unix(su:session): session closed for user morfik
Apr 27 14:41:11 morfikownia systemd[1759]: Time has been changed
Apr 27 15:00:33 morfikownia systemd[1759]: Time has been changed

There's one session for lightdm, one for the main user (morfik) and I've logged
to TTY as root so I could collect the data, and I didn't cut anything.

In tmux I have one root session (su -) and I got the following log after logout
from graphical environment:

(loginctl session-status)

1 - morfik (1000)
	   Since: Sun 2015-04-26 23:19:01 CEST; 19h ago
	  Leader: 1720
	    Seat: seat0; vc7
	 Display: :0
	 Service: lightdm; type x11; class user
	   State: closing
	    Unit: session-1.scope
		  ├─1972 tmux attach-session -t system-logs
		  ├─2000 zsh -c journalctl -b --no-pager --since -10m | ccze -m ansi && systemctl --failed --no-pager | ccze -m ansi && journalctl -n 0 -f | ccze -m ansi
		  ├─2003 zsh -c cat /dev/log-lxc | ccze -m ansi -p syslog -C
		  ├─2004 newsbeuter
		  ├─2129 cat /dev/log-lxc
		  ├─2131 ccze -m ansi -p syslog -C
		  ├─2637 journalctl -n 0 -f
		  ├─2638 ccze -m ansi
		  ├─2794 -zsh
		  ├─2881 su -
		  ├─2898 -su
		  └─3557 -zsh

Apr 27 13:11:51 morfikownia su[68388]: pam_unix(su:session): session opened for user debian-security-support by (uid=0)
Apr 27 13:11:51 morfikownia su[68388]: pam_unix(su:session): session closed for user debian-security-support
Apr 27 13:12:10 morfikownia su[69569]: Successful su for morfik by root
Apr 27 13:12:10 morfikownia su[69569]: + ??? root:morfik
Apr 27 13:12:10 morfikownia su[69569]: pam_unix(su:session): session opened for user morfik by (uid=0)
Apr 27 13:12:10 morfikownia org.freedesktop.Notifications[1813]: (xfce4-notifyd:69577): Gtk-WARNING **: Failed to set text from markup due to error parsing markup: Unknown tag 'p' on line 1 char 20
Apr 27 13:12:12 morfikownia su[69569]: pam_unix(su:session): session closed for user morfik
Apr 27 18:20:14 morfikownia lightdm[1720]: pam_unix(lightdm:session): session closed for user morfik
Apr 27 18:20:14 morfikownia gnome-keyring-daemon[1764]: g_dbus_connection_real_closed: Remote peer vanished with error: Underlying GIOStream returned 0 bytes on an async read (g-io-error-quark, 0). Exiting.
Apr 27 18:20:15 morfikownia sg[1880]: user 'morfik' (login 'morfik' on ???) returned to group 'morfik'

21 - root (0)
	   Since: Mon 2015-04-27 18:00:08 CEST; 20min ago
	  Leader: 41244 (login)
	    Seat: seat0; vc1
	     TTY: /dev/tty1
	 Service: login; type tty; class user
	   State: active
	    Unit: session-21.scope
		  ├─12773 -zsh
		  ├─20048 loginctl session-status 1 21 c1 --no-pager
		  └─41244 /bin/login --     

Apr 27 18:00:08 morfikownia systemd[1]: Started Session 21 of user root.
Apr 27 18:00:08 morfikownia systemd[1]: Starting Session 21 of user root.
Apr 27 18:00:08 morfikownia login[12773]: ROOT LOGIN  on '/dev/tty1'


(loginctl user-status 1000)

morfik (1000)
	   Since: Sun 2015-04-26 23:19:01 CEST; 19h ago
	   State: closing
	Sessions: *1
	    Unit: user-1000.slice
		  ├─session-1.scope
		  │ ├─1972 tmux attach-session -t system-logs
		  │ ├─2000 zsh -c journalctl -b --no-pager --since -10m | ccze -m ansi && systemctl --failed --no-pager | ccze -m ansi && journalctl -n 0 -f | ccze -m ansi
		  │ ├─2003 zsh -c cat /dev/log-lxc | ccze -m ansi -p syslog -C
		  │ ├─2004 newsbeuter
		  │ ├─2129 cat /dev/log-lxc
		  │ ├─2131 ccze -m ansi -p syslog -C
		  │ ├─2637 journalctl -n 0 -f
		  │ ├─2638 ccze -m ansi
		  │ ├─2794 -zsh
		  │ ├─2881 su -
		  │ ├─2898 -su
		  │ └─3557 -zsh
		  └─user at 1000.service
		    ├─1759 /lib/systemd/systemd --user
		    └─1760 (sd-pam)         

Apr 27 13:12:10 morfikownia su[69569]: Successful su for morfik by root
Apr 27 13:12:10 morfikownia su[69569]: + ??? root:morfik
Apr 27 13:12:10 morfikownia su[69569]: pam_unix(su:session): session opened for user morfik by (uid=0)
Apr 27 13:12:10 morfikownia org.freedesktop.Notifications[1813]: (xfce4-notifyd:69577): Gtk-WARNING **: Failed to set text from markup due to error parsing markup: Unknown tag 'p' on line 1 char 20
Apr 27 13:12:12 morfikownia su[69569]: pam_unix(su:session): session closed for user morfik
Apr 27 14:41:11 morfikownia systemd[1759]: Time has been changed
Apr 27 15:00:33 morfikownia systemd[1759]: Time has been changed
Apr 27 18:20:14 morfikownia lightdm[1720]: pam_unix(lightdm:session): session closed for user morfik
Apr 27 18:20:14 morfikownia gnome-keyring-daemon[1764]: g_dbus_connection_real_closed: Remote peer vanished with error: Underlying GIOStream returned 0 bytes on an async read (g-io-error-quark, 0). Exiting.
Apr 27 18:20:15 morfikownia sg[1880]: user 'morfik' (login 'morfik' on ???) returned to group 'morfik'

And that's pretty much it. After 17secs (see below), the session disappeared.

Apr 27 18:20:15 morfikownia sg[1880]: user 'morfik' (login 'morfik' on ???) returned to group 'morfik'
Apr 27 18:20:16 morfikownia lightdm[20025]: pam_unix(lightdm-greeter:session): session opened for user lightdm by (uid=0)
Apr 27 18:20:16 morfikownia systemd-logind[1182]: New session c7 of user lightdm.
Apr 27 18:20:16 morfikownia systemd[1]: Started Session c7 of user lightdm.
Apr 27 18:20:16 morfikownia systemd[1]: Starting Session c7 of user lightdm.
Apr 27 18:20:16 morfikownia org.a11y.Bus[20033]: Activating service name='org.a11y.atspi.Registry'
Apr 27 18:20:16 morfikownia org.a11y.Bus[20033]: Successfully activated service 'org.a11y.atspi.Registry'
Apr 27 18:20:16 morfikownia org.a11y.atspi.Registry[20039]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry
Apr 27 18:20:17 morfikownia systemd-logind[1182]: Removed session c1.
Apr 27 18:20:34 morfikownia systemd[1]: Stopped Session 1 of user morfik.
Apr 27 18:20:34 morfikownia systemd[1]: Stopping Session 1 of user morfik.
Apr 27 18:20:34 morfikownia systemd-logind[1182]: Removed session 1.
Apr 27 18:20:34 morfikownia systemd[1]: Stopping User Manager for UID 1000...
Apr 27 18:20:34 morfikownia systemd[1759]: Stopped target Default.
Apr 27 18:20:34 morfikownia systemd[1759]: Stopping Default.
Apr 27 18:20:34 morfikownia systemd[1759]: Reached target Shutdown.
Apr 27 18:20:34 morfikownia systemd[1759]: Starting Shutdown.
Apr 27 18:20:34 morfikownia systemd[1759]: Starting Exit the Session...
Apr 27 18:20:34 morfikownia systemd[1759]: Stopped target Basic System.
Apr 27 18:20:34 morfikownia systemd[1759]: Stopping Basic System.
Apr 27 18:20:34 morfikownia systemd[1759]: Stopped target Sockets.
Apr 27 18:20:34 morfikownia systemd[1759]: Stopping Sockets.
Apr 27 18:20:34 morfikownia systemd[1759]: Stopped target Paths.
Apr 27 18:20:34 morfikownia systemd[1759]: Stopping Paths.
Apr 27 18:20:34 morfikownia systemd[1759]: Stopped target Timers.
Apr 27 18:20:34 morfikownia systemd[1759]: Stopping Timers.
Apr 27 18:20:34 morfikownia systemd[1759]: Received SIGRTMIN+24 from PID 20075 (kill).
Apr 27 18:20:34 morfikownia systemd[1760]: pam_unix(systemd-user:session): session closed for user morfik
Apr 27 18:20:34 morfikownia systemd[1]: Stopped User Manager for UID 1000.
Apr 27 18:20:34 morfikownia systemd[1]: Removed slice user-1000.slice.
Apr 27 18:20:34 morfikownia systemd[1]: Stopping user-1000.slice.
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 801 bytes
Desc: OpenPGP digital signature
URL: <http://lists.freedesktop.org/archives/systemd-devel/attachments/20150427/43254977/attachment-0001.sig>


More information about the systemd-devel mailing list