Sudden Reboots on Thinkpad T400

xelophon · 2014-06-30 16:30:27

Hi All,

Since some time I'm having trouble on my Lenovo T400 running Arch. The device is equipped with two video cards (intel and ati onboard, when docked an external ati video card is used)
Sometimes the device reboots randomly. This happens when the power is plugged in, as well as on battery.

A Memtest was showing no errors.
Temperature seems always to be ok. I could compute prime-numbers for two hours without any problems.

uname -a                           
Linux smeik 3.15.2-1-ARCH #1 SMP PREEMPT Fri Jun 27 07:41:19 CEST 2014 x86_64 GNU/Linux

I saved logfiles for some time now. First i still used syslog-ng later I discovered the wonderfull features of journalctl

... above this line NetworkManager is producing a damn lot of loglines and doing something funky....
May 23 21:08:34 smeik NetworkManager[444]: <warn> disconnected by the system bus.
May 23 21:08:34 smeik systemd[1]: Stopped Getty on tty1.
May 23 21:08:34 smeik systemd[1]: Stopped D-Bus System Message Bus.
May 23 21:08:34 smeik systemd[1]: freshclamd.service: main process exited, code=exited, status=2/INVALIDARGUMENT
May 23 21:08:34 smeik systemd[1]: Stopped clamav updater.
May 23 21:08:34 smeik systemd[1]: Unit freshclamd.service entered failed state.
May 23 21:08:34 smeik systemd[1]: Stopped OpenSSH Daemon.
May 23 21:08:34 smeik systemd[1]: Stopped Network Time Service.
May 23 21:08:34 smeik systemd[1]: Stopping Network.
May 23 21:08:34 smeik systemd[1]: Stopped target Network.
May 23 21:08:34 smeik systemd[1]: Stopping Network Manager...
May 23 21:08:34 smeik systemd[1]: Stopping system-getty.slice.
May 23 21:08:34 smeik NetworkManager[444]: <info> caught signal 15, shutting down normally.
May 23 21:08:34 smeik NetworkManager[444]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'removed') [20 10 36]
May 23 21:08:34 smeik NetworkManager[444]: <info> (eth0): cleaning up...
May 23 21:08:34 smeik NetworkManager[444]: <info> (eth0): taking down device.
May 23 21:08:34 smeik systemd[1]: Removed slice system-getty.slice.
May 23 21:08:34 smeik systemd[1]: Stopping Permit User Sessions...
May 23 21:08:34 smeik systemd[1]: Stopped Permit User Sessions.
May 23 21:08:34 smeik systemd[1]: Stopping Remote File Systems.
May 23 21:08:34 smeik systemd[1]: Stopped target Remote File Systems.
May 23 21:08:34 smeik systemd[1]: Stopped Modem Manager.
May 23 21:08:59 smeik systemd[1]: Starting Slices.
May 23 21:08:59 smeik systemd[1]: Reached target Slices.
May 23 21:08:59 smeik systemd[1]: Starting Remount Root and Kernel File Systems...
May 23 21:08:59 smeik systemd[1]: Mounted Huge Pages File System.
May 23 21:08:59 smeik systemd[1]: Mounted POSIX Message Queue File System.
May 23 21:08:59 smeik systemd[1]: Mounted Temporary Directory.
May 23 21:08:59 smeik systemd[1]: Mounted Debug File System.
May 23 21:08:59 smeik systemd[1]: Started Create list of required static device nodes for the current kernel.
May 23 21:08:59 smeik systemd[1]: Starting Create Static Device Nodes in /dev...
May 23 21:08:59 smeik systemd[1]: Started Apply Kernel Variables.
May 23 21:09:13 smeik kernel: [    0.000000] Initializing cgroup subsys cpuset
May 23 21:09:13 smeik kernel: [    0.000000] Initializing cgroup subsys cpu
May 23 21:09:13 smeik kernel: [    0.000000] Initializing cgroup subsys cpuacct
May 23 21:09:13 smeik kernel: [    0.000000] Linux version 3.14.4-1-ARCH (nobody@var-lib-archbuild-testing-x86_64-tobias) (gcc version 4.9.0 20140507 (prerelease) (GCC) ) #1 SMP PREEMPT Tue May 13 16:41:39 CEST 2014

Next time:

May 27 21:06:14 smeik dhclient: bound to 192.168.1.122 -- renewal in 1336 seconds.
May 27 21:06:14 smeik NetworkManager[459]: <info> (wlan0): DHCPv4 state changed renew -> renew
May 27 21:06:14 smeik NetworkManager[459]: <info>   address 192.168.1.122
May 27 21:06:14 smeik NetworkManager[459]: <info>   prefix 24 (255.255.255.0)
May 27 21:06:14 smeik NetworkManager[459]: <info>   gateway 192.168.1.1
May 27 21:06:14 smeik NetworkManager[459]: <info>   nameserver '192.168.1.1'
May 27 21:06:14 smeik NetworkManager[459]: <info>   domain name 'demuth.mobi'
May 27 21:06:14 smeik dbus[495]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
May 27 21:06:14 smeik dbus[495]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 27 21:06:14 smeik systemd[1]: Cannot add dependency job for unit acpid.socket, ignoring: Unit acpid.socket failed to load: No such file or directory.
May 27 21:06:14 smeik systemd[1]: Starting Network Manager Script Dispatcher Service...
May 27 21:06:14 smeik systemd[1]: Started Network Manager Script Dispatcher Service.
May 27 21:12:22 smeik systemd[1]: Starting Slices.
May 27 21:12:22 smeik systemd[1]: Reached target Slices.
May 27 21:12:22 smeik systemd[1]: Starting Remount Root and Kernel File Systems...
May 27 21:12:22 smeik systemd[1]: Started Create list of required static device nodes for the current kernel.
May 27 21:12:22 smeik systemd[1]: Starting Create Static Device Nodes in /dev...
May 27 21:12:22 smeik systemd[1]: Started Apply Kernel Variables.
May 27 21:12:22 smeik systemd[1]: Starting CLI Netfilter Manager...
May 27 21:12:22 smeik systemd[1]: Mounted POSIX Message Queue File System.
May 27 21:12:22 smeik systemd[1]: Started udev Coldplug all Devices.
May 27 21:12:22 smeik systemd[1]: Mounted Debug File System.
May 27 21:12:39 smeik kernel: [    0.000000] Initializing cgroup subsys cpuset
May 27 21:12:39 smeik kernel: [    0.000000] Initializing cgroup subsys cpu
May 27 21:12:39 smeik kernel: [    0.000000] Initializing cgroup subsys cpuacct

and again:

un  5 13:40:13 smeik mtp-probe: checking bus 2, device 19: "/sys/devices/pci0000:00/0000:00:1a.7/usb2/2-5/2-5.4/2-5.4.4"
Jun  5 13:40:13 smeik mtp-probe: bus: 2, device: 19 was not an MTP device
Jun  5 13:40:15 smeik dbus[463]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service'
Jun  5 13:40:15 smeik dbus[463]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service failed to load: No such file or directory.
Jun  5 13:40:17 smeik dbus[463]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service'
Jun  5 13:40:17 smeik dbus[463]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service failed to load: No such file or directory.
Jun  5 13:40:31 smeik kernel: [17427.265240] usb 2-5.4.4: reset full-speed USB device number 19 using ehci-pci
Jun  5 13:51:12 smeik systemd[1]: Starting Slices.
Jun  5 13:51:12 smeik systemd[1]: Reached target Slices.
Jun  5 13:51:12 smeik systemd[1]: Starting Remount Root and Kernel File Systems...
Jun  5 13:51:12 smeik systemd[1]: Started Create list of required static device nodes for the current kernel.
Jun  5 13:51:12 smeik systemd[1]: Starting Create Static Device Nodes in /dev...
Jun  5 13:51:12 smeik systemd[1]: Started udev Coldplug all Devices.
Jun  5 13:51:12 smeik systemd[1]: Started Apply Kernel Variables.
Jun  5 13:51:12 smeik systemd[1]: Starting CLI Netfilter Manager...
Jun  5 13:51:12 smeik systemd[1]: Mounted Debug File System.
Jun  5 13:51:12 smeik systemd[1]: Mounted Huge Pages File System.
Jun  5 13:51:32 smeik kernel: [    0.000000] Initializing cgroup subsys cpuset
Jun  5 13:51:32 smeik kernel: [    0.000000] Initializing cgroup subsys cpu
Jun  5 13:51:32 smeik kernel: [    0.000000] Initializing cgroup subsys cpuacct
Jun  5 13:51:32 smeik kernel: [    0.000000] Linux version 3.14.5-1-ARCH (nobody@var-lib-archbuild-testing-x86_64-tobias) (gcc version 4.9.0 20140521 (prerelease) (GCC) ) #1 SMP PREEMPT Sun Jun 1 07:36:23 CEST 2014
Jun  5 13:51:32 smeik kernel: [    0.000000] Command line: BOOT_IMAGE=../vmlinuz-linux root=/dev/sda1 resume=/dev/sda2 rw initrd=../initramfs-linux.img
Jun  5 13:51:32 smeik kernel: [    0.000000] Disabled fast string operations
Jun  5 13:51:32 smeik kernel: [    0.000000] e820: BIOS-provided physical RAM map:

Jun 10 21:37:36 smeik dbus[483]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Jun 10 21:37:36 smeik dbus[483]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jun 10 21:37:36 smeik systemd[1]: Cannot add dependency job for unit acpid.socket, ignoring: Unit acpid.socket failed to load: No such file or directory.
Jun 10 21:37:36 smeik systemd[1]: Starting Network Manager Script Dispatcher Service...
Jun 10 21:37:36 smeik systemd[1]: Started Network Manager Script Dispatcher Service.
Jun 10 21:41:43 smeik systemd[1]: Starting Slices.
Jun 10 21:41:43 smeik systemd[1]: Reached target Slices.
Jun 10 21:41:43 smeik systemd[1]: Starting Remount Root and Kernel File Systems...
Jun 10 21:41:43 smeik systemd[1]: Mounted Huge Pages File System.
Jun 10 21:41:43 smeik systemd[1]: Mounted POSIX Message Queue File System.
Jun 10 21:41:43 smeik systemd[1]: Mounted Temporary Directory.
Jun 10 21:41:43 smeik systemd[1]: Mounted Debug File System.
Jun 10 21:41:43 smeik systemd[1]: Started udev Coldplug all Devices.
Jun 10 21:41:43 smeik systemd[1]: Started Create list of required static device nodes for the current kernel.
Jun 10 21:41:43 smeik systemd[1]: Starting Create Static Device Nodes in /dev...
Jun 10 21:41:59 smeik kernel: [    0.000000] Initializing cgroup subsys cpuset
Jun 10 21:41:59 smeik kernel: [    0.000000] Initializing cgroup subsys cpu
Jun 10 21:41:59 smeik kernel: [    0.000000] Initializing cgroup subsys cpuacct
Jun 10 21:41:59 smeik kernel: [    0.000000] Linux version 3.14.5-1-ARCH (nobody@var-lib-archbuild-testing-x86_64-tobias) (gcc version 4.9.0 20140521 (prerelease) (GCC) ) #1 SMP PREEMPT Sun Jun 1 07:36:23 CEST 2014
Jun 10 21:41:59 smeik kernel: [    0.000000] Command line: BOOT_IMAGE=../vmlinuz-linux root=/dev/sda1 resume=/dev/sda2 rw initrd=../initramfs-linux.img
Jun 10 21:41:59 smeik kernel: [    0.000000] Disabled fast string operations
Jun 10 21:41:59 smeik kernel: [    0.000000] e820: BIOS-provided physical RAM map:

The NetworkManagerDispatcher seemed to be always one of the last things which is logging things...The next time it wasn't

Jun 18 13:58:35 smeik dbus[578]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service'
Jun 18 13:58:35 smeik dbus[578]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service failed to load: No such file or directory.
Jun 18 13:58:36 smeik kernel: [ 1097.518136] thinkpad_acpi: EC reports that Thermal Table has changed
Jun 18 13:58:44 smeik dbus[578]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service'
Jun 18 13:58:44 smeik systemd[1]: Cannot add dependency job for unit acpid.socket, ignoring: Unit acpid.socket failed to load: No such file or directory.
Jun 18 13:58:44 smeik systemd[1]: Starting RealtimeKit Scheduling Policy Service...
Jun 18 13:58:44 smeik dbus[578]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Jun 18 13:58:44 smeik systemd[1]: Started RealtimeKit Scheduling Policy Service.
Jun 18 11:58:44 smeik rtkit-daemon[21473]: Successfully made thread 21467 of process 21467 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Jun 18 13:58:44 smeik bluetoothd[564]: Endpoint registered: sender=:1.30 path=/MediaEndpoint/A2DPSource
Jun 18 13:58:44 smeik bluetoothd[564]: Endpoint registered: sender=:1.30 path=/MediaEndpoint/A2DPSink
Jun 18 11:58:44 smeik rtkit-daemon[21473]: Successfully made thread 21484 of process 21484 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Jun 18 11:58:44 smeik rtkit-daemon[21473]: Successfully made thread 21487 of process 21487 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Jun 18 14:02:05 smeik kernel: [ 1306.057184] fuse init (API version 7.22)
Jun 18 14:02:05 smeik systemd[1]: Mounting FUSE Control File System...
Jun 18 14:02:05 smeik systemd[1]: Mounted FUSE Control File System.
Jun 18 14:06:15 smeik systemd[1]: Starting Slices.
Jun 18 14:06:15 smeik systemd[1]: Reached target Slices.
Jun 18 14:06:15 smeik systemd[1]: Starting Remount Root and Kernel File Systems...
Jun 18 14:06:15 smeik systemd[1]: Mounted Huge Pages File System.
Jun 18 14:06:15 smeik systemd[1]: Mounted POSIX Message Queue File System.
Jun 18 14:06:15 smeik systemd[1]: Mounted Temporary Directory.
Jun 18 14:06:15 smeik systemd[1]: Mounted Debug File System.
Jun 18 14:06:15 smeik systemd[1]: Started Apply Kernel Variables.
Jun 18 14:06:15 smeik systemd[1]: Starting CLI Netfilter Manager...
Jun 18 14:06:15 smeik systemd[1]: Started udev Coldplug all Devices.
Jun 18 14:06:29 smeik kernel: [    0.000000] Initializing cgroup subsys cpuset
Jun 18 14:06:29 smeik kernel: [    0.000000] Initializing cgroup subsys cpu
Jun 18 14:06:29 smeik kernel: [    0.000000] Initializing cgroup subsys cpuacct
Jun 18 14:06:29 smeik kernel: [    0.000000] Linux version 3.14.6-1-ARCH (nobody@var-lib-archbuild-testing-x86_64-tobias) (gcc version 4.9.0 20140521 (prerelease) (GCC) ) #1 SMP PREEMPT Sun Jun 8 10:08:38 CEST 2014
Jun 18 14:06:29 smeik kernel: [    0.000000] Command line: BOOT_IMAGE=../vmlinuz-linux root=/dev/sda1 resume=/dev/sda2 rw initrd=../initramfs-linux.img
Jun 18 14:06:29 smeik kernel: [    0.000000] Disabled fast string operations
Jun 18 14:06:29 smeik kernel: [    0.000000] e820: BIOS-provided physical RAM map:

Now we are talking journalctl:

un 23 21:15:31 smeik dbus[502]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Jun 23 21:15:31 smeik dbus[502]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jun 23 21:15:31 smeik systemd[1]: Cannot add dependency job for unit acpid.socket, ignoring: Unit acpid.socket failed to load: No such file or directory.
Jun 23 21:15:31 smeik systemd[1]: Starting Network Manager Script Dispatcher Service...
-- Subject: Unit NetworkManager-dispatcher.service has begun with start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Unit NetworkManager-dispatcher.service has begun starting up.
Jun 23 21:15:31 smeik systemd[1]: Started Network Manager Script Dispatcher Service.
-- Subject: Unit NetworkManager-dispatcher.service has finished start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Unit NetworkManager-dispatcher.service has finished starting up.
-- 
-- The start-up result is done.
-- Reboot --
Jun 23 21:30:00 smeik systemd-journal[128]: Runtime journal is using 8.0M (max allowed 394.1M, trying to leave 591.2M free of 3.8G available → current limit 394.1M).
Jun 23 21:30:00 smeik systemd-journal[128]: Runtime journal is using 8.0M (max allowed 394.1M, trying to leave 591.2M free of 3.8G available → current limit 394.1M).
Jun 23 21:30:00 smeik kernel: Initializing cgroup subsys cpuset
Jun 23 21:30:00 smeik kernel: Initializing cgroup subsys cpu
Jun 23 21:30:00 smeik kernel: Initializing cgroup subsys cpuacct
Jun 23 21:30:00 smeik kernel: Linux version 3.14.6-1-ARCH (nobody@var-lib-archbuild-testing-x86_64-tobias) (gcc version 4.9.0 20140521 (prerelease) (GCC) ) #1 SMP PREEMPT Sun Jun 8 10:08:38 CEST 2014
Jun 23 21:30:00 smeik kernel: Command line: BOOT_IMAGE=../vmlinuz-linux root=/dev/sda1 resume=/dev/sda2 rw initrd=../initramfs-linux.img
Jun 23 21:30:00 smeik kernel: Disabled fast string operations
Jun 23 21:30:00 smeik kernel: e820: BIOS-provided physical RAM map:

Now I was pissed and reinstalled from scratch. On a new HD. Unfortunately:

Jun 30 17:45:17 smeik dbus[225]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Jun 30 17:45:17 smeik systemd[1]: Starting Network Manager Script Dispatcher Service...
-- Subject: Unit NetworkManager-dispatcher.service has begun with start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Unit NetworkManager-dispatcher.service has begun starting up.
Jun 30 17:45:17 smeik dbus[225]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jun 30 17:45:17 smeik systemd[1]: Started Network Manager Script Dispatcher Service.
-- Subject: Unit NetworkManager-dispatcher.service has finished start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Unit NetworkManager-dispatcher.service has finished starting up.
-- 
-- The start-up result is done.
-- Reboot --
Jun 30 17:47:22 smeik systemd-journal[131]: Runtime journal is using 8.0M (max allowed 394.1M, trying to leave 591.2M free of 3.8G available → current limit 394.1M).
Jun 30 17:47:22 smeik systemd-journal[131]: Runtime journal is using 8.0M (max allowed 394.1M, trying to leave 591.2M free of 3.8G available → current limit 394.1M).
Jun 30 17:47:22 smeik kernel: Initializing cgroup subsys cpuset
Jun 30 17:47:22 smeik kernel: Initializing cgroup subsys cpu
Jun 30 17:47:22 smeik kernel: Initializing cgroup subsys cpuacct
Jun 30 17:47:22 smeik kernel: Linux version 3.15.2-1-ARCH (nobody@var-lib-archbuild-testing-x86_64-tobias) (gcc version 4.9.0 20140604 (prerelease) (GCC) ) #1 SMP PREEMPT Fri Jun 27 07:41:19 CEST 2014
Jun 30 17:47:22 smeik kernel: Command line: BOOT_IMAGE=../vmlinuz-linux root=UUID=6aff7404-2973-4b7b-b9dc-650e93a584b9 rw initrd=../initramfs-linux.img
Jun 30 17:47:22 smeik kernel: Disabled fast string operations
Jun 30 17:47:22 smeik kernel: e820: BIOS-provided physical RAM map:

Any Ideas how to stop the reboots?

Best Regards and thanks for your help.

Arch Linux

#1 2014-06-30 16:30:27

Sudden Reboots on Thinkpad T400

Board footer