14.10 Freezes During Shutdown

[actionmystique]

Hello guys,

I've just upgraded to 14.10 almost flawlessly. Everything seems to work fine, execpt when I try to shutdown or restart the system which then becomes completely blocked:
- no more mouse
- no more access to keyboard
- the CPU spikes in the background (I can tell from the noise made by the fan)

BTW, I never had that issue with 14.04.

Any insight?

[actionmystique]

Either I'm the only one with this bug, which would indicate that it is linked with my hardware (MSI GE6O), or very few people have upgraded to 14.10.

I have given up the possibility to report this bug on launchpad, due to the extreme complexity of reporting Ubuntu kernel bugs.

Below are the contents of /var/log/syslog which are related to the exact moment of the shutdown and the beginning of the reboot process:

Shutdown
Oct 28 14:30:06 MSI-GE60-Ubuntu-14 polkitd[1273]: Unregistered Authentication Agent for unix-session:c2 (system bus name :1.72, object path /org/gnome/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8) (disconnected from bus)
Oct 28 14:30:06 MSI-GE60-Ubuntu-14 dbus-daemon[1198]: [system] Activating service name='org.freedesktop.systemd1' (using servicehelper)
Oct 28 14:30:06 MSI-GE60-Ubuntu-14 dbus-daemon[1198]: [system] Successfully activated service 'org.freedesktop.systemd1'
Oct 28 14:30:06 MSI-GE60-Ubuntu-14 kernel: [ 686.420383] audit: type=1400 audit(1414503006.642:110): apparmor="ALLOWED" operation="connect" profile="/usr/sbin/nmbd" pid=2333 comm="nmbd" family="unix" sock_type="stream" protocol=0 requested_mask="send receive connect" denied_mask="send connect" addr=none peer_addr="@2F746D702F65736574732E736F636B00000000 00000000000000000000000000000000000000000000000000 00000000000000000000000000000000000000000000000000 00000000000000000000000000000000000000000000000000 00000000000000000000000000" peer="unconfined"
Oct 28 14:30:06 MSI-GE60-Ubuntu-14 rsyslogd: [origin software="rsyslogd" swVersion="7.4.4" x-pid="1208" x-info="http://www.rsyslog.com"] exiting on signal 15.

Reboot
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 rsyslogd: [origin software="rsyslogd" swVersion="7.4.4" x-pid="1204" x-info="http://www.rsyslog.com"] start
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 rsyslogd: rsyslogd's groupid changed to 104
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 rsyslogd: rsyslogd's userid changed to 101
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.000000] Initializing cgroup subsys cpuset
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.000000] Initializing cgroup subsys cpu
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.000000] Initializing cgroup subsys cpuacct
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.000000] Linux version 3.16.0-23-generic (buildd@phianna) (gcc version 4.9.1 (Ubuntu 4.9.1-16ubuntu6) ) #31-Ubuntu SMP Tue Oct 21 17:56:17 UTC 2014 (Ubuntu 3.16.0-23.31-generic 3.16.4)
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-3.16.0-23-generic.efi.signed root=UUID=0e92ddf7-be63-4b1f-85b1-6d3843f1098d ro quiet splash vt.handoff=7

If someone knows how to detect something unusual here, I'd be very glad to hear about it.

[actionmystique]

I have also noticed a few recurring errors during bootup which may have an impact on shutdown (?):

Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-3.16.0-23-generic.efi.signed root=UUID=0e92ddf7-be63-4b1f-85b1-6d3843f1098d ro quiet splash vt.handoff=7
...
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.189919] ACPI Error: No handler for Region [EC__] (ffff88043f039c18) [EmbeddedControl] (20140424/evregion-163)
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.189921] ACPI Error: Region EmbeddedControl (ID=3) has no handler (20140424/exfldio-299)
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.189923] ACPI Error: Method parse/execution failed [\_SB_.PCI0.LPCB.EC__._REG] (Node ffff88043f03c2f8), AE_NOT_EXIST (20140424/psparse-536)
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.190581] ACPI: Executed 2 blocks of module-level executable AML code
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.199927] [Firmware Bug]: ACPI: BIOS _OSI(Linux) query ignored
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.203997] ACPI: Dynamic OEM Table Load:
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.204004] ACPI: SSDT 0xFFFF8804390E0C00 0003D3 (v01 PmRef Cpu0Cst 00003001 INTL 20051117)
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.208112] ACPI: Dynamic OEM Table Load:
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.208117] ACPI: SSDT 0xFFFF880438CA0000 0005AA (v01 PmRef ApIst 00003000 INTL 20051117)
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.211998] ACPI: Dynamic OEM Table Load:
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.212002] ACPI: SSDT 0xFFFF880438C98200 000119 (v01 PmRef ApCst 00003000 INTL 20051117)
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.230434] ACPI: Interpreter enabled
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.230442] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20140424/hwxface-580)
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.230446] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20140424/hwxface-580)
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.230461] ACPI: (supports S0 S3 S4 S5)
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.230462] ACPI: Using IOAPIC for interrupt routing
Oct 28 14:37:10 MSI-GE60-Ubuntu-14 kernel: [ 0.230488] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug

[actionmystique]

The last kernel update 3.16.0-24 has NOT solved this issue.

[RobertTom]

I had the same problem after updating to 14.10 on my MSI GE40.
But installing the proprietary drivers for nvidia fixed it for me.

[actionmystique]

Thanks for the tip.
However, the Nvidia driver available on the official repositories is so old...

[crcarson]

Been seeing intermittant system freezes on my GeForce GTX 660M/PCIe/SSE2 system but cannot attribute it to any action I was doing on the system at the time of the freeze. Looking at the last syslog activity and searching these bug report hit this bug because of the org.freedesktop.systemd1 references. These are the last two entries in syslog before the reboot entries.

Nov 2 05:49:22 cliffps dbus[877]: [system] Activating service name='org.freedesktop.systemd1' (using servicehelper)
Nov 2 05:49:22 cliffps dbus[877]: [system] Successfully activated service 'org.freedesktop.systemd1'

Running the lastest Nvidia drivers version 331.89 from Nivida-331-updates.

[actionmystique]

You're suggesting that systemd might be responsible instead, since you're running Nvidia driver rather than Nouveau.
To be honest, I find its presence surprising because I thought it was still in active development and not ready yet for official deployment.

[actionmystique]

A trace has recently shown up during a failed shutdown; the following is the OCR translation obtained from a picture (available as attachment), meaning some decoding errors may be still there, especially in the "Modules linked in" section that I have not checked:

wait-for-state stop/waiting
* Stopping rsync daemon rsync
* speech-dispatcher disabled; edit /etc/default/speech-dispatcher
* Stopping uuidd generator uuidd
* Asking all remaining processes to terminate...
* Killing all remaining processes...

ModemManager(1044]: <warn> Could not acquire the 'org.freedesktop.ModemManageris service name
modemManager(1044]: <info> ModemManager is shut down
Deactivating swap...
[ 3944.666185] BUG: unable to handle kernel NULL pointer dereference at (null)
[ 3944.666206] IP: [< (null)>] (null)
[ 3944.666219] PGD 0
[ 3944.666225] Oops: 0010 [#1] SMP

[ 3944.666234] Modules linked in: ctr ccm rfComm bnep nls_iso8859....1 snd_hda_codec_realtek snd-hda-codec-geheric snd-hda-codec-hdmi IP6t-REJECT xt_hl iP6t_rt nf_conntrack_ipv6 nf_defrag_ipv6 ipt_REJECT arc4 xt_t_OG xt_limit xt_tcpudp x
We nf-conntracK-IPv4 nf_defrag_ipv4 xt_conntrack intel_rapi rt18723ae ip6table_filter ip6_tables rt18723_common x86_0(g_temp_thermal rti_pci intei_powerclamp uvcvideo nf_conntrack_netbios_ns rt lwif i coretemp nf_conntrack_broadcast
nf_nat_ftp mace snd_hda_intel kvm_intel snd_hda_controller nf_nat videobuf2_vmalloc snd_hda_codec Kvm snd_hwdep nf_conntrack_ftp videobuf2_memops videobuf2_core nf_conntrack snd_pcm v412_common 1915 videodev iptable_filter media
es snd_seq_midi snd_seq_midi_event cfg80211 snd_rawmidi crctlOdif_pcIml snd_seq x_tables crc32_pclmul btusb ghash_clmuli_intel ttm aesni_intel snd_seq_device drm_kms_helper bluetooth aes_x86_64 snd_timer irw drm snd gf128mui rtsx
lue_helper soundcore memstick 6lowpan_lphc ablk_helper jdydev i2c_algd_bit cryptd mei_me IPc_ich mei shpchp serio_raw parport_pc ppdev msi_wmi sparse_keymap mxm_wmi 1p wmi soc_button_array parport mac hid video hid_generic usbhid b_storage rtsx_pci_sdmac ahci psmouse alx libahci rtsx_pci mdio
[ 3944.666555] CPU: 5 PID: 1584 Comm: Xorg Tainted: S I 3.16.0-24-generic #32-Ubuntu
[ 3944.666574] Hardware name: Micro-Star International Co., Ltd. GE 30 6.0-24 BIOS El6GCIMS.511 08/27/2013
[ 3944.666596] task! ffff880437739e90 ti: ffff880427824000 task.ti: ffff880427824000
[ 3944.666612] RIP: 0010:[<0000000000000000>] [< (null)>] (null)
1 3944.666628] RSP: 0018:ffff880427827b60 EFLAGS: 00010246
( 3944.666640] RAX: ffffffffc0acd040 RBX: ffff880438c47800 RCX: 00000000e00000:11)
( 3944.666655] RDX: ffffffffc0abfaa7 RSI: 0000000000000000 RDI: ffff880438c47800
[ 3944.666670] RBP: ffff880427827b80 R08: 0000000Offffffff R09: ffffffffc0abf9a8
[ 3944.666686] R10: ffffffffc0a2cb58 R11: ffff880438c47a00 R12: 0000000000000001
[ 3944.666701] R13: 0000000000000000 R14: ffff880435282d30 R15: ffff8804360d3088
[ 3944.666716] FS: 00007f4c73ad99c0(0000) GSOfff88044fb40000(0000) knIGS:0000000000000000
[ 3944.666733] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 394406667451 CR2: 0000000000000000 CR3: 0000000002c13000 CR4: 00000000001407e0
[ 3944.6667601 Stack:
[ 3944.666765] ffffffff209e7065 ffff880435282d20 ffff880435282d48 0000000000000000
[ 3944.666783] ffff880427827bd0 ffffffffc09ed98a ffff880427827bc0 ffffffffc0abf9a8
[ 3944.666802] 0000000000000000 0000000000000000 ffff880438c47800 ffffffffc0abf810
[ 3944.666820] Call Trace:
[ 3944.666838] kffffffffc09ef065>] ? nouveau_object_dec+0x125/0x2a0 [nouveau]
[ 3944.666858] [afffffffc09ed98a>] nouveau_handle_fini+Oxlfa/Ox2a0 [nouveau]
[ 3944.666878] [Offfffffc09ecld3>] nouveau_client_fini+Ox53/0x80 [nouveau]
[ 3944.666907] [afffffffc0a6e16f>] nouveau_cli_destroy+Ox2f/Ox50 [nouveau]
[ 3944.666934] [<ffffffffc0a6e25b>] nouveau_drm_postclose+Ox1b/Ox50 [nouveau]
[ 3944.666956] [dfffffffc03fdce4>] drm_release+Ox334/0x640 [dm]
[ 3944.666970] Wfffffff821e26b4>] __fput+Oxe4/0x240
[ 3944.666981] [afffffff821e285e>] ____f_put+Oxe/Ox10
[ 3944.666994] l<ffffffff820919c0] task_work_run+Oxd4/Oxf0
[ 3944.6670071 (afffffff820725c7>] do_exit+Ox377/0xab0
[ 3944.667023] kffffffffc09eef27>] ? nouveau_object_del+0x97/OxbO [nouveau]
[ 3944.667039] [<ffffffff82072d83>] do_group_exit+Ox43/0xc0
[ 3944.667052] Wfffffff8208312.b] get_signal_to_deliver+Ox29f/Ox8d0
( 3944.667067] Uffffffff82012588>] do_signal+0x48/OxbOO
( 3944.6670911 kffffffffc0a6e0da>] ? nouveau_drm_ioct1+0x8a/Oxa0 [nouveau]
[ 3944.667106] (Offfffff8204bc8>] ? do_vfs_ioctl+0x2c8/0x4a0
[ 3944.667120] kffffffff820130c9>] do_notiy_resume+Ox69/OxbO
( 3944.667133] [afffffff82787faw] int_signal+0x12/0x17
( 3944.667144] Code: Bad RIP value.
( 3944.667154] RIP (< (null)>] (null)
( 3944.667166] RSP <ffff880427827b60>
( 3944.667174] CR2: 0000000000000000

Nouveau is clearly involved in the bug.

IMG_20141103_130450.jpg

[g_hol]

same issue here ! Any new insights ?