Thank you. While debugging, I think I found the problem of the slow startx: xauth's segfault. I'm going to search for this bug. Your answers:
On Thu, Dec 21, 2017 at 6:44 AM, Simon McVittie <s...@debian.org> wrote: > Control: tags -1 + moreinfo > > On Thu, 21 Dec 2017 at 01:34:32 -0200, Carlos Eduardo M. Santos wrote: > > The following problems started after dist-upgrading from jessie to buster > > I hope you upgraded from jessie to stretch first, and then from stretch > to buster. Upgrades that skip a release are not supported by Debian. > (If it worked, then it's *probably* OK, but please go via stretch on any > other machines you upgrade in the same way.) > I upgraded from stretch. I'm sorry, I got confused with the names. > > > 1. startx takes a lot of time to launch X; > > 2. gnome-terminal doesn't start. > > How do you get into the environment from which you run startx? Is it a > text-mode console (getty/login) or something else? > tty > > Is dbus-user-session installed? Is libpam-systemd installed? > dbus-user-session/testing,now 1.12.2-1 amd64 libpam-systemd/testing,now 235-3 amd64 > > What's in your /etc/X11/Xsession.options? > allow-failsafe allow-user-resources allow-user-xsession use-ssh-agent use-session-dbus > > If you drop a script into /etc/X11/Xsession.d/01debug-early or similar > that runs > > env | LC_ALL=C sort | logger -t Xsession-early > > then look at the systemd journal, what environment variables are set > there? (In particular LANG, LANGUAGE, LC_anything, DBUS_anything, > XDG_RUNTIME_DIR) > > Similarly, if you put > > env | LC_ALL=C sort | logger -t Xsession-late > > right at the beginning of 95dbus_update-activation-env, what happens there? > In both cases, there's no output in journal. I'm afraid the files in its dir are not being read. However, I manually ran these commands in xterm with the marker "xterm". There's no DBUS related variable: Dec 21 12:48:12 kytos xterm[19709]: LANG=en_US.UTF-8 Dec 21 12:48:12 kytos xterm[19709]: SHELL=/bin/zsh Dec 21 12:48:12 kytos xterm[19709]: XAUTHORITY=/home/cemsbr/.Xauthority Dec 21 12:48:12 kytos xterm[19709]: XDG_RUNTIME_DIR=/run/user/1000 Dec 21 12:48:12 kytos xterm[19709]: XTERM_LOCALE=en_US.UTF-8 Dec 21 12:48:12 kytos xterm[19709]: XTERM_SHELL=/bin/zsh > > In journalctl, I found 2 famous messages: > > - gnome-terminal-server[18005]: Unable to init server: Could not > connect: Connection refused > > - Failed to activate service 'org.gnome.Terminal': timed out > (service_start_timeout=120000ms) > > I'm sure you get more messages than just those two. Please show me the > rest of what's logged when you: > > - start from not being logged in > - log in at the text-mode prompt, or whatever else you do to get into the > environment from which you want to run startx > - put a marker in the log: logger -t marker "now I am logged in" > - run startx > - journalctl_report.txt: Note: I had to switch to tty2 and kill Xorg to quit; - journalctl_report_workaround.txt: a successful launch of gnome-terminal using the workaround in .xinitrc. > > The output of systemd-cgls after you get into your GUI session (use > a non-D-Bus-related terminal like xterm) would also be useful > information. > systemd-cgls.txt > > Regards, > smcv > Best, cems
Dec 21 12:22:35 kytos kernel: xauth[13999]: segfault at 1 ip 00007f70be84e405 sp 00007fff744a2a28 error 4 in libc-2.25.so[7f70be7b8000+199000] Dec 21 12:18:13 kytos login[12126]: pam_unix(login:session): session opened for user cemsbr by LOGIN(uid=0) Dec 21 12:18:13 kytos systemd[1]: Created slice User Slice of cemsbr. Dec 21 12:18:13 kytos systemd[1]: Starting User Manager for UID 1000... Dec 21 12:18:13 kytos systemd-logind[888]: New session 17 of user cemsbr. Dec 21 12:18:13 kytos systemd[1]: Started Session 17 of user cemsbr. Dec 21 12:18:13 kytos systemd[12136]: pam_unix(systemd-user:session): session opened for user cemsbr by (uid=0) Dec 21 12:18:13 kytos systemd[12136]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 21 12:18:13 kytos systemd[12136]: Reached target Timers. Dec 21 12:18:13 kytos systemd[12136]: Reached target Paths. Dec 21 12:18:13 kytos systemd[12136]: Listening on Sound System. Dec 21 12:18:13 kytos systemd[12136]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Dec 21 12:18:13 kytos systemd[12136]: Listening on GnuPG network certificate management daemon. Dec 21 12:18:13 kytos systemd[12136]: Starting D-Bus User Message Bus Socket. Dec 21 12:18:13 kytos systemd[12136]: Listening on GnuPG cryptographic agent and passphrase cache. Dec 21 12:18:13 kytos systemd[12136]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Dec 21 12:18:13 kytos systemd[12136]: Listening on D-Bus User Message Bus Socket. Dec 21 12:18:13 kytos systemd[12136]: Reached target Sockets. Dec 21 12:18:13 kytos systemd[12136]: Reached target Basic System. Dec 21 12:18:13 kytos systemd[12136]: Reached target Default. Dec 21 12:18:13 kytos systemd[12136]: Startup finished in 17ms. Dec 21 12:18:13 kytos systemd[1]: Started User Manager for UID 1000. Dec 21 12:18:20 kytos marker[12351]: Now I am logged in Dec 21 12:18:34 kytos kernel: xauth[12556]: segfault at 1 ip 00007f058e06d405 sp 00007fff095bb9e8 error 4 in libc-2.25.so[7f058dfd7000+199000] Dec 21 12:18:54 kytos pkexec[12566]: pam_unix(polkit-1:session): session opened for user root by (uid=1000) Dec 21 12:18:54 kytos pkexec[12566]: cemsbr: Executing command [USER=root] [TTY=unknown] [CWD=/home/cemsbr] [COMMAND=/usr/lib/xserver-xorg-video-intel/xf86-video-intel-backlight-helper intel_backlight] Dec 21 12:19:34 kytos marker[12982]: Launching gnome-terminal Dec 21 12:19:48 kytos systemd[12136]: Started D-Bus User Message Bus. Dec 21 12:19:48 kytos dbus-daemon[13163]: [session uid=1000 pid=13163] Activating via systemd: service name='org.a11y.Bus' unit='at-spi-dbus-bus.service' requested by ':1.1' (uid=1000 pid=13161 comm="/usr/bin/gnome-terminal ") Dec 21 12:19:48 kytos systemd[12136]: Starting Accessibility services bus... Dec 21 12:19:48 kytos dbus-daemon[13163]: [session uid=1000 pid=13163] Successfully activated service 'org.a11y.Bus' Dec 21 12:19:48 kytos systemd[12136]: Started Accessibility services bus. Dec 21 12:19:48 kytos at-spi-bus-launcher[13164]: dbus-daemon[13169]: Activating service name='org.a11y.atspi.Registry' requested by ':1.0' (uid=1000 pid=13161 comm="/usr/bin/gnome-terminal ") Dec 21 12:19:48 kytos dbus-daemon[13163]: [session uid=1000 pid=13163] Activating via systemd: service name='org.gnome.Terminal' unit='gnome-terminal-server.service' requested by ':1.3' (uid=1000 pid=13161 comm="/usr/bin/gnome-terminal ") Dec 21 12:19:48 kytos systemd[12136]: Starting GNOME Terminal Server... Dec 21 12:19:48 kytos at-spi-bus-launcher[13164]: dbus-daemon[13169]: Successfully activated service 'org.a11y.atspi.Registry' Dec 21 12:19:48 kytos at-spi-bus-launcher[13164]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry Dec 21 12:19:48 kytos gnome-terminal-server[13174]: Unable to init server: Could not connect: Connection refused Dec 21 12:19:48 kytos gnome-terminal-server[13174]: Failed to parse arguments: Cannot open display: Dec 21 12:19:48 kytos systemd[12136]: gnome-terminal-server.service: Main process exited, code=exited, status=10/n/a Dec 21 12:19:48 kytos systemd[12136]: gnome-terminal-server.service: Failed with result 'exit-code'. Dec 21 12:19:48 kytos systemd[12136]: Failed to start GNOME Terminal Server. Dec 21 12:20:35 kytos marker[13268]: Quitting Dec 21 12:21:55 kytos systemd[1]: getty@tty2.service: Service has no hold-off time, scheduling restart. Dec 21 12:21:55 kytos systemd[1]: getty@tty2.service: Scheduled restart job, restart counter is at 5. Dec 21 12:21:55 kytos systemd[1]: Stopped Getty on tty2. Dec 21 12:21:55 kytos systemd[1]: Started Getty on tty2. Dec 21 12:21:55 kytos systemd-logind[888]: Removed session 19. Dec 21 12:22:07 kytos login[12126]: pam_unix(login:session): session closed for user cemsbr Dec 21 12:22:07 kytos systemd[1]: getty@tty1.service: Service has no hold-off time, scheduling restart. Dec 21 12:22:07 kytos systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 5. Dec 21 12:22:07 kytos systemd[1]: Stopped Getty on tty1. Dec 21 12:22:07 kytos systemd[1]: Started Getty on tty1. Dec 21 12:22:07 kytos systemd-logind[888]: Removed session 17. Dec 21 12:22:07 kytos systemd[1]: Stopping User Manager for UID 1000... Dec 21 12:22:07 kytos systemd[12136]: Stopping D-Bus User Message Bus... Dec 21 12:22:07 kytos systemd[12136]: Stopping Accessibility services bus... Dec 21 12:22:07 kytos systemd[12136]: Stopped target Default. Dec 21 12:22:07 kytos systemd[12136]: Stopped D-Bus User Message Bus. Dec 21 12:22:07 kytos systemd[12136]: Stopped Accessibility services bus. Dec 21 12:22:07 kytos systemd[12136]: Stopped target Basic System. Dec 21 12:22:07 kytos systemd[12136]: Stopped target Sockets. Dec 21 12:22:07 kytos systemd[12136]: Closed GnuPG cryptographic agent (ssh-agent emulation). Dec 21 12:22:07 kytos systemd[12136]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 21 12:22:07 kytos systemd[12136]: Closed Sound System. Dec 21 12:22:07 kytos systemd[12136]: Closed GnuPG cryptographic agent and passphrase cache (restricted). Dec 21 12:22:07 kytos systemd[12136]: Closed GnuPG network certificate management daemon. Dec 21 12:22:07 kytos systemd[12136]: Closed GnuPG cryptographic agent and passphrase cache. Dec 21 12:22:07 kytos systemd[12136]: Stopped target Paths. Dec 21 12:22:07 kytos systemd[12136]: Stopped target Timers. Dec 21 12:22:07 kytos systemd[12136]: Closed D-Bus User Message Bus Socket. Dec 21 12:22:07 kytos systemd[12136]: Reached target Shutdown. Dec 21 12:22:07 kytos systemd[12136]: Starting Exit the Session... Dec 21 12:22:07 kytos systemd[12136]: Received SIGRTMIN+24 from PID 13678 (kill). Dec 21 12:22:07 kytos systemd[12137]: pam_unix(systemd-user:session): session closed for user cemsbr Dec 21 12:22:07 kytos systemd[1]: Stopped User Manager for UID 1000. Dec 21 12:22:07 kytos systemd[1]: Removed slice User Slice of cemsbr.
Dec 21 12:22:55 kytos pkexec[14007]: pam_unix(polkit-1:session): session opened for user root by (uid=1000) Dec 21 12:22:55 kytos pkexec[14007]: cemsbr: Executing command [USER=root] [TTY=unknown] [CWD=/home/cemsbr] [COMMAND=/usr/lib/xserver-xorg-video-intel/xf86-video-intel-backlight-helper intel_backlight] Dec 21 12:22:55 kytos systemd[13683]: Started D-Bus User Message Bus. Dec 21 12:23:00 kytos dbus-daemon[14017]: [session uid=1000 pid=14017] Activating via systemd: service name='org.a11y.Bus' unit='at-spi-dbus-bus.service' requested by ':1.2' (uid=1000 pid=14164 comm="/usr/bin/gnome-terminal --hide-menubar ") Dec 21 12:23:00 kytos systemd[13683]: Starting Accessibility services bus... Dec 21 12:23:00 kytos dbus-daemon[14017]: [session uid=1000 pid=14017] Successfully activated service 'org.a11y.Bus' Dec 21 12:23:00 kytos systemd[13683]: Started Accessibility services bus. Dec 21 12:23:00 kytos at-spi-bus-launcher[14168]: dbus-daemon[14173]: Activating service name='org.a11y.atspi.Registry' requested by ':1.0' (uid=1000 pid=14164 comm="/usr/bin/gnome-terminal --hide-menubar ") Dec 21 12:23:00 kytos dbus-daemon[14017]: [session uid=1000 pid=14017] Activating via systemd: service name='org.gnome.Terminal' unit='gnome-terminal-server.service' requested by ':1.4' (uid=1000 pid=14164 comm="/usr/bin/gnome-terminal --hide-menubar ") Dec 21 12:23:00 kytos systemd[13683]: Starting GNOME Terminal Server... Dec 21 12:23:00 kytos at-spi-bus-launcher[14168]: dbus-daemon[14173]: Successfully activated service 'org.a11y.atspi.Registry' Dec 21 12:23:00 kytos at-spi-bus-launcher[14168]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry Dec 21 12:23:00 kytos dbus-daemon[14017]: [session uid=1000 pid=14017] Successfully activated service 'org.gnome.Terminal' Dec 21 12:23:00 kytos systemd[13683]: Started GNOME Terminal Server.
Control group /: -.slice ├─user.slice │ └─user-1000.slice │ ├─user@1000.service │ │ └─init.scope │ │ ├─12136 /lib/systemd/systemd --user │ │ └─12137 (sd-pam) │ └─session-17.scope │ ├─12126 /bin/login -p -- │ ├─12147 -zsh │ ├─12537 /bin/sh /usr/bin/startx │ ├─12561 xinit /home/cemsbr/.xinitrc -- /etc/X11/xinit/xserverrc :0 vt1... │ ├─12562 /usr/lib/xorg/Xorg -nolisten tcp :0 vt1 -keeptty -auth /tmp/ser... │ ├─12566 /usr/lib/xserver-xorg-video-intel/xf86-video-intel-backlight-he... │ ├─12574 i3 │ ├─12584 /bin/sh -c i3bar --bar_id=bar-0 --socket="/run/user/1000/i3/ipc... │ ├─12585 i3bar --bar_id=bar-0 --socket=/run/user/1000/i3/ipc-socket.12574 │ ├─12586 /bin/sh -c i3status | i3status.pl │ ├─12587 i3status │ ├─12588 perl /home/cemsbr/bin/i3status.pl │ ├─12600 /bin/zsh │ ├─12726 xterm │ ├─12728 zsh │ └─12921 systemd-cgls ├─init.scope │ └─1 /sbin/init └─system.slice ├─irqbalance.service │ └─886 /usr/sbin/irqbalance --foreground ├─lvm2-lvmetad.service │ └─329 /sbin/lvmetad -f ├─systemd-timesyncd.service │ └─725 /lib/systemd/systemd-timesyncd ├─bumblebeed.service │ └─882 /usr/sbin/bumblebeed ├─dbus.service │ └─820 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfi... ├─dnscrypt-proxy.service │ └─12559 /usr/sbin/dnscrypt-proxy /etc/dnscrypt-proxy/dnscrypt-proxy.conf ├─system-getty.slice │ └─getty@tty2.service │ └─12130 /sbin/agetty -o -p -- \u --noclear tty2 linux ├─smartd.service │ └─885 /usr/sbin/smartd -n ├─systemd-logind.service │ └─888 /lib/systemd/systemd-logind ├─minissdpd.service │ └─981 /usr/sbin/minissdpd -i 0.0.0.0 ├─polkit.service │ └─938 /usr/lib/policykit-1/polkitd --no-debug ├─cron.service │ └─804 /usr/sbin/cron -f ├─systemd-udevd.service │ └─331 /lib/systemd/systemd-udevd ├─rsyslog.service │ └─883 /usr/sbin/rsyslogd -n └─systemd-journald.service └─315 /lib/systemd/systemd-journald