#22045: system hangs when reconnecting pppoe-wan multiple times
------------------------+------------------------
Reporter: anonymous | Owner: developers
Type: defect | Status: new
Priority: normal | Milestone:
Component: packages | Version: Trunk
Resolution: | Keywords:
------------------------+------------------------
Comment (by anonymous):
There's no page allocation failure anymore.
more output goes:
{{{
root@OpenWrt:~# free -m
total used free shared buffers cached
Mem: 248 59 188 0 4 14
-/+ buffers/cache: 40 208
Swap: 0 0 0
root@OpenWrt:~# ifup -w wan
+ ifup_all=
+ setup_wifi=
+ modes=down up
+ setup_wifi=1
+ :
+ setup_wifi=
+ shift
+ :
+ break
+ [ down up = down up ]
+ ubus call network reload
+ [ -n ]
+ ubus -S list network.interface.wan
+ if_call wan
+ local interface=wan
+ ubus call network.interface down { "interface" : "wan" }
+ ubus call network.interface up { "interface" : "wan" }
+ [ -n ]
root@OpenWrt:~# ifup -w wan
+ ifup_all=
+ setup_wifi=
+ modes=down up
+ setup_wifi=1
+ :
+ setup_wifi=
+ shift
+ :
+ break
+ [ down up = down up ]
+ ubus call network reload
+ [ -n ]
+ ubus -S list network.interface.wan
+ if_call wan
+ local interface=wan
+ ubus call network.interface down { "interface" : "wan" }
+ ubus call network.interface up { "interface" : "wan" }
+ [ -n ]
root@OpenWrt:~# logread -f
Sun Mar 20 09:23:44 2016 authpriv.info dropbear[2526]: Child connection
from 192.168.1.121:53287
Sun Mar 20 09:23:45 2016 authpriv.notice dropbear[2526]: Password auth
succeeded for 'root' from 192.168.1.121:53287
Sun Mar 20 09:24:04 2016 cron.info crond[1385]: USER root pid 2534 cmd
/sbin/fan_ctrl.sh >/dev/null
Sun Mar 20 09:24:04 2016 daemon.info pppd[2071]: Terminating on signal 15
Sun Mar 20 09:24:04 2016 daemon.info pppd[2071]: Connect time 1.2 minutes.
Sun Mar 20 09:24:04 2016 daemon.info pppd[2071]: Sent 133197 bytes,
received 195248 bytes.
Sun Mar 20 09:24:04 2016 daemon.err miniupnpd[2306]: Failed to get IP for
interface pppoe-wan
Sun Mar 20 09:24:04 2016 daemon.warn miniupnpd[2306]:
SendNATPMPPublicAddressChangeNotification: cannot get public IP address,
stopping
Sun Mar 20 09:24:04 2016 daemon.notice netifd: Network device 'pppoe-wan'
link is down
Sun Mar 20 09:24:04 2016 daemon.notice pppd[2071]: Connection terminated.
Sun Mar 20 09:24:04 2016 daemon.info pppd[2071]: Sent PADT
Sun Mar 20 09:24:04 2016 daemon.info pppd[2071]: Exit.
Sun Mar 20 09:24:04 2016 daemon.notice netifd: Interface 'wan' is now down
Sun Mar 20 09:24:04 2016 kern.info kernel: [ 97.530278] IPv6:
ADDRCONF(NETDEV_UP): eth1: link is not ready
Sun Mar 20 09:24:04 2016 daemon.notice netifd: Interface 'wan' is disabled
Sun Mar 20 09:24:04 2016 daemon.notice netifd: Interface 'wan' is enabled
Sun Mar 20 09:24:04 2016 daemon.notice netifd: Interface 'wan' is setting
up now
Sun Mar 20 09:24:04 2016 daemon.notice netifd: Network device 'eth1' link
is down
Sun Mar 20 09:24:04 2016 daemon.notice netifd: Interface 'wan' has link
connectivity loss
Sun Mar 20 09:24:04 2016 daemon.notice netifd: wan (2588): Command failed:
Permission denied
Sun Mar 20 09:24:06 2016 daemon.notice netifd: Network device 'eth1' link
is up
Sun Mar 20 09:24:06 2016 daemon.notice netifd: Interface 'wan' has link
connectivity
Sun Mar 20 09:24:06 2016 daemon.notice netifd: Interface 'wan' is setting
up now
Sun Mar 20 09:24:06 2016 kern.info kernel: [ 99.549898] mvneta
f1074000.ethernet eth1: Link is Up - 1Gbps/Full - flow control off
Sun Mar 20 09:24:06 2016 daemon.info pppd[2618]: Plugin rp-pppoe.so
loaded.
Sun Mar 20 09:24:06 2016 daemon.info pppd[2618]: RP-PPPoE plugin version
3.8p compiled against pppd 2.4.7
Sun Mar 20 09:24:06 2016 daemon.notice pppd[2618]: pppd 2.4.7 started by
root, uid 0
Sun Mar 20 09:24:07 2016 daemon.info pppd[2618]: PPP session is 26850
Sun Mar 20 09:24:07 2016 daemon.warn pppd[2618]: Connected to
7c:a2:3e:ea:3b:23 via interface eth1
Sun Mar 20 09:24:07 2016 kern.info kernel: [ 99.874034] pppoe-wan:
renamed from ppp0
Sun Mar 20 09:24:07 2016 daemon.info pppd[2618]: Using interface pppoe-wan
Sun Mar 20 09:24:07 2016 daemon.notice pppd[2618]: Connect: pppoe-wan <-->
eth1
Sun Mar 20 09:24:10 2016 daemon.info pppd[2618]: Remote message:
Authentication success,Welcome!
Sun Mar 20 09:24:10 2016 daemon.notice pppd[2618]: PAP authentication
succeeded
Sun Mar 20 09:24:10 2016 daemon.notice pppd[2618]: peer from calling
number 7C:A2:3E:EA:3B:23 authorized
Sun Mar 20 09:24:10 2016 daemon.err miniupnpd[2306]: Failed to get IP for
interface pppoe-wan
Sun Mar 20 09:24:10 2016 daemon.warn miniupnpd[2306]:
SendNATPMPPublicAddressChangeNotification: cannot get public IP address,
stopping
Sun Mar 20 09:24:10 2016 daemon.err miniupnpd[2306]: Failed to get IP for
interface pppoe-wan
Sun Mar 20 09:24:10 2016 daemon.warn miniupnpd[2306]:
SendNATPMPPublicAddressChangeNotification: cannot get public IP address,
stopping
Sun Mar 20 09:24:10 2016 daemon.notice pppd[2618]: local IP address
xxx.xxx.xxx.xxx
Sun Mar 20 09:24:10 2016 daemon.notice pppd[2618]: remote IP address
xxx.xxx.xxx.xxx
Sun Mar 20 09:24:10 2016 daemon.notice pppd[2618]: primary DNS address
xxx.xxx.xxx.xxx
Sun Mar 20 09:24:10 2016 daemon.notice pppd[2618]: secondary DNS address
xxx.xxx.xxx.xxx
Sun Mar 20 09:24:10 2016 daemon.notice netifd: Network device 'pppoe-wan'
link is up
Sun Mar 20 09:24:10 2016 daemon.notice netifd: Interface 'wan' is now up
Sun Mar 20 09:24:10 2016 user.notice firewall: Reloading firewall due to
ifup of wan (pppoe-wan)
Sun Mar 20 09:24:11 2016 user.notice ddns-scripts[2753]: myddns_ipv4: PID
'2753' started at 2016-03-20 09:24
Sun Mar 20 09:24:11 2016 user.warn ddns-scripts[2753]: myddns_ipv4:
Service section disabled! - TERMINATE
Sun Mar 20 09:24:11 2016 user.warn ddns-scripts[2753]: myddns_ipv4: PID
'2753' exit WITH ERROR '1' at 2016-03-20 09:24
Sun Mar 20 09:24:37 2016 daemon.info pppd[2618]: Terminating on signal 15
Sun Mar 20 09:24:37 2016 daemon.info pppd[2618]: Connect time 0.5 minutes.
Sun Mar 20 09:24:37 2016 daemon.info pppd[2618]: Sent 571 bytes, received
691 bytes.
Sun Mar 20 09:24:37 2016 daemon.notice netifd: Network device 'pppoe-wan'
link is down
Sun Mar 20 09:24:37 2016 daemon.err miniupnpd[2306]: Failed to get IP for
interface pppoe-wan
Sun Mar 20 09:24:37 2016 daemon.warn miniupnpd[2306]:
SendNATPMPPublicAddressChangeNotification: cannot get public IP address,
stopping
Sun Mar 20 09:24:37 2016 daemon.notice pppd[2618]: Connection terminated.
Sun Mar 20 09:24:37 2016 daemon.info pppd[2618]: Sent PADT
Sun Mar 20 09:24:37 2016 daemon.info pppd[2618]: Exit.
Sun Mar 20 09:24:37 2016 daemon.notice netifd: Interface 'wan' is now down
Sun Mar 20 09:24:37 2016 kern.info kernel: [ 130.629290] IPv6:
ADDRCONF(NETDEV_UP): eth1: link is not ready
Sun Mar 20 09:24:37 2016 daemon.notice netifd: Interface 'wan' is disabled
Sun Mar 20 09:25:00 2016 cron.info crond[1385]: USER root pid 2847 cmd
/sbin/fan_ctrl.sh >/dev/null
Sun Mar 20 09:25:38 2016 kern.err kernel: [ 190.627416] INFO: rcu_sched
self-detected stall on CPU
Sun Mar 20 09:25:38 2016 kern.err kernel: [ 190.632625] 0-...:
(5999 ticks this GP) idle=675/140000000000001/0 softirq=10801/10801
fqs=5999
Sun Mar 20 09:25:38 2016 kern.err kernel: [ 190.641533] (t=6000
jiffies g=1323 c=1322 q=410)
Sun Mar 20 09:25:38 2016 kern.info kernel: [ 190.646369] Task dump for
CPU 0:
Sun Mar 20 09:25:38 2016 kern.info kernel: [ 190.649621] netifd
R running 0 1330 1 0x00000002
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.656034] Backtrace:
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.658540] [<c001dbe4>]
(dump_backtrace) from [<c001ddec>] (show_stack+0x18/0x1c)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.666138] r7:c051a4c0
r6:80000193 r5:c0522140 r4:ce1e2c00
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.671916] [<c001ddd4>]
(show_stack) from [<c004d1ac>] (sched_show_task+0xcc/0x100)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.679716] [<c004d0e0>]
(sched_show_task) from [<c004e790>] (dump_cpu_task+0x40/0x44)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.687675] r5:c0522140
r4:00000000
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.691316] [<c004e750>]
(dump_cpu_task) from [<c006f6e0>] (rcu_dump_cpu_stacks+0x80/0xb8)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.699621] r5:c0522140
r4:00000000
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.703261] [<c006f660>]
(rcu_dump_cpu_stacks) from [<c0072db4>] (rcu_check_callbacks+0x29c/0x754)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.712262] r9:c0522140
r8:c0522140 r7:c051a100 r6:0f8c0000 r5:c051a100 r4:c0516200
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.720146] [<c0072b18>]
(rcu_check_callbacks) from [<c00752d4>] (update_process_times+0x40/0x6c)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.729060] r10:c0544b20
r9:0000002c r8:63078c00 r7:c0544ba0 r6:cdba9d20 r5:00000000
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.737000] r4:ce1e2c00
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.739597] [<c0075294>]
(update_process_times) from [<c008581c>] (tick_sched_timer+0x250/0x2a4)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.748424] r5:cfdd3c80
r4:c051a3c8
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.752066] [<c00855cc>]
(tick_sched_timer) from [<c0076354>] (__hrtimer_run_queues+0x110/0x1dc)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.760893] r10:c051a4f0
r9:00000000 r8:cfdd3b00 r7:cfdd3ac0 r6:cfdd3b0c r5:c0513ac0
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.768847] r4:cfdd3c80
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.771423] [<c0076244>]
(__hrtimer_run_queues) from [<c00765bc>] (hrtimer_interrupt+0xd4/0x238)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.780249] r10:7fffffff
r9:00000003 r8:cfdd3b38 r7:cfdd3b58 r6:c051a3c8 r5:c0513ac0
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.788198] r4:cfdd3ac0
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.790781] [<c00764e8>]
(hrtimer_interrupt) from [<c029460c>]
(armada_370_xp_timer_interrupt+0x38/0x40)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.800305] r10:00000000
r9:00000000 r8:00000010 r7:cf80eb80 r6:c05261cc r5:cf805c00
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.808254] r4:cfdd8780
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.810840] [<c02945d4>]
(armada_370_xp_timer_interrupt) from [<c006a628>]
(handle_percpu_devid_irq+0x74/0x8c)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.820886] r5:cf805c00
r4:cfdd8780
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.824526] [<c006a5b4>]
(handle_percpu_devid_irq) from [<c00660f0>] (generic_handle_irq+0x2c/0x3c)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.833614] r9:00000000
r8:cf808000 r7:00000001 r6:00000000 r5:00000000 r4:c0513fd4
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.841495] [<c00660c4>]
(generic_handle_irq) from [<c0066408>] (__handle_domain_irq+0xa0/0xb4)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.850250] [<c0066368>]
(__handle_domain_irq) from [<c00094d8>]
(armada_370_xp_handle_irq+0x54/0xb8)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.859514] r9:00000000
r8:c055cda0 r7:c055cd88 r6:cdba9d20 r5:c055cda0 r4:c0045510
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.867382] [<c0009484>]
(armada_370_xp_handle_irq) from [<c000a6b4>] (__irq_svc+0x54/0x70)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.875777] Exception
stack(0xcdba9d20 to 0xcdba9d68)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.880878] 9d20: 00000000
cf83eca0 00000000 cf83eca0 c0520260 cf83eca0 00000000 c04a9dcc
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.889112] 9d40: cf83e830
00000000 00000000 cdba9d7c cf83eca4 cdba9d70 c002a584 c0045510
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.897324] 9d60: a0000013
ffffffff
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.900839] r10:00000000
r9:00000000 r8:cf83e830 r7:cdba9d54 r6:ffffffff r5:a0000013
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.908794] r4:c0045510
r3:c002a584
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.912432] [<c00454f4>]
(raw_notifier_chain_register) from [<c002a584>]
(register_cpu_notifier+0x30/0x44)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.922151] [<c002a554>]
(register_cpu_notifier) from [<c0283784>] (mvneta_open+0xb8/0x18c)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.930545] r5:cf83ec80
r4:cf83e800
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.934184] [<c02836cc>]
(mvneta_open) from [<c02c03f0>] (__dev_open+0xa4/0x134)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.941617] r6:c051a3c8
r5:00000001 r4:cf83e800
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.946314] [<c02c034c>]
(__dev_open) from [<c02c0708>] (__dev_change_flags+0xb8/0x148)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.954356] r8:00000000
r7:00001002 r6:00001003 r5:00000001 r4:cf83e800
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.961189] [<c02c0650>]
(__dev_change_flags) from [<c02c07b8>] (dev_change_flags+0x20/0x50)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.969666] r9:00000000
r8:00000000 r7:00001002 r6:cf83e800 r5:cf83e940 r4:cf83e800
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.977544] [<c02c0798>]
(dev_change_flags) from [<c02d67b4>] (dev_ifsioc+0xe8/0x34c)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.985406] r9:00000000
r8:c04a9dcc r7:cdba9e8c r6:cf83e800 r5:00008914 r4:c051a3c8
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 190.993282] [<c02d66cc>]
(dev_ifsioc) from [<c02d729c>] (dev_ioctl+0x808/0x8d4)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 191.000630] r9:00000000
r8:00000000 r7:be92cc34 r6:c051a3c8 r5:c0535ec0 r4:00008914
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 191.008509] [<c02d6a94>]
(dev_ioctl) from [<c02a47f8>] (sock_ioctl+0x2e4/0x2f8)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 191.015848] r10:00000000
r9:00000005 r8:cf4b56a0 r7:cddeac00 r6:be92cc34 r5:fffffdfd
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 191.023799] r4:00008914
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 191.026376] [<c02a4514>]
(sock_ioctl) from [<c00e6850>] (do_vfs_ioctl+0x6e0/0x744)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 191.033984] r7:cddeac00
r6:cddeac00 r5:be92cc34 r4:c051a3c8
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 191.039756] [<c00e6170>]
(do_vfs_ioctl) from [<c00e68f0>] (SyS_ioctl+0x3c/0x64)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 191.047094] r9:cdba8000
r8:be92cc34 r7:00008914 r6:cddeac00 r5:00000005 r4:cddeac00
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 191.054971] [<c00e68b4>]
(SyS_ioctl) from [<c0009d40>] (ret_fast_syscall+0x0/0x3c)
Sun Mar 20 09:25:38 2016 kern.warn kernel: [ 191.062578] r9:cdba8000
r8:c0009f04 r7:00000036 r6:0003b418 r5:0003b440 r4:b6f1f1bc
}}}
--
Ticket URL: <https://dev.openwrt.org/ticket/22045#comment:6>
OpenWrt <http://openwrt.org>
Opensource Wireless Router Technology
_______________________________________________
openwrt-tickets mailing list
[email protected]
https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-tickets
