From mboxrd@z Thu Jan 1 00:00:00 1970 From: =?UTF-8?B?VG9yYWxmIEbDtnJzdGVy?= Subject: Re: 3.13 hangs when I tried to start a KVM at a 32 bit stable Gentoo Date: Thu, 23 Jan 2014 20:50:11 +0100 Message-ID: <52E17273.2030200@gmx.de> References: <52DEA270.60803@gmx.de> <52E165B7.6020506@intel.com> <52E16E83.6060301@intel.com> Mime-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: QUOTED-PRINTABLE Cc: kernel@gentoo.org, kvm@vger.kernel.org, Gleb Natapov , Paolo Bonzini , Peter Zijlstra To: Dave Hansen , Linux Kernel Return-path: In-Reply-To: <52E16E83.6060301@intel.com> Sender: linux-kernel-owner@vger.kernel.org List-Id: kvm.vger.kernel.org -----BEGIN PGP SIGNED MESSAGE----- Hash: SHA256 On 01/23/2014 08:33 PM, Dave Hansen wrote: > On 01/23/2014 10:55 AM, Dave Hansen wrote: >> On 01/21/2014 08:38 AM, Toralf F=C3=B6rster wrote: >>> Jan 21 17:18:57 n22 kernel: INFO: rcu_sched self-detected stall on = CPU { 2} (t=3D60001 jiffies g=3D18494 c=3D18493 q=3D183951) >>> Jan 21 17:18:57 n22 kernel: sending NMI to all CPUs: >>> Jan 21 17:18:57 n22 kernel: NMI backtrace for cpu 2 >>> Jan 21 17:18:57 n22 kernel: CPU: 2 PID: 6779 Comm: qemu-system-x86 = Not tainted 3.13.0 #3 >>> Jan 21 17:18:57 n22 kernel: Hardware name: LENOVO 4180F65/4180F65, = BIOS 83ET75WW (1.45 ) 05/10/2013 >>> Jan 21 17:18:57 n22 kernel: task: e921c370 ti: e5f36000 task.ti: e5= f36000 >> >> I'm seeing a very similar hang with an ubuntu guest and a custom ker= nel. >> I'm on commit 0dc3fd0249a, and it's 100% reproducible every time I r= un KVM. >=20 > Did a little more LKML digging and found this: >=20 > http://marc.info/?l=3Dlinux-kernel&m=3D139038631607917&q=3Draw >=20 Hhm, that patch cannot be applied to 3.13.0 kernel :-( > Peter's fix works for me. I'm also running a CONFIG_PREEMPT_VOLUNTAR= Y=3Dy > config. >=20 I do have : n22 /usr/src/linux # zgrep CONFIG_PREEMPT /proc/config.gz # CONFIG_PREEMPT_RCU is not set CONFIG_PREEMPT_NOTIFIERS=3Dy # CONFIG_PREEMPT_NONE is not set CONFIG_PREEMPT_VOLUNTARY=3Dy # CONFIG_PREEMPT is not set >=20 What makes the situation really annyoing - sometimes I just can restart= my wlan device it the system works normal, but sometimes the whole sys= tem hangs and for those cases then sometimes not even sysrq buttons do = work. What I see in /var/log/messages are those lines : Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: Error sending POWER_T= ABLE_CMD: time out after 2000ms. Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: Current CMD queue rea= d_ptr 158 write_ptr 159 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: Loaded firmware versi= on: 18.168.6.1 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | OK = Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0= x00000000 | uPc Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | branchli= nk1 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | branchli= nk2 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | interrup= tlink1 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | interrup= tlink2 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | data1 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | data2 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | line Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | beacon t= ime Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | tsf low Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | tsf hi Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | time gp1 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | time gp2 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | time gp3 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | uCode ve= rsion Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | hw versi= on Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | board ve= rsion Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | hcmd Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr0 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr1 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr2 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr3 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr4 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr_pref Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | wait_eve= nt Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | l2p_cont= rol Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | l2p_dura= tion Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | l2p_mhva= lid Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | l2p_addr= _match Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | lmpm_pmg= _sel Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | timestam= p Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | flow_han= dler Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: Start IWL Event Log D= ump: nothing in log Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: set power fail, ret =3D= -110 Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: iwl_trans_wait_tx_que= ue_empty bad state =3D 0 Jan 23 19:23:15 n22 kernel: ieee80211 phy0: Hardware restart was reques= ted Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: L1 Enabled; Disabling= L0S Jan 23 19:23:15 n22 kernel: iwlwifi 0000:03:00.0: Radio type=3D0x1-0x2-= 0x0 Jan 23 19:23:20 n22 kernel: iwlwifi 0000:03:00.0: Failed to load firmwa= re chunk! Jan 23 19:23:20 n22 kernel: iwlwifi 0000:03:00.0: Could not load the [0= ] uCode section Jan 23 19:23:20 n22 kernel: iwlwifi 0000:03:00.0: Failed to start RT uc= ode: -110 Jan 23 19:23:20 n22 kernel: iwlwifi 0000:03:00.0: Unable to initialize = device. Jan 23 19:23:28 n22 dhcpcd[4878]: wlp3s0: carrier lost Jan 23 19:23:28 n22 kernel: iwlwifi 0000:03:00.0: Fw not loaded - dropp= ing CMD: 1e = Jan 23 19:23:28 n22 kernel: iwlwifi 0000:03:00.0: Couldn't flu= sh the AGG queue = Jan 23 19:23:28 n22 kernel: iwlwifi 0000:03:00.0: Fw = not loaded - dropping CMD: 18 = Jan 23 19:23:28 n22 kernel: wlp3s0: HW probl= em - can not stop rx aggregation for 08:96:d7:05:f9:2a tid 0 = Jan 23 19:23:28 n22 kernel: iwlwifi= 0000:03:00.0: iwl_trans_wait_tx_queue_empty bad state =3D 0 = Jan 23 19:23:28 n22 kern= el: cfg80211: Calling CRDA to update world regulatory domain = Jan 23 19:23:28= n22 kernel: cfg80211: World regulatory domain updated: = =20 Jan 23 19:23:= 28 n22 kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_an= tenna_gain, max_eirp) Jan = 23 19:23:28 n22 kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 = KHz), (300 mBi, 2000 mBm) = Jan 23 19:23:28 n22 kernel: cfg80211: (2457000 KHz - 2482000 KHz= @ 40000 KHz), (300 mBi, 2000 mBm) = Jan 23 19:23:28 n22 kernel: cfg80211: (2474000 KHz - 24= 94000 KHz @ 20000 KHz), (300 mBi, 2000 mBm) = Jan 23 19:23:28 n22 kernel: cfg80211: (5170000= KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) = Jan 23 19:23:28 n22 kernel: cfg80211: = (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) Jan 23 19:23:28 n22 wpa_cli: interface wlp3s0 DISCONNECTED Jan 23 19:23:28 n22 wpa_cli: executing 'false /etc/init.d/net.wlp3s0 --= quiet stop' failed Jan 23 19:23:28 n22 kernel: iwlwifi 0000:03:00.0: Request scan called w= hen driver not ready. Jan 23 19:23:29 n22 kernel: iwlwifi 0000:03:00.0: Request scan called w= hen driver not ready. Jan 23 19:23:29 n22 ntpd[5421]: Deleting interface #3 wlp3s0, 192.168.1= 78.21#123, interface stats: received=3D12, sent=3D12, dropped=3D0, acti= ve_time=3D188 secs Jan 23 19:23:29 n22 ntpd[5421]: 62.116.162.126 interface 192.168.178.21= -> (none) Jan 23 19:23:29 n22 ntpd[5421]: 131.188.3.220 interface 192.168.178.21 = -> (none) Jan 23 19:23:29 n22 ntpd[5421]: 46.165.212.205 interface 192.168.178.21= -> (none) Jan 23 19:23:29 n22 ntpd[5421]: 46.4.54.78 interface 192.168.178.21 -> = (none) Jan 23 19:23:29 n22 ntpd[5421]: peers refreshed Jan 23 19:23:30 n22 kernel: iwlwifi 0000:03:00.0: Request scan called w= hen driver not ready. Jan 23 19:23:31 n22 kernel: iwlwifi 0000:03:00.0: Request scan called w= hen driver not ready. Jan 23 19:23:32 n22 kernel: iwlwifi 0000:03:00.0: Request scan called w= hen driver not ready. The last line now repeats every second till I either restart the wlan0 = device or the system completely hangs. - --=20 MfG/Sincerely Toralf F=C3=B6rster pgp finger print:1A37 6F99 4A9D 026F 13E2 4DCF C4EA CDDE 0076 E94E - --=20 MfG/Sincerely Toralf F=C3=B6rster pgp finger print:1A37 6F99 4A9D 026F 13E2 4DCF C4EA CDDE 0076 E94E -----BEGIN PGP SIGNATURE----- Version: GnuPG v2.0.22 (GNU/Linux) Comment: Using GnuPG with Thunderbird - http://www.enigmail.net/ iF4EAREIAAYFAlLhcnMACgkQxOrN3gB26U5pNgD9Gnd/2LOwCL/Ca2s3eeDje+Zz lzAZKSoLk+VQXn5R0qgA/RZV4WW4BTT/xSc1E378TlmctXQ+AX7iJ/FbgfZXyLvB =3DTw8L -----END PGP SIGNATURE-----