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: Sat, 08 Feb 2014 09:30:27 +0100 Message-ID: <52F5EB23.4060204@gmx.de> References: <52DEA270.60803@gmx.de> <52E165B7.6020506@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 To: Dave Hansen , Linux Kernel Return-path: In-Reply-To: <52E165B7.6020506@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 07:55 PM, 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 C= PU { 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 N= ot tainted 3.13.0 #3 >> Jan 21 17:18:57 n22 kernel: Hardware name: LENOVO 4180F65/4180F65, B= IOS 83ET75WW (1.45 ) 05/10/2013 >> Jan 21 17:18:57 n22 kernel: task: e921c370 ti: e5f36000 task.ti: e5f= 36000 >=20 > I'm seeing a very similar hang with an ubuntu guest and a custom kern= el. > I'm on commit 0dc3fd0249a, and it's 100% reproducible every time I r= un KVM. >=20 > Cc-ing a few more folks... >=20 With 3.12.2 at the same 32 bit Gentoo Linux I experienced a similar thi= ngin a complete different scenario: I umounted a loop-mounted file, on = which I created and filled a btrfs file system before (/usr/portage FWI= W - I'm a Gentoo user) and the systems becomes very slowly and /var/log= /messages shows : =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: Error sending POWER= _TABLE_CMD: time out after 2000ms. =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: Current CMD queue r= ead_ptr 100 write_ptr 101 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: Loaded firmware ver= sion: 18.168.6.1 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | OK =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | uPc =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | branch= link1 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | branch= link2 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | interr= uptlink1 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | interr= uptlink2 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | data1 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | data2 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | line =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | beacon= time =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | tsf lo= w =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | tsf hi =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | time g= p1 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | time g= p2 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | time g= p3 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | uCode = version =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | hw ver= sion =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | board = version =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | hcmd =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr0 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr1 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr2 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr3 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr4 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr_pr= ef =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | wait_e= vent =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | l2p_co= ntrol =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | l2p_du= ration =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | l2p_mh= valid =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | l2p_ad= dr_match =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | lmpm_p= mg_sel =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | timest= amp =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: 0x00000000 | flow_h= andler =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: Start IWL Event Log= Dump: nothing in log =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: set power fail, ret= =3D -110 =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: iwl_trans_wait_tx_q= ueue_empty bad state =3D 0 =46eb 7 22:46:46 n22 kernel: ieee80211 phy0: Hardware restart was requ= ested =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: L1 Enabled; Disabli= ng L0S =46eb 7 22:46:46 n22 kernel: iwlwifi 0000:03:00.0: Radio type=3D0x1-0x= 2-0x0 =46eb 7 22:46:51 n22 kernel: iwlwifi 0000:03:00.0: Failed to load firm= ware chunk! =46eb 7 22:46:51 n22 kernel: iwlwifi 0000:03:00.0: Could not load the = [0] uCode section =46eb 7 22:46:51 n22 kernel: iwlwifi 0000:03:00.0: Failed to start RT = ucode: -110 =46eb 7 22:46:51 n22 kernel: iwlwifi 0000:03:00.0: Unable to initializ= e device. =46eb 7 22:46:51 n22 kernel: iwlwifi 0000:03:00.0: Fw not loaded - dro= pping CMD: 18 =46eb 7 22:46:51 n22 kernel: wlp3s0: HW problem - can not stop rx aggr= egation for 08:96:d7:05:f9:2a tid 0 =46eb 7 22:46:51 n22 kernel: iwlwifi 0000:03:00.0: Fw not loaded - dro= pping CMD: 18 =46eb 7 22:46:51 n22 kernel: wlp3s0: HW problem - can not stop rx aggr= egation for 08:96:d7:05:f9:2a tid 6 =46eb 7 22:47:06 n22 dhcpcd[5978]: wlp3s0: carrier lost =46eb 7 22:47:06 n22 dhcpcd[5978]: wlp3s0: deleting route to 192.168.1= 78.0/24 =46eb 7 22:47:06 n22 dhcpcd[5978]: wlp3s0: deleting default route via = 192.168.178.1 =46eb 7 22:47:06 n22 kernel: iwlwifi 0000:03:00.0: iwl_trans_wait_tx_q= ueue_empty bad state =3D 0 =46eb 7 22:47:06 n22 kernel: cfg80211: Calling CRDA to update world re= gulatory domain =46eb 7 22:47:06 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:06 n22 wpa_cli: interface wlp3s0 DISCONNECTED =46eb 7 22:47:06 n22 wpa_cli: executing 'false /etc/init.d/net.wlp3s0 = --quiet stop' failed =46eb 7 22:47:07 n22 kernel: cfg80211: World regulatory domain updated= : =46eb 7 22:47:07 n22 kernel: cfg80211: (start_freq - end_freq @ band= width), (max_antenna_gain, max_eirp) =46eb 7 22:47:07 n22 kernel: cfg80211: (2402000 KHz - 2472000 KHz @ = 40000 KHz), (300 mBi, 2000 mBm) =46eb 7 22:47:07 n22 kernel: cfg80211: (2457000 KHz - 2482000 KHz @ = 40000 KHz), (300 mBi, 2000 mBm) =46eb 7 22:47:07 n22 kernel: cfg80211: (2474000 KHz - 2494000 KHz @ = 20000 KHz), (300 mBi, 2000 mBm) =46eb 7 22:47:07 n22 kernel: cfg80211: (5170000 KHz - 5250000 KHz @ = 40000 KHz), (300 mBi, 2000 mBm) =46eb 7 22:47:07 n22 kernel: cfg80211: (5735000 KHz - 5835000 KHz @ = 40000 KHz), (300 mBi, 2000 mBm) =46eb 7 22:47:07 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:08 n22 ntpd[6263]: Deleting interface #3 wlp3s0, 192.168= =2E178.21#123, interface stats: received=3D279, sent=3D281, dropped=3D0= , active_time=3D12050 secs =46eb 7 22:47:08 n22 ntpd[6263]: 144.76.117.245 interface 192.168.178.= 21 -> (none) =46eb 7 22:47:08 n22 ntpd[6263]: 193.175.73.151 interface 192.168.178.= 21 -> (none) =46eb 7 22:47:08 n22 ntpd[6263]: 78.47.93.200 interface 192.168.178.21= -> (none) =46eb 7 22:47:08 n22 ntpd[6263]: 130.149.220.125 interface 192.168.178= =2E21 -> (none) =46eb 7 22:47:08 n22 ntpd[6263]: peers refreshed =46eb 7 22:47:08 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:09 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:10 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:11 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:12 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:13 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:14 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:15 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:16 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:17 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:18 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:19 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:20 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:21 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:22 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:23 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:24 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:25 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:26 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =46eb 7 22:47:27 n22 kernel: iwlwifi 0000:03:00.0: Request scan called= when driver not ready. =2E.. - --=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/ iF0EAREIAAYFAlL16yIACgkQxOrN3gB26U6ZLAD4+xOsxbCxkoLLyZm10XESUBxh 3SK0iNuGjBEamGth3gD9GgeZoyK2dwfA5SJX/r6IwWVvwgwQ03frfqCRWQNe6BU=3D =3DEyi4 -----END PGP SIGNATURE-----