Bug#696554: System freezes and lockups
----- Original Message -----
> From: Steven Chamberlain <steven@pyro.eu.org>
> To: Mar Mel <marmel6942@yahoo.com>; 696554@bugs.debian.org
> Cc: Sven Joachim <svenjoac@gmx.de>
> Sent: Thursday, December 27, 2012 3:27 PM
> Subject: Re: Bug#696554: System freezes and lockups
>
>Thanks for the logs.
>
> Do you have a dual monitor setup?
No I only have a single monitor.
> If so, set glxgears running on one of
> them where you'll be able to see it. Then please try this from a
> terminal window on the other monitor:
I've pinned a glxgears window for an hour while playing video at the same time
without triggering the freeze. As I've said, it seems comletely independent of
the load on the GPU.
> $ mplayer -nosound -vo xv -dr -fs somefile.avi
>
> (Any video file should be okay as long as it's at least 10 seconds long.)
I can sometimes play HD (.mkv, .mts) video for hours without fail. The complete
system freeze takes up to a full day's useage to happen, or it can happen within
minutes of booting.
> This is just to see if it can trigger the freezes on demand.
Last night I thought I'd found a trigger by syncing package database in Synaptic.
I had two consecutive full freeze/lockups while the progress bar was displayed.
Unfortunately, I cannot reproduce it reliably. Must have been coincidental.
Twice now, I've had a hard lockup while _only_ running Synaptic. Last night was
one of the quickest @2410 seconds (see below) uptime. I waited for a while to see
if it would thaw by itself, but it was locked up hard.
1) I was unable to switch virtual consoles
2) Could not toggle NumLock/CapsLock keys
3) Mouse pointer still moved (as always)
4) Required magic sysrq to reboot
5) There was no suspend/resume cycle before the lockup, so at least I've ruled
that out as a culprit.
Dec 27 01:38:14 humonculux kernel: [ 9.370761] e1000e: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx
Dec 27 01:38:14 humonculux kernel: [ 9.371048] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Dec 27 01:38:14 humonculux dhclient: Internet Systems Consortium DHCP Client 4.2.2
Dec 27 01:38:14 humonculux dhclient: Copyright 2004-2011 Internet Systems Consortium.
Dec 27 01:38:14 humonculux dhclient: All rights reserved.
Dec 27 01:38:14 humonculux dhclient: For info, please visit https://www.isc.org/software/dhcp/
Dec 27 01:38:14 humonculux dhclient:
Dec 27 01:38:14 humonculux NetworkManager[3154]: <info> (eth0): DHCPv4 state changed nbi -> preinit
Dec 27 01:38:14 humonculux dhclient: Listening on LPF/eth0/38:60:77:5a:01:9b
Dec 27 01:38:14 humonculux dhclient: Sending on LPF/eth0/38:60:77:5a:01:9b
Dec 27 01:38:14 humonculux dhclient: Sending on Socket/fallback
Dec 27 01:38:14 humonculux dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67
Dec 27 01:38:14 humonculux dhclient: DHCPACK from 192.168.0.1
Dec 27 01:38:14 humonculux NetworkManager[3154]: <info> (eth0): DHCPv4 state changed preinit -> reboot
Dec 27 01:38:14 humonculux dhclient: bound to 192.168.0.100 -- renewal in 2147483648 seconds.
Dec 27 01:38:14 humonculux NetworkManager[3154]: <info> address 192.168.0.100
Dec 27 01:38:14 humonculux NetworkManager[3154]: <info> prefix 24 (255.255.255.0)
Dec 27 01:38:14 humonculux NetworkManager[3154]: <info> gateway 192.168.0.1
Dec 27 01:38:14 humonculux NetworkManager[3154]: <info> nameserver '192.168.0.1'
Dec 27 01:38:14 humonculux NetworkManager[3154]: <info> Activation (eth0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Dec 27 01:38:14 humonculux NetworkManager[3154]: <info> Activation (eth0) Stage 5 of 5 (IPv4 Commit) started...
Dec 27 01:38:14 humonculux avahi-daemon[3085]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.100.
Dec 27 01:38:14 humonculux avahi-daemon[3085]: New relevant interface eth0.IPv4 for mDNS.
Dec 27 01:38:14 humonculux avahi-daemon[3085]: Registering new address record for 192.168.0.100 on eth0.IPv4.
Dec 27 01:38:15 humonculux avahi-daemon[3085]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::3a60:77ff:fe5a:19b.
Dec 27 01:38:15 humonculux avahi-daemon[3085]: New relevant interface eth0.IPv6 for mDNS.
Dec 27 01:38:15 humonculux avahi-daemon[3085]: Registering new address record for fe80::3a60:77ff:fe5a:19b on eth0.*.
Dec 27 01:38:15 humonculux NetworkManager[3154]: <info> (eth0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Dec 27 01:38:15 humonculux NetworkManager[3154]: <info> Policy set 'MetroCast Cable' (eth0) as default for IPv4 routing and DNS.
Dec 27 01:38:15 humonculux NetworkManager[3154]: <info> Activation (eth0) successful, device activated.
Dec 27 01:38:15 humonculux NetworkManager[3154]: <info> Activation (eth0) Stage 5 of 5 (IPv4 Commit) complete.
Dec 27 01:38:15 humonculux dbus[2893]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Dec 27 01:38:15 humonculux dbus[2893]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Dec 27 01:38:16 humonculux acpid: client connected from 2558[0:0]
Dec 27 01:38:16 humonculux acpid: 1 client rule loaded
Dec 27 01:38:17 humonculux gdm3][3796]: Error attempting to parse .ecryptfsrc file; rc = [-13]
Dec 27 01:38:17 humonculux gdm3][3850]: pam_ecryptfs: Passphrase file wrapped
Dec 27 01:38:17 humonculux gdm-simple-slave[3288]: WARNING: Child process 3573 was already dead.
Dec 27 01:38:18 humonculux rtkit-daemon[3770]: Successfully made thread 3959 of process 3959 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Dec 27 01:38:18 humonculux rtkit-daemon[3770]: Supervising 4 threads of 2 processes of 2 users.
Dec 27 01:38:18 humonculux rtkit-daemon[3770]: Successfully made thread 3960 of process 3959 (/usr/bin/pulseaudio) owned by '1000' RT at priority 5.
Dec 27 01:38:18 humonculux rtkit-daemon[3770]: Supervising 5 threads of 2 processes of 2 users.
Dec 27 01:38:18 humonculux dbus[2893]: [system] Activating service name='org.freedesktop.UDisks' (using servicehelper)
Dec 27 01:38:18 humonculux dbus[2893]: [system] Successfully activated service 'org.freedesktop.UDisks'
Dec 27 01:38:18 humonculux dbus[2893]: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Dec 27 01:38:18 humonculux dbus[2893]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Dec 27 01:38:18 humonculux rtkit-daemon[3770]: Successfully made thread 4007 of process 4007 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Dec 27 01:38:18 humonculux rtkit-daemon[3770]: Supervising 6 threads of 3 processes of 2 users.
Dec 27 01:38:18 humonculux pulseaudio[4007]: [pulseaudio] pid.c: Daemon already running.
Dec 27 01:38:19 humonculux goa[4039]: goa-daemon version 3.4.2 starting [main.c:112, main()]
Dec 27 01:38:24 humonculux kernel: [ 19.396440] eth0: no IPv6 routers present
Dec 27 01:38:30 humonculux sudo: pam_ecryptfs: pam_sm_authenticate: /home/marc is already mounted
Dec 27 01:38:30 humonculux kernel: [ 24.741069] fuse init (API version 7.17)
Dec 27 01:38:30 humonculux kernel: [ 24.941650] device-mapper: uevent: version 1.0.3
Dec 27 01:38:30 humonculux kernel: [ 24.941681] device-mapper: ioctl: 4.22.0-ioctl (2011-10-19) initialised: dm-devel@redhat.com
Dec 27 01:38:31 humonculux ntfs-3g[4153]: Version 2012.1.15AR.5 external FUSE 29
Dec 27 01:38:31 humonculux ntfs-3g[4153]: Mounted /dev/mapper/truecrypt1 (Read-Write, label "", NTFS 3.1)
Dec 27 01:38:31 humonculux ntfs-3g[4153]: Cmdline options: rw,uid=1000,gid=1000,umask=077
Dec 27 01:38:31 humonculux ntfs-3g[4153]: Mount options: rw,allow_other,nonempty,relatime,default_permissions,fsname=/dev/mapper/truecrypt1,blkdev,blksize=4096
Dec 27 01:38:31 humonculux ntfs-3g[4153]: Global ownership and permissions enforced, configuration type 7
Dec 27 01:38:37 humonculux rpc.mountd[2909]: Caught signal 15, un-registering and exiting.
Dec 27 01:38:37 humonculux kernel: [ 32.450839] nfsd: last server has exited, flushing export cache
Dec 27 01:38:38 humonculux kernel: [ 33.459521] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
Dec 27 01:38:38 humonculux kernel: [ 33.459769] NFSD: starting 90-second grace period
Dec 27 01:38:38 humonculux rpc.mountd[4210]: Version 1.2.6 starting
Dec 27 02:14:31 humonculux dbus[2893]: [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Dec 27 02:14:31 humonculux dbus[2893]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Dec 27 02:14:36 humonculux kernel: [ 2184.032107] [drm] nouveau 0000:01:00.0: PFIFO_DMA_PUSHER - Ch 2 Get 0x002002bdf4 Put 0x002002cf9c IbGet 0x000004e8 IbPut 0x000004e9 State 0x40000004 (err: INVALID_MTHD) Push 0x00400040
Dec 27 02:14:36 humonculux kernel: [ 2184.047008] [drm] nouveau 0000:01:00.0: PFIFO_CACHE_ERROR - Ch 2/0 Mthd 0x0000 Data 0x000000e1
Dec 27 02:16:01 humonculux sudo: pam_ecryptfs: pam_sm_authenticate: /home/marc is already mounted
Dec 27 02:16:54 humonculux sudo: pam_ecryptfs: pam_sm_authenticate: /home/marc is already mounted
Dec 27 02:17:01 humonculux /USR/SBIN/CRON[4660]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Dec 27 02:18:23 humonculux kernel: [ 2410.330333] [drm] nouveau 0000:01:00.0: PFIFO_DMA_PUSHER - Ch 2 Get 0x0020021e80 Put 0x0020024de4 IbGet 0x000002ea IbPut 0x000002eb State 0x80000054 (err: INVALID_CMD) Push 0x00706031
Dec 27 02:18:23 humonculux kernel: [ 2410.339898] [drm] nouveau 0000:01:00.0: PFIFO_DMA_PUSHER - Ch 2 Get 0x0020024de4 Put 0x0020024de4 IbGet 0x000002ea IbPut 0x000002ed State 0x80000000 (err: INVALID_CMD) Push 0x00400040
Dec 27 02:21:00 humonculux kernel: [ 2567.071343] SysRq : Keyboard mode set to system default
Dec 27 02:21:04 humonculux kernel: [ 2570.954719] SysRq : This sysrq operation is disabled.
Dec 27 02:21:08 humonculux kernel: [ 2574.295848] SysRq : This sysrq operation is disabled.
Dec 27 02:21:11 humonculux kernel: [ 2577.605064] SysRq : Emergency Sync
Dec 27 02:21:11 humonculux kernel: [ 2577.605576] Emergency Sync complete
Dec 27 02:21:14 humonculux kernel: [ 2580.994021] SysRq : Emergency Remount R/O
Reply to: