Modify

Opened 4 years ago

Last modified 3 years ago

#15675 new defect

procd crash on usb remove

Reported by: nunojpg Owned by: developers
Priority: normal Milestone: Chaos Calmer 15.05
Component: base system Version: Trunk
Keywords: Cc:

Description

r40555
TL-WR703N

Wed Apr 23 10:38:47 2014 kern.info kernel: [ 88.190000] usb 1-1: USB disconnect, device number 2
Wed Apr 23 10:38:47 2014 user.emerg syslog: {{"ACTION":"remove","DEVPATH":"\/devices\/platform\/ehci-platform\/usb1\/1-1\/dvb\/dvb0.frontend0","SUBSYSTEM":"dvb","MAJOR":"212","MINOR":"3","DEVNAME":"dvb\/adapter0\/frontend0","DVB_ADAPTER_NUM":"0","DVB_DEVICE_TYPE":"frontend","DVB_DEVICE_NUM":"0","SEQNUM":"371"}}
Wed Apr 23 10:38:47 2014 kern.info kernel: [ 88.210000] r820t 0-001a: destroying instance
Wed Apr 23 10:38:47 2014 kern.info kernel: [ 88.210000] usb 1-1: dvb_usb_v2: 'TerraTec NOXON DAB Stick' successfully deinitialized and disconnected
Wed Apr 23 10:38:47 2014 user.emerg syslog: Command: rm
Wed Apr 23 10:38:47 2014 user.emerg syslog: /dev/dvb/adapter0/frontend0
Wed Apr 23 10:38:47 2014 user.emerg syslog:
Wed Apr 23 10:38:47 2014 user.emerg syslog: Message:
Wed Apr 23 10:38:47 2014 user.emerg syslog: ACTION=remove
Wed Apr 23 10:38:47 2014 user.emerg syslog: DEVPATH=/devices/platform/ehci-platform/usb1/1-1/dvb/dvb0.frontend0
Wed Apr 23 10:38:47 2014 user.emerg syslog: SUBSYSTEM=dvb
Wed Apr 23 10:38:47 2014 user.emerg syslog: MINOR=3
Wed Apr 23 10:38:47 2014 kern.emerg 2
Wed Apr 23 10:38:47 2014 kern.emerg ��s
Wed Apr 23 10:38:47 2014 kern.emerg pr 23 10:38:47 syslog: MINOR=3
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] Unhandled kernel unaligned access#1:
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] CPU: 0 PID: 1 Comm: procd Not tainted 3.10.36 #1
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] task: 81828000 ti: 81826000 task.ti: 81826000
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] $ 0 : 00000000 0040869a 3c383e41 3c383e41
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] $ 4 : 81802e80 000106d0 81042030 00003139
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] $ 8 : 000019be 800648fc 801f1b5c fffffffc
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] $12 : 00000025 00000002 00000007 00000002
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] $16 : 81802e80 000106d0 81827c80 801f8c5c
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] $20 : 00000100 000004d0 0000003b 80093754
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] $24 : 00000000 76f68750
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] $28 : 81826000 81827c28 00000000 800e2b88
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] Hi : 00000008
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] Lo : 00000000
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] epc : 800e2bc4 kmalloc_track_caller+0x94/0x13c
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] Not tainted
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] ra : 800e2b88
kmalloc_track_caller+0x58/0x13c
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] Status: 1000dc03 KERNEL EXL IE
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] Cause : 00800010
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] BadVA : 3c383e41
Wed Apr 23 10:38:47 2014 kern.warn kernel: [ 88.230000] PrId : 00019374 (MIPS 24Kc)

<6>[ 88.190000] usb 1-1: USB disconnect, device number 2
<6>[ 88.210000] r820t 0-001a: destroying instance
<6>[ 88.210000] usb 1-1: dvb_usb_v2: 'TerraTec NOXON DAB Stick' successfully deinitialized and disconnected
<4>[ 88.230000] Unhandled kernel unaligned access#1:
<4>[ 88.230000] CPU: 0 PID: 1 Comm: procd Not tainted 3.10.36 #1
<4>[ 88.230000] task: 81828000 ti: 81826000 task.ti: 81826000
<4>[ 88.230000] $ 0 : 00000000 0040869a 3c383e41 3c383e41
<4>[ 88.230000] $ 4 : 81802e80 000106d0 81042030 00003139
<4>[ 88.230000] $ 8 : 000019be 800648fc 801f1b5c fffffffc
<4>[ 88.230000] $12 : 00000025 00000002 00000007 00000002
<4>[ 88.230000] $16 : 81802e80 000106d0 81827c80 801f8c5c
<4>[ 88.230000] $20 : 00000100 000004d0 0000003b 80093754
<4>[ 88.230000] $24 : 00000000 76f68750
<4>[ 88.230000] $28 : 81826000 81827c28 00000000 800e2b88
<4>[ 88.230000] Hi : 00000008
<4>[ 88.230000] Lo : 00000000
<4>[ 88.230000] epc : 800e2bc4 kmalloc_track_caller+0x94/0x13c
<4>[ 88.230000] Not tainted
<4>[ 88.230000] ra : 800e2b88
kmalloc_track_caller+0x58/0x13c
<4>[ 88.230000] Status: 1000dc03 KERNEL EXL IE
<4>[ 88.230000] Cause : 00800010
<4>[ 88.230000] BadVA : 3c383e41
<4>[ 88.230000] PrId : 00019374 (MIPS 24Kc)
<4>[ 88.230000] Modules linked in: ath9k ath9k_common pppoe ppp_async iptable_nat ath9k_hw ath pppox ppp_generic nf_nat_ipv4 nf_conntrack_ipv4 mac80211 ipt_MASQUERADE dvb_usb_rtl28xxu cfg80211 xt_time xt_tcpudp xt_state xt_nat xt_multiport xt_mark xt_mac xt_limit xt_conntrack xt_comment xt_TCPMSS xt_REDIRECT xt_LOG xt_CT uvcvideo slhc rtl2832 rtl2830 r820t nf_nat_irc nf_nat_ftp nf_nat nf_defrag_ipv4 nf_conntrack_irc nf_conntrack_ftp iptable_raw iptable_mangle iptable_filter ipt_REJECT ip_tables input_core dvb_usb_v2 dvb_core crc_ccitt compat videobuf2_vmalloc videobuf2_memops videobuf2_core v4l2_common videodev ip6t_REJECT ip6t_rt ip6t_hbh ip6t_mh ip6t_ipv6header ip6t_frag ip6t_eui64 ip6t_ah ip6table_raw ip6table_mangle ip6table_filter ip6_tables x_tables nf_conntrack_ipv6 nf_conntrack nf_defrag_ipv6 ipv6 arc4 crypto_blkcipher ehci_platform ehci_hcd gpio_button_hotplug usbcore nls_base usb_common
<4>[ 88.230000] Process procd (pid: 1, threadinfo=81826000, task=81828000, tls=77029440)
<4>[ 88.230000] Stack : 80383840 80f90c00 00000000 80f90000 00000001 80f87780 000004d0 801f8b70
<4>[ 88.230000] 00000001 0000001f 00000027 81827eb0 80f87780 000004d0 81802780 0000005a
<4>[ 88.230000] 00000000 801f8c5c 00000000 7fa2da78 80314920 80f8fff4 00000001 8009a07c
<4>[ 88.230000] 8087c700 00000000 ffffffff 00000000 00000000 801f3410 00000001 80f87cc0
<4>[ 88.230000] 00000024 81827ec0 81827dd8 8009a2ec 81827ec8 00000040 00000006 00000000
<4>[ 88.230000] ...
<4>[ 88.230000] Call Trace:
<4>[ 88.230000] [<800e2bc4>] kmalloc_track_caller+0x94/0x13c
<4>[ 88.230000] [<801f8b70>]
kmalloc_reserve.isra.35+0x40/0xa4
<4>[ 88.230000] [<801f8c5c>] alloc_skb+0x88/0x174
<4>[ 88.230000] [<801f3410>] sock_alloc_send_pskb+0xdc/0x394
<4>[ 88.230000] [<802728e0>] unix_dgram_sendmsg+0x1a0/0x520
<4>[ 88.230000] [<801ef428>] sock_sendmsg+0x78/0xa0
<4>[ 88.230000] [<801f1b1c>] SyS_sendto+0xd0/0x110
<4>[ 88.230000] [<801f1b74>] SyS_send+0x18/0x24
<4>[ 88.230000] [<800625b0>] stack_done+0x20/0x44
<4>[ 88.230000]
<4>[ 88.230000]
<4>[ 88.230000] Code: 32238000 8e030014 00431821 <8c640000> 41676000 30e70001 000000c0 8e030000 8c660000
<4>[ 88.500000] ---[ end trace ac48cba55eeadbfc ]---

===================================
Time: 1398249527.855281
<4>[ 88.760000] ---[ end trace ac48cba55eeadbfd ]---

===================================
Time: 1398249528.196457

Attachments (0)

Change History (2)

comment:1 Changed 4 years ago by nunojpg

Issue confirmed again at r40864.

comment:2 Changed 3 years ago by nunojpg

With procd set to debug mode:

[   21.670000] eth0: link up (100Mbps/Full duplex)
[   21.670000] br-lan: port 1(eth0) entered forwarding state
[   21.680000] br-lan: port 1(eth0) entered forwarding state
[   21.680000] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   21.690000] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   23.680000] br-lan: port 1(eth0) entered forwarding state
procd: service_handle_set(242): Create service dnsmasq
procd: service_instance_update(66): Create instance dnsmasq::instance1
procd: instance_start(201): Started instance dnsmasq::instance1
procd: _rc(132): running /etc/init.d/dnsmasq running
procd: q_initd_run(68): start /etc/init.d/dnsmasq running 
procd: q_initd_complete(98): stop /etc/init.d/dnsmasq running 
procd: service_handle_set(238): Update service dnsmasq
procd: service_instance_update(58): Update instance dnsmasq::instance1
procd: _rc(132): running /etc/init.d/dnsmasq running
procd: q_initd_run(68): start /etc/init.d/dnsmasq running 
procd: q_initd_complete(98): stop /etc/init.d/dnsmasq running 
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping

/*
USB DEVICE INSERTION
*/

[  106.730000] usb 1-1: new high-speed USB device number 2 using ehci-platform
procd: hotplug_handler_debug(404): {{"ACTION":"add","DEVPATH":"\/devices\/platform\/ehci-platform\/usb1\/1-1","SUBSYSTEM":"usb","MAJOR":"189","MINOR":"1","DEVNAME":"bu[  106.900000] usb 1-1: dvb_usb_v2: found a 'TerraTec NOXON DAB Stick' in warm state
s\/usb\/001\/002","DEVTYPE":"usb_device","PRODUCT":"ccd\/b3\/100","TYPE":"0\/0\/0","BUSNUM":"001","DEVNUM":"002","SEQNUM":"364"}}
procd: rule_handle_command(355): Command: makedevprocd: rule_handle_command(357):  /dev/bus/usb/001/002procd: rule_handle_command(357):  0644procd: rule_handle_command(358): 
procd: rule_handle_command(360): Message:procd: rule_handle_command(362):  ACTION=addprocd: rule_handle_command(362):  DEVPATH=/devices/platform/ehci-platform/usb1/1-1procd: rule_handle_command(362):  SUBSYSTEM=usbprocd: rule_handle_command(362):  MAJOR=189procd: rule_handle_command(362):  MINOR=1procd: rule_handle_command(362):  DEVN}}
procd: rule_handle_command(355): Command: execprocd: rule_handle_command(357):  /sbin/hotplug-callprocd: rule_handle_command(357):  usbprocd: rule_handle_command(358): 
procd: rule_handle_command(360): Message:procd: rule_handle_command(362):  ACTION=addprocd: rule_handle_command(362):  DEVPATH=/devices/platform/ehci-platform/usb1/1-1/1-1:1.0procd: rule_handle_command(362):  SUBSYSTEM=usbprocd: rule_handle_command(362):  DEVTYPE=usb_interfaceprocd: rule_handle_command(362):  PRODUCT=ccd/b3/100procd: rule_handle_command(362):  TYPE=0/0/0procd: rule_handle_command(362):  INTERFACE=255/255/255procd: rule_handle_command(362):  MODALIAS=usb:v0CCDp00B3d0100dc00dsc00dp00icFFiscFFipFFin00procd: rule_handle_command(362):  SEQNUM=365procd: rule_handle_command(363): 
procd: queue_proc_cb(286): Finished hotplug exec instance, pid=908
procd: queue_next(281): Launched hotplug exec instance, pid=910
procd: queue_proc_cb(286): Finished hotplug exec instance, pid=910
procd: hotplug_handler_debug(404): {{"ACTION":"add","DEVPATH":"\/devices\/platform\/ehci-platform\/usb1\/1-1\/i2c-0","SUBSYSTEM":"i2c","SEQNUM":"366"}}
procd: hotplug_handler_debug(404): {{"ACTION":"add","DEVPATH":"\/devices\/platform\/ehci-platform\/usb1\/1-1\/dvb\/dvb0.demux0","SUBSYSTEM":"dvb","MAJOR":"212","MINOR":"4","DEVNAME":"dvb\/adapter0\/demux0","DVB_ADAPTER_NUM":"0","DVB_DEVICE_TYPE":"demux","DVB_DEVICE_NUM":"0","SEQNUM":"367"}}
procd: rule_handle_command(355): Command: makedevprocd: rule_handle_command(357):  /dev/dvb/adapter0/demux0procd: rule_handle_command(357):  0644procd: rule_handle_command(358): 
procd: rule_handle_command(360): Message:procd: rule_handle_command(362):  ACTION=addprocd: rule_handle_command(362):  DEVPATH=/devices/platform/ehci-platform/usb1/1-1/dvb/dvb0.demux0procd: rule_handle_command(362):  SUBSYSTEM=dvbprocd: rule_handle_command(362):  MAJOR=212procd: rule_handle_command(362):  MINOR=4procd: rule_handle_command(362):  DEVNAME=dvb/adapter0/demux0procd: rule_handle_command(362):  DVB_ADAPTER_NUM=0procd: rule_handle_command(362):  DVB_DEVICE_TYPE=demuxprocd: rule_handle_command(362):  DVB_DEVICE_NUM=0procd: rule_handle_command(362):  SEQNUM=367procd: rule_handle_command(363): 
procd: hotplug_handler_debug(404): {{"ACTION":"add","DEVPATH":"\/devices\/platform\/ehci-platform\/usb1\/1-1\/dvb\/dvb0.dvr0","SUBSYSTEM":"dvb","MAJOR":"212","MINOR":"5","DEVNAME":"dvb\/adapter0\/dvr0","DVB_ADAPTER_NUM":"0","DVB_DEVICE_TYPE":"dvr","DVB_DEVICE_NUM":"0","SEQNUM":"368"}}
procd: rule_handle_command(355): Command: makedevprocd: rule_handle_command(357):  /dev/dvb/adapter0/dvr0procd: rule_handle_command(357):  0644procd: rule_handle_command(358): 
procd: rule_handle_command(360): Message:procd: rule_handle_command(362):  ACTION=addprocd: rule_handle_command(362):  DEVPATH=/devices/platform/ehci-platform/usb1/1-1/dvb/dvb0.dvr0procd: rule_handle_command(362):  SUBSYSTEM=dvbprocd: rule_handle_command(362):  MAJOR=212procd: rule_handle_command(362):  MINOR=5procd: rule_handle_command(362):  DEVNAME=dvb/adapter0/dvr0procd: rule_handle_command(362):  DVB_ADAPTER_NUM=0procd: rule_handle_command(362):  DVB_DEVICE_TYPE=dvrprocd: rule_handle_command(362):  DVB_DEVICE_NUM=0procd: rule_handle_command(362):  SEQNUM=368procd: rule_handle_command(363): 
procd: hotplug_handler_debug(404): {{"ACTION":"add","DEVPATH":"\/devices\/platform\/ehci-platform\/usb1\/1-1\/dvb\/dvb0.frontend0","SUBSYSTEM":"dvb","MAJOR":"212","MINOR":"3","DEVNAME":"dvb\/adapter0\/frontend0","DVB_ADAPTER_NUM":"0","DVB_DEVICE_TYPE":"frontend","DVB_DEVICE_NUM":"0","SEQNUM":"369"}}
procd: rule_handle_command(355): Command: makedevprocd: rule_handle_command(357):  /dev/dvb/adapter0/frontend0procd: rule_handle_command(357):  0644procd: rule_handle_command(358): 
procd: rule_handle_command(360): Message:procd: rule_handle_command(362):  ACTION=addprocd: rule_handle_command(362):  DEVPATH=/devices/platform/ehci-platform/usb1/1-1/dvb/dvb0.frontend0procd: rule_handle_command(362):  SUBSYSTEM=dvbprocd: rule_handle_command(362):  MAJOR=212procd: rule_handle_command(362):  MINOR=3procd: rule_handle_command(362):  DEVNAME=dvb/adapter0/frontend0procd: rule_handle_command(362):  DVB_ADAPTER_NUM=0procd: rule_handle_command(362):  DVB_DEVICE_TYPE=frontendprocd: rule_handle_command(362):  DVB_DEVICE_NUM=0procd: rule_handle_command(362):  SEQNUM=369procd: rule_handle_command(363): 
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping
procd: watchdog_timeout_cb(37): Ping

/*
USB DEVICE REMOVAL
*/

[  128.770000] usb 1-1: USB disconnect, device number 2
procd: hotplug_handler_debug(404): {{"ACTION":"remove",[  128.780000] r820t 0-001a: destroying instance
"DEVPATH":"\/devices\/platform\/[  128.790000] Unhandled kernel unaligned access[#1]:
[  128.790000] CPU: 0 PID: 1 Comm: procd Not tainted 3.10.49 #13
[  128.790000] task: 81828000 ti: 81826000 task.ti: 81826000
[  128.790000] $ 0   : 00000000 0040953c 3c383e53 3c383e53
[  128.790000] $ 4   : 81802e80 000106d0 81042030 00001e17
[  128.790000] $ 8   : 00001a7b 800648fc 8007a218 fffffffc
[  128.790000] $12   : 00000025 00000002 00000007 00000002
[  128.790000] $16   : 81802e80 000106d0 81827c80 80081c88
[  128.790000] $20   : 000000e0 000004d0 0000001d 800c525c
[  128.790000] $24   : 00000000 77407690                  
[  128.790000] $28   : 81826000 81827c28 00000000 80098340
[  128.790000] Hi    : 00000008
[  128.790000] Lo    : 00000000
[  128.790000] epc   : 8009837c __kmalloc_track_caller+0x94/0x13c
[  128.790000]     Not tainted
[  128.790000] ra    : 80098340 __kmalloc_track_caller+0x58/0x13c
[  128.790000] Status: 1000fc03	KERNEL EXL IE 
[  128.790000] Cause : 00800010
[  128.790000] BadVA : 3c383e53
[  128.790000] PrId  : 00019374 (MIPS 24Kc)
[  128.790000] Modules linked in: ath970000] usb 1-1: dvb_usb_v2: 'TerraTec NOXON DAB Stick' successfully deinitialized and disconnected
er0\/frontend0","DVB_ADAPTER_NUM[  129.080000] ath79_wdt: device closed unexpectedly, watchdog timer will not stop!
":"0","DVB_DEVIC[  129.090000] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b
[  129.090000] 
[  129.090000] Rebooting in 3 seconds..ÿ

U-Boot 1.1.5 (Apr  6 2009 - 13:54:11)

Add Comment

Modify Ticket

Action
as new .
Author


E-mail address and user name can be saved in the Preferences.

 
Note: See TracTickets for help on using tickets.