Re: 2.6.33.6-rt26: oops (network related?)

[Fernando Lopez-Lezcano]

On Sat, 2010-07-31 at 12:01 -0700, Fernando Lopez-Lezcano wrote:
> On Thu, 2010-07-29 at 20:30 -0700, Fernando Lopez-Lezcano wrote:
> > Hi all...
> > This may not be rt related but here it goes anyway. It happened when I
> > tried to restart my iptables service (/sbin/service iptables start). I
> > think a day or two ago I had another network related hang, but it was a
> > complete hang (no clues left behind - power button to reset). 
> 
> Ok this one is rt related (apparently). The workstation (4 core, intel
> based) hang hard while a process was starting a daily backup but the
> logs captured the BUG:

One more observation, I repeatedly copied a couple of ISO images over
the network to the affected machine. No problem during the copy. BUT, it
hang completely when I tried to reboot it - while stopping the firewall
(I'm using shorewall), regretfully no trace was left behind. So
_something_ in the network stack is left is a bad state. I did see a
similar hang when stopping the iptables service in my laptop. 

This did not happen with the regular Fedora kernel (same test, no
problem). 

-- Fernando


> --------
> Jul 31 06:48:35 localhost kernel: ------------[ cut here ]------------
> Jul 31 06:48:35 localhost kernel: kernel BUG at kernel/rtmutex.c:808!
> Jul 31 06:48:35 localhost kernel: invalid opcode: 0000 [#1] PREEMPT SMP 
> Jul 31 06:48:35 localhost kernel: last sysfs
> file: /sys/devices/system/cpu/cpu3/cache/index2/shared_cpu_map
> Jul 31 06:48:35 localhost kernel: Modules linked in: snd_seq_midi
> autofs4 act_police cls_flow cls_fw cls_u32 sch_htb sch_hfsc sch_ingress
> sch_sfq xt_time xt_connlimit xt_realm iptable_raw xt_comment xt_recent
> xt_policy ipt_ULOG ipt_REDIRECT ipt_NETMAP ipt_MASQUERADE ipt_ECN
> ipt_ecn ipt_CLUSTERIP ipt_ah ipt_addrtype nf_nat_tftp nf_nat_snmp_basic
> nf_nat_sip nf_nat_pptp nf_nat_proto_gre nf_nat_irc nf_nat_h323
> nf_nat_ftp nf_nat_amanda ts_kmp nf_conntrack_amanda nf_conntrack_sane
> nf_conntrack_tftp nf_conntrack_sip nf_conntrack_proto_sctp
> nf_conntrack_pptp nf_conntrack_proto_gre nf_conntrack_netlink
> nf_conntrack_netbios_ns nf_conntrack_irc nf_conntrack_h323
> nf_conntrack_ftp xt_TPROXY nf_tproxy_core xt_tcpmss xt_pkttype
> xt_physdev xt_owner xt_NFQUEUE xt_NFLOG nfnetlink_log xt_multiport
> xt_MARK xt_mark xt_mac xt_limit xt_length xt_iprange xt_helper
> xt_hashlimit xt_DSCP xt_dscp xt_dccp xt_CONNMARK xt_connmark xt_CLASSIFY
> ipt_LOG iptable_nat nf_nat iptable_mangle nfnetlink coretemp hwmon_vid
> nfs lockd fscache nfs_acl auth_rp
> Jul 31 06:48:35 localhost kernel: cgss sunrpc ip6t_REJECT
> nf_conntrack_ipv6 ip6table_filter ip6_tables ipv6 dm_multipath uinput
> snd_hdspm snd_hdsp snd_hda_intel snd_hda_codec snd_usb_audio r8169
> snd_seq_dummy snd_seq_oss snd_seq_midi_event snd_seq mii snd_pcm_oss
> snd_mixer_oss snd_pcm ohci1394 ppdev snd_hwdep snd_usb_lib snd_rawmidi
> snd_seq_device snd_timer snd parport_pc i2c_i801 parport snd_page_alloc
> iTCO_wdt iTCO_vendor_support serio_raw soundcore ata_generic pata_acpi
> pata_jmicron radeon ttm drm_kms_helper drm i2c_algo_bit i2c_core [last
> unloaded: microcode]
> Jul 31 06:48:35 localhost kernel:
> Jul 31 06:48:35 localhost kernel: Pid: 50, comm: sirq-net-rx/3 Not
> tainted 2.6.33.6-147.rt26.1.fc12.ccrma.i686.rtPAE #1 EP45-DS3R/EP45-DS3R
> Jul 31 06:48:35 localhost kernel: EIP: 0060:[<c0791bf7>] EFLAGS:
> 00010046 CPU: 3
> Jul 31 06:48:35 localhost kernel: EIP is at rt_spin_lock_slowlock
> +0x43/0x1bb
> Jul 31 06:48:35 localhost kernel: EAX: f715c0b0 EBX: c5f85c80 ECX:
> c5f85c80 EDX: f715c0b0
> Jul 31 06:48:35 localhost kernel: ESI: ed004fc0 EDI: f5fec240 EBP:
> f7161dc0 ESP: f7161d68
> Jul 31 06:48:35 localhost kernel: DS: 007b ES: 007b FS: 00d8 GS: 00e0
> SS: 0068 preempt:00000001
> Jul 31 06:48:35 localhost kernel: Process sirq-net-rx/3 (pid: 50,
> ti=f7160000 task=f715c0b0 task.ti=f7160000)
> Jul 31 06:48:35 localhost kernel: Stack:
> Jul 31 06:48:35 localhost kernel: f7161e04 c057783d c043a00f 00000039
> 00000292 c0420002 6d9c675d c5f881c0
> Jul 31 06:48:35 localhost kernel: <0> f7161dd0 f7161d94 c041caac
> f7161dd8 c0792979 ed7d99f4 c5f881c0 00000002
> Jul 31 06:48:35 localhost kernel: <0> 00000000 00000000 00000246
> c5f85c80 ed004fc0 f5fec240 f7161dcc c0465595
> Jul 31 06:48:35 localhost kernel: Call Trace:
> Jul 31 06:48:35 localhost kernel: [<c057783d>] ?
> selinux_socket_sock_rcv_skb+0x8f/0x1c7
> Jul 31 06:48:35 localhost kernel: [<c043a00f>] ? post_schedule_rt
> +0xd/0x14
> Jul 31 06:48:35 localhost kernel: [<c0420002>] ? hpet_msi_set_affinity
> +0xf/0x75
> Jul 31 06:48:35 localhost kernel: [<c041caac>] ?
> smp_reschedule_interrupt+0x16/0x20
> Jul 31 06:48:35 localhost kernel: [<c0792979>] ? reschedule_interrupt
> +0x31/0x38
> Jul 31 06:48:35 localhost kernel: [<c0465595>] ?
> rt_spin_lock_fastlock.clone.1+0x5c/0x5f
> Jul 31 06:48:35 localhost kernel: [<c0792332>] ? rt_spin_lock+0x8/0xa
> Jul 31 06:48:35 localhost kernel: [<c075f896>] ? ipt_do_table+0xce/0x4f0
> Jul 31 06:48:35 localhost kernel: [<c0791d51>] ? rt_spin_lock_slowlock
> +0x19d/0x1bb
> Jul 31 06:48:35 localhost kernel: [<c07923eb>] ? rt_read_unlock
> +0x13/0x15
> Jul 31 06:48:35 localhost kernel: [<c074c0d7>] ? udp_queue_rcv_skb
> +0x193/0x1d2
> Jul 31 06:48:35 localhost kernel: [<fa6ad0a2>] ? ipt_hook+0x1e/0x24
> [iptable_raw]
> Jul 31 06:48:35 localhost kernel: [<c0720ceb>] ? nf_iterate+0x2f/0x62
> Jul 31 06:48:35 localhost kernel: [<c072d600>] ? ip_rcv_finish+0x0/0x2d9
> Jul 31 06:48:35 localhost kernel: [<c0720e44>] ? nf_hook_slow+0x44/0xa1
> Jul 31 06:48:35 localhost kernel: [<c072d600>] ? ip_rcv_finish+0x0/0x2d9
> Jul 31 06:48:35 localhost kernel: [<c072dae6>] ? ip_rcv+0x20d/0x243
> Jul 31 06:48:35 localhost kernel: [<c072d600>] ? ip_rcv_finish+0x0/0x2d9
> Jul 31 06:48:35 localhost kernel: [<c07082fc>] ? netif_receive_skb
> +0x3ca/0x3ea
> Jul 31 06:48:35 localhost kernel: [<c070838f>] ? process_backlog
> +0x73/0x9d
> Jul 31 06:48:35 localhost kernel: [<c07088ef>] ? net_rx_action
> +0x92/0x1a7
> Jul 31 06:48:35 localhost kernel: [<c044445e>] ? run_ksoftirqd
> +0x138/0x236
> Jul 31 06:48:35 localhost kernel: [<c0444326>] ? run_ksoftirqd+0x0/0x236
> Jul 31 06:48:35 localhost kernel: [<c04557ae>] ? kthread+0x5f/0x64
> Jul 31 06:48:35 localhost kernel: [<c045574f>] ? kthread+0x0/0x64
> Jul 31 06:48:35 localhost kernel: [<c040907e>] ? kernel_thread_helper
> +0x6/0x10
> Jul 31 06:48:35 localhost kernel: Code: 7b 08 00 89 45 b8 75 12 8d 43 04
> 89 43 04 89 43 08 8d 43 0c 89 43 0c 89 43 10 8b 43 14 64 8b 15 2c b1 a5
> c0 83 e0 fc 39 c2 75 04 <0f> 0b eb fe 8b 3a 81 ff 08 01 00 00 74 0a 83
> ff 02 b8 04 00 00 
> Jul 31 06:48:35 localhost kernel: EIP: [<c0791bf7>]
> rt_spin_lock_slowlock+0x43/0x1bb SS:ESP 0068:f7161d68
> Jul 31 06:48:35 localhost kernel: ---[ end trace 1f65f0b05c43491f ]---
> Jul 31 06:48:35 localhost kernel: note: sirq-net-rx/3[50] exited with
> preempt_count 1
> --------
> 
> There is something fishy going on in rt26 and it appears to be related
> to network activity. 
> 
> -- Fernando
> 
> 
> 
> > Jul 29 18:27:57 localhost kernel: BUG: unable to handle kernel NULL
> > pointer dereference at (null)
> > Jul 29 18:27:57 localhost kernel: IP: [<c045ad40>] exit_creds+0xc/0x54
> > Jul 29 18:27:57 localhost kernel: *pdpt = 0000000000a68001 *pde =
> > 0000000000000000 
> > Jul 29 18:27:57 localhost kernel: Oops: 0000 [#1] PREEMPT SMP 
> > Jul 29 18:27:57 localhost kernel: last sysfs
> > file: /sys/devices/virtual/net/pan0/statistics/collisions
> > Jul 29 18:27:57 localhost kernel: Modules linked in: snd_seq_midi
> > snd_seq_midi_event snd_seq_dummy snd_usb_audio snd_usb_lib snd_rawmidi
> > fuse rfcomm sco bridge stp llc bnep l2cap sunrpc cpufreq_ondemand
> > acpi_cpufreq ip6t_REJECT nf_conntrack_ipv6 ip6table_filter ip6_tables
> > ipv6 dm_multipath uinput arc4 snd_hda_codec_analog ecb iwl3945
> > snd_hda_intel snd_hda_codec iwlcore thinkpad_acpi btusb sdhci_pci sdhci
> > mmc_core snd_hwdep e1000e snd_seq ohci1394 ricoh_mmc snd_seq_device
> > snd_pcm snd_timer snd mac80211 i2c_i801 iTCO_wdt iTCO_vendor_support
> > joydev cfg80211 bluetooth snd_page_alloc rfkill soundcore yenta_socket
> > rsrc_nonstatic i915 drm_kms_helper drm i2c_algo_bit i2c_core video
> > output [last unloaded: microcode]
> 


--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at  http://vger.kernel.org/majordomo-info.html
Please read the FAQ at  http://www.tux.org/lkml/