Closed kverkind closed 3 years ago
Expect no kernel warnings in log file
kernel warning random for different cores
Normal OpenMTCProuter running with 3 WANs
Normal operation. Random warnings (not affecting operation)
Kernel warning at random intervals
Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.559472] ------------[ cut here ]------------ Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.564195] WARNING: CPU: 3 PID: 27970 at kernel/module.c:1152 module_put.part.61+0x48/0x50 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.572625] Modules linked in: ath9k ath9k_htc ath9k_common rtl8192cu rtl8192c_common rtl_usb rt2800usb rt2800lib qcserial pppoe ppp_async option ipw cdc_mbim brcmfmac ath9k_hw ath wireguard usb_wwan usb_serial_simple ti_usb_3410_5052 sr9700 smsc95xx smsc75xx sierra_net sierra rtlwifi rtl8xxxu rtl8812au rtl8187 rt2x00usb rt2x00lib rndis_host qmi_wwan pppox ppp_generic pl2303 oti6858 mt7601u mos7720 mmc_spi mct_u232 mcs7830 mac80211 lzo libchacha20poly1305 libblake2s keyspan kalmia ipt_REJECT huawei_cdc_ncm garmin_gps ftdi_sio ebtable_nat ebtable_filter ebtable_broute dm9601 cypress_m8 cp210x chacha_neon ch341 cfg80211 cdc_subset cdc_ncm cdc_ether cdc_eem belkin_sa ax88179_178a asix ark3116 xt_time xt_tcpudp xt_tcpmss xt_statistic xt_state xt_socket xt_recent xt_quota xt_policy xt_pkttype xt_owner xt_ndpi xt_nat xt_multiport xt_mark xt_mac xt_limit xt_length xt_iface xt_hl xt_helper xt_hashlimit xt_esp xt_ecn xt_dscp xt_conntrack xt_connmark xt_connlimit xt_connbytes xt_condition xt_comment Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.572696] xt_cgroup xt_addrtype xt_TRACE xt_TPROXY xt_TCPMSS xt_REDIRECT xt_MASQUERADE xt_LOG xt_IPMARK xt_HL xt_FLOWOFFLOAD xt_DSCP xt_CT xt_CLASSIFY xt_ACCOUNT visor via_velocity via_rhine usbserial usbnet usbhid tulip ts_fsm ts_bm solos_pci slhc sky2 skge sis900 sis190 sch_cake rtl8150 r8712u(C) r8169 r8152 r8125 r6040 poly1305_neon pegasus pcnet32 of_mmc_spi nf_tproxy_ipv6 nf_tproxy_ipv4 nf_socket_ipv6 nf_socket_ipv4 nf_reject_ipv4 nf_nat_tftp nf_nat_snmp_basic nf_nat_sip nf_nat_pptp nf_nat_irc nf_nat_h323 nf_nat_ftp nf_nat_amanda nf_log_ipv4 nf_flow_table_hw nf_flow_table nf_conntrack_tftp nf_conntrack_snmp nf_conntrack_sip nf_conntrack_rtcache nf_conntrack_pptp nf_conntrack_netlink nf_conntrack_irc nf_conntrack_h323 nf_conntrack_ftp nf_conntrack_broadcast ts_kmp nf_conntrack_amanda nf_conncount ne2k_pci macvlan lzo_decompress lzo_compress libcurve25519_generic libchacha libblake2s_generic kaweth iptable_raw iptable_nat iptable_mangle iptable_filter ipt_ah ipt_ECN ipheth Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.660480] ip6table_raw ip_tables hso hid_generic forcedeth ezusb ethoc et131x ebtables ebt_vlan ebt_stp ebt_redirect ebt_pkttype ebt_mark_m ebt_mark ebt_limit ebt_among ebt_802_3 e1000e e100 crc7 crc_itu_t crc_ccitt compat_xtables compat cdc_wdm cdc_acm brcmutil bnx2 atl2 atl1e atl1c atl1 asn1_decoder arptable_filter arpt_mangle arp_tables 8390 8250_pci 8139too 8139cp tcp_nanqinlang tcp_bbr2 sch_teql sch_sfq sch_red sch_prio sch_pie sch_multiq sch_gred sch_fq sch_dsmark sch_codel em_text em_nbyte em_meta em_cmp act_simple act_police act_pedit act_ipt act_gact act_csum sch_tbf sch_ingress sch_htb sch_hfsc em_u32 cls_u32 cls_tcindex cls_route cls_matchall cls_fw cls_flow cls_basic act_skbedit act_mirred snd_bcm2835(C) hid evdev i2c_gpio i2c_algo_bit i2c_dev spi_ks8995 xt_set ip_set_list_set ip_set_hash_netportnet ip_set_hash_netport ip_set_hash_netnet ip_set_hash_netiface ip_set_hash_net ip_set_hash_mac ip_set_hash_ipportnet ip_set_hash_ipportip ip_set_hash_ipport ip_set_hash_ipmark Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.747314] ip_set_hash_ip ip_set_bitmap_port ip_set_bitmap_ipmac ip_set_bitmap_ip ip_set nfnetlink ip6table_nat nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip6t_NPT nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables ip6t_REJECT x_tables nf_reject_ipv6 swconfig bonding 3c59x ip6_gre ip_gre gre vmxnet3 e1000 ifb nat46 ip6_udp_tunnel udp_tunnel sit sctp libcrc32c ipcomp6 xfrm6_tunnel esp6 ah6 xfrm4_tunnel ipcomp esp4 ah4 ip6_tunnel netlink_diag tunnel6 tunnel4 ip_tunnel hfcpci hfcmulti veth tun snd_rawmidi snd_seq_device snd_pcm_oss snd_pcm_dmaengine snd_pcm snd_timer snd_mixer_oss snd_hwdep snd_compress snd soundcore mISDN_dsp l1oip mISDN_core xfrm_user xfrm_ipcomp af_key xfrm_algo autofs4 br2684 atm nls_utf8 zram zsmalloc natsemi eeprom_93cx6 sha1_generic md5 kpp ghash_generic gf128mul gcm echainiv des_generic libdes deflate zlib_inflate zlib_deflate authenc crypto_acompress vfat fat nls_iso8859_1 nls_cp437 ahci libahci libata fsl_mph_dr_of ehci_platform ehci_fsl Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.834586] ehci_hcd gpio_button_hotplug tg3 b44 ssb ptp realtek pps_core Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.929100] CPU: 3 PID: 27970 Comm: kworker/u8:1 Tainted: G WC 5.4.106 #0 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.937183] Hardware name: Raspberry Pi 4 Model B Rev 1.2 (DT) Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.943105] Workqueue: mptcp_wq mptcp_sub_close_wq Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.947975] pstate: 80400005 (Nzcv daif +PAN -UAO) Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.952845] pc : module_put.part.61+0x48/0x50 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.957280] lr : module_put+0x10/0x20 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.961018] sp : ffffffc01153bcc0 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.964409] x29: ffffffc01153bcc0 x28: 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.969800] x27: ffffff80f26b72b8 x26: ffffffc0108a1bd0 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.975190] x25: 0000000000000000 x24: ffffff80f28b46c0 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.980579] x23: 0000000000000000 x22: ffffffc01070c69c Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.985969] x21: ffffff80ef4dd840 x20: 0000000000000007 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.991358] x19: ffffff80ef4dd7c0 x18: 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.996748] x17: 0000000000000000 x16: 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.002137] x15: 0000000000000000 x14: 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.007526] x13: 0000000000000000 x12: 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.012916] x11: 000000004c000e63 x10: 0000000000000800 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.018305] x9 : ffffff80f20b2f64 x8 : fefefefefefefeff Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.023695] x7 : 0000000000000010 x6 : 00000cf5d34856bb Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.029084] x5 : 0000000000000000 x4 : 0000000000000005 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.034473] x3 : 00000000ffffffff x2 : 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.039863] x1 : 0000000000000000 x0 : ffffffc008ddc200 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.045253] Call trace: Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.047777] module_put.part.61+0x48/0x50 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.051867] tcp_cleanup_congestion_control+0x30/0x38 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.056996] tcp_v4_destroy_sock+0x40/0x110 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.061257] inet_csk_destroy_sock+0x50/0x110 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.065693] tcp_close+0x31c/0x580 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.069172] mptcp_sub_close_wq+0x74/0xe8 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.073260] process_one_work+0x1ec/0x378 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.077346] worker_thread+0x48/0x4d0 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.081089] kthread+0x120/0x128 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.084395] ret_from_fork+0x10/0x1c Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.088047] ---[ end trace 5d7fd9bc74f67f97 ]---
disappeared. I believe it was related to ssh timeout. Puted idle time to 120 sec and it is gone
Expected Behavior
Expect no kernel warnings in log file
Current Behavior
kernel warning random for different cores
Possible Solution
Steps to Reproduce the Problem
Normal OpenMTCProuter running with 3 WANs
Context (Environment)
Normal operation. Random warnings (not affecting operation)
Kernel warning at random intervals
Specifications
Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.559472] ------------[ cut here ]------------ Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.564195] WARNING: CPU: 3 PID: 27970 at kernel/module.c:1152 module_put.part.61+0x48/0x50 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.572625] Modules linked in: ath9k ath9k_htc ath9k_common rtl8192cu rtl8192c_common rtl_usb rt2800usb rt2800lib qcserial pppoe ppp_async option ipw cdc_mbim brcmfmac ath9k_hw ath wireguard usb_wwan usb_serial_simple ti_usb_3410_5052 sr9700 smsc95xx smsc75xx sierra_net sierra rtlwifi rtl8xxxu rtl8812au rtl8187 rt2x00usb rt2x00lib rndis_host qmi_wwan pppox ppp_generic pl2303 oti6858 mt7601u mos7720 mmc_spi mct_u232 mcs7830 mac80211 lzo libchacha20poly1305 libblake2s keyspan kalmia ipt_REJECT huawei_cdc_ncm garmin_gps ftdi_sio ebtable_nat ebtable_filter ebtable_broute dm9601 cypress_m8 cp210x chacha_neon ch341 cfg80211 cdc_subset cdc_ncm cdc_ether cdc_eem belkin_sa ax88179_178a asix ark3116 xt_time xt_tcpudp xt_tcpmss xt_statistic xt_state xt_socket xt_recent xt_quota xt_policy xt_pkttype xt_owner xt_ndpi xt_nat xt_multiport xt_mark xt_mac xt_limit xt_length xt_iface xt_hl xt_helper xt_hashlimit xt_esp xt_ecn xt_dscp xt_conntrack xt_connmark xt_connlimit xt_connbytes xt_condition xt_comment Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.572696] xt_cgroup xt_addrtype xt_TRACE xt_TPROXY xt_TCPMSS xt_REDIRECT xt_MASQUERADE xt_LOG xt_IPMARK xt_HL xt_FLOWOFFLOAD xt_DSCP xt_CT xt_CLASSIFY xt_ACCOUNT visor via_velocity via_rhine usbserial usbnet usbhid tulip ts_fsm ts_bm solos_pci slhc sky2 skge sis900 sis190 sch_cake rtl8150 r8712u(C) r8169 r8152 r8125 r6040 poly1305_neon pegasus pcnet32 of_mmc_spi nf_tproxy_ipv6 nf_tproxy_ipv4 nf_socket_ipv6 nf_socket_ipv4 nf_reject_ipv4 nf_nat_tftp nf_nat_snmp_basic nf_nat_sip nf_nat_pptp nf_nat_irc nf_nat_h323 nf_nat_ftp nf_nat_amanda nf_log_ipv4 nf_flow_table_hw nf_flow_table nf_conntrack_tftp nf_conntrack_snmp nf_conntrack_sip nf_conntrack_rtcache nf_conntrack_pptp nf_conntrack_netlink nf_conntrack_irc nf_conntrack_h323 nf_conntrack_ftp nf_conntrack_broadcast ts_kmp nf_conntrack_amanda nf_conncount ne2k_pci macvlan lzo_decompress lzo_compress libcurve25519_generic libchacha libblake2s_generic kaweth iptable_raw iptable_nat iptable_mangle iptable_filter ipt_ah ipt_ECN ipheth Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.660480] ip6table_raw ip_tables hso hid_generic forcedeth ezusb ethoc et131x ebtables ebt_vlan ebt_stp ebt_redirect ebt_pkttype ebt_mark_m ebt_mark ebt_limit ebt_among ebt_802_3 e1000e e100 crc7 crc_itu_t crc_ccitt compat_xtables compat cdc_wdm cdc_acm brcmutil bnx2 atl2 atl1e atl1c atl1 asn1_decoder arptable_filter arpt_mangle arp_tables 8390 8250_pci 8139too 8139cp tcp_nanqinlang tcp_bbr2 sch_teql sch_sfq sch_red sch_prio sch_pie sch_multiq sch_gred sch_fq sch_dsmark sch_codel em_text em_nbyte em_meta em_cmp act_simple act_police act_pedit act_ipt act_gact act_csum sch_tbf sch_ingress sch_htb sch_hfsc em_u32 cls_u32 cls_tcindex cls_route cls_matchall cls_fw cls_flow cls_basic act_skbedit act_mirred snd_bcm2835(C) hid evdev i2c_gpio i2c_algo_bit i2c_dev spi_ks8995 xt_set ip_set_list_set ip_set_hash_netportnet ip_set_hash_netport ip_set_hash_netnet ip_set_hash_netiface ip_set_hash_net ip_set_hash_mac ip_set_hash_ipportnet ip_set_hash_ipportip ip_set_hash_ipport ip_set_hash_ipmark Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.747314] ip_set_hash_ip ip_set_bitmap_port ip_set_bitmap_ipmac ip_set_bitmap_ip ip_set nfnetlink ip6table_nat nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip6t_NPT nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables ip6t_REJECT x_tables nf_reject_ipv6 swconfig bonding 3c59x ip6_gre ip_gre gre vmxnet3 e1000 ifb nat46 ip6_udp_tunnel udp_tunnel sit sctp libcrc32c ipcomp6 xfrm6_tunnel esp6 ah6 xfrm4_tunnel ipcomp esp4 ah4 ip6_tunnel netlink_diag tunnel6 tunnel4 ip_tunnel hfcpci hfcmulti veth tun snd_rawmidi snd_seq_device snd_pcm_oss snd_pcm_dmaengine snd_pcm snd_timer snd_mixer_oss snd_hwdep snd_compress snd soundcore mISDN_dsp l1oip mISDN_core xfrm_user xfrm_ipcomp af_key xfrm_algo autofs4 br2684 atm nls_utf8 zram zsmalloc natsemi eeprom_93cx6 sha1_generic md5 kpp ghash_generic gf128mul gcm echainiv des_generic libdes deflate zlib_inflate zlib_deflate authenc crypto_acompress vfat fat nls_iso8859_1 nls_cp437 ahci libahci libata fsl_mph_dr_of ehci_platform ehci_fsl Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.834586] ehci_hcd gpio_button_hotplug tg3 b44 ssb ptp realtek pps_core Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.929100] CPU: 3 PID: 27970 Comm: kworker/u8:1 Tainted: G WC 5.4.106 #0 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.937183] Hardware name: Raspberry Pi 4 Model B Rev 1.2 (DT) Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.943105] Workqueue: mptcp_wq mptcp_sub_close_wq Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.947975] pstate: 80400005 (Nzcv daif +PAN -UAO) Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.952845] pc : module_put.part.61+0x48/0x50 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.957280] lr : module_put+0x10/0x20 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.961018] sp : ffffffc01153bcc0 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.964409] x29: ffffffc01153bcc0 x28: 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.969800] x27: ffffff80f26b72b8 x26: ffffffc0108a1bd0 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.975190] x25: 0000000000000000 x24: ffffff80f28b46c0 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.980579] x23: 0000000000000000 x22: ffffffc01070c69c Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.985969] x21: ffffff80ef4dd840 x20: 0000000000000007 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.991358] x19: ffffff80ef4dd7c0 x18: 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264144.996748] x17: 0000000000000000 x16: 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.002137] x15: 0000000000000000 x14: 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.007526] x13: 0000000000000000 x12: 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.012916] x11: 000000004c000e63 x10: 0000000000000800 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.018305] x9 : ffffff80f20b2f64 x8 : fefefefefefefeff Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.023695] x7 : 0000000000000010 x6 : 00000cf5d34856bb Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.029084] x5 : 0000000000000000 x4 : 0000000000000005 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.034473] x3 : 00000000ffffffff x2 : 0000000000000000 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.039863] x1 : 0000000000000000 x0 : ffffffc008ddc200 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.045253] Call trace: Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.047777] module_put.part.61+0x48/0x50 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.051867] tcp_cleanup_congestion_control+0x30/0x38 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.056996] tcp_v4_destroy_sock+0x40/0x110 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.061257] inet_csk_destroy_sock+0x50/0x110 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.065693] tcp_close+0x31c/0x580 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.069172] mptcp_sub_close_wq+0x74/0xe8 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.073260] process_one_work+0x1ec/0x378 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.077346] worker_thread+0x48/0x4d0 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.081089] kthread+0x120/0x128 Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.084395] ret_from_fork+0x10/0x1c Wed Sep 15 21:25:35 2021 kern.warn kernel: [264145.088047] ---[ end trace 5d7fd9bc74f67f97 ]---