Modify

Opened 4 years ago

Last modified 4 years ago

#14521 new defect

netifd / endless loop during startup

Reported by: bittorf@… Owned by: developers
Priority: normal Milestone: Barrier Breaker 14.07
Component: base system Version: Trunk
Keywords: netifd Cc:

Description

since a while it can occour during bootup,
that the script /etc/init.d/network will never
end. i have logs from r37801, r38043 and r38885.

login via LAN is possible. seems it is caused by
the 'wifi down' call.

messages in klog are e.g.
unregister_netdevice: waiting for wlan0 to become free. Usage count = 1

Attachments (0)

Change History (21)

comment:1 Changed 4 years ago by bittorf@…

its not possible to attach files to a ticket. message is:

Oops …
Trac hat einen internen Fehler festgestellt:
IndexError: pop from empty list
There was an internal error in Trac.

Die Aktion, die den Fehler ausgelöst hat, war:

GET: /attachment/ticket/14521/

comment:3 Changed 4 years ago by bittorf@…

important: a simple reboot makes the router working again.

comment:4 Changed 4 years ago by anonymous

the same can happen during shutdown: endless loop with high load:
http://intercity-vpn.de/files/openwrt/r37801-fehler_beim_runterfahren.txt

comment:5 Changed 4 years ago by bittorf@…

here during startup, both log above are from TP-LINK TL-WR1043ND,
this is a Buffalo WZR-HP-AG300H

http://intercity-vpn.de/files/openwrt/highload_unregister_netdevice.txt

comment:6 Changed 4 years ago by bittorf@…

here again from a TP-LINK TL-WR1043ND with r38993, output of top during this race/loop:

Mem: 26908K used, 2112K free, 0K shrd, 2684K buff, 10428K cached
CPU:   0% usr   0% sys   0% nic  99% idle   0% io   0% irq   0% sirq
Load average: 3.00 2.72 1.60 2/39 3243
  PID  PPID USER     STAT   VSZ %VSZ %CPU COMMAND
 3243  3238 root     R     1496   5%   0% top
 2571     1 root     S     1664   6%   0% /usr/sbin/uhttpd -f -h /www -r EG-lab
 1723  1498 root     S     1612   6%   0% {mac80211.sh} /bin/sh ./mac80211.sh m
 1498     1 root     D     1540   5%   0% /sbin/netifd
 3197  2620 root     S     1540   5%   0% /bin/sh -c . /tmp/CRON_IS_CAREFUL
 3238  3237 root     S     1516   5%   0% -ash
 2620     1 root     S     1508   5%   0% /usr/sbin/crond -L /dev/null
 3236  3197 root     S     1484   5%   0% sleep 90
    1     0 root     S     1344   5%   0% /sbin/procd
 1475     1 root     S     1308   5%   0% /sbin/logd
 2743     1 nobody   S     1208   4%   0% /usr/sbin/dnsmasq -C /var/etc/dnsmasq
 3237  2538 root     S     1204   4%   0% /usr/sbin/dropbear -F -P /var/run/dro
 2538     1 root     S     1160   4%   0% /usr/sbin/dropbear -F -P /var/run/dro
 2296  1723 root     D     1116   4%   0% iw dev wlan0 del
 2747  2743 root     S     1024   4%   0% /usr/sbin/dnsmasq -C /var/etc/dnsmasq
  379     1 root     S      888   3%   0% /sbin/ubusd
    4     2 root     SW       0   0%   0% [kworker/0:0]
    8     2 root     SW       0   0%   0% [kworker/u2:1]
    6     2 root     SW       0   0%   0% [kworker/u2:0]
^C744     2 root     SW       0   0%   0% [kworker/0:1]

dmesg:

[    0.000000] Linux version 3.10.21 (bastian@gcc20) (gcc version 4.6.4 (OpenWrt/Linaro GCC 4.6-2013.05 r38896) ) #9 Tue Dec 3 19:54:50 CET 2013
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU revision is: 00019374 (MIPS 24Kc)
[    0.000000] SoC: Atheros AR9132 rev 2
[    0.000000] Clocks: CPU:400.000MHz, DDR:400.000MHz, AHB:200.000MHz, Ref:5.000MHz
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 02000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x00000000-0x01ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x00000000-0x01ffffff]
[    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 8128
[    0.000000] Kernel command line:  board=TL-WR1043ND  oops=panic panic=10  console=ttyS0,115200 rootfstype=squashfs,jffs2 noinitrd
[    0.000000] PID hash table entries: 128 (order: -3, 512 bytes)
[    0.000000] Dentry cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Inode-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 28784k/32768k available (2241k kernel code, 3984k reserved, 593k data, 236k init, 0k highmem)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS:51
[    0.000000] Calibrating delay loop... 265.42 BogoMIPS (lpj=1327104)
[    0.080000] pid_max: default: 32768 minimum: 301
[    0.080000] Mount-cache hash table entries: 512
[    0.090000] NET: Registered protocol family 16
[    0.100000] MIPS: machine is TP-LINK TL-WR1043ND
[    0.360000] bio: create slab <bio-0> at 0
[    0.370000] Switching to clocksource MIPS
[    0.380000] NET: Registered protocol family 2
[    0.380000] TCP established hash table entries: 512 (order: 0, 4096 bytes)
[    0.380000] TCP bind hash table entries: 512 (order: -1, 2048 bytes)
[    0.390000] TCP: Hash tables configured (established 512 bind 512)
[    0.390000] TCP: reno registered
[    0.400000] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.400000] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.410000] NET: Registered protocol family 1
[    0.430000] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.440000] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.450000] msgmni has been set to 56
[    0.450000] io scheduler noop registered
[    0.450000] io scheduler deadline registered (default)
[    0.460000] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.490000] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11) is a 16550A
[    0.490000] console [ttyS0] enabled, bootconsole disabled
[    0.490000] console [ttyS0] enabled, bootconsole disabled
[    0.510000] ath79-spi ath79-spi: master is unqueued, this is deprecated
[    0.520000] m25p80 spi0.0: found s25sl064p, expected m25p80
[    0.520000] m25p80 spi0.0: s25sl064p (8192 Kbytes)
[    0.530000] 5 tp-link partitions found on MTD device spi0.0
[    0.530000] Creating 5 MTD partitions on "spi0.0":
[    0.540000] 0x000000000000-0x000000020000 : "u-boot"
[    0.540000] 0x000000020000-0x00000011f000 : "kernel"
[    0.550000] mtd: partition "kernel" must either start or end on erase block boundary or be smaller than an erase block -- forcing read-only
[    0.560000] 0x00000011f000-0x0000007f0000 : "rootfs"
[    0.570000] mtd: partition "rootfs" must either start or end on erase block boundary or be smaller than an erase block -- forcing read-only
[    0.580000] mtd: device 2 (rootfs) set to be root filesystem
[    0.590000] 1 squashfs-split partitions found on MTD device rootfs
[    0.590000] 0x000000440000-0x0000007f0000 : "rootfs_data"
[    0.600000] 0x0000007f0000-0x000000800000 : "art"
[    0.610000] 0x000000020000-0x0000007f0000 : "firmware"
[    0.620000] Realtek RTL8366RB ethernet switch driver version 0.2.4
[    0.740000] rtl8366rb rtl8366rb: using GPIO pins 18 (SDA) and 19 (SCK)
[    0.740000] rtl8366rb rtl8366rb: RTL5937 ver. 2 chip found
[    0.930000] libphy: rtl8366rb: probed
[    1.230000] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
[    1.240000] TCP: cubic registered
[    1.240000] NET: Registered protocol family 17
[    1.250000] Bridge firewalling registered
[    1.250000] 8021q: 802.1Q VLAN Support v1.8
[    1.260000] VFS: Mounted root (squashfs filesystem) readonly on device 31:2.
[    1.270000] Freeing unused kernel memory: 236K (80325000 - 80360000)
procd: Console is alive
procd: - watchdog -
procd: - preinit -
[    3.780000] eth0: link up (1000Mbps/Full duplex)
Press the [f] key and hit [enter] to enter failsafe mode
Press the [1], [2], [3] or [4] key and hit [enter] to select the debug level
[    5.180000] usbcore: registered new interface driver usbfs
[    5.190000] usbcore: registered new interface driver hub
[    5.190000] usbcore: registered new device driver usb
[    5.200000] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    5.210000] ehci-platform: EHCI generic platform driver
[    5.220000] ehci-platform ehci-platform: EHCI Host Controller
[    5.220000] ehci-platform ehci-platform: new USB bus registered, assigned bus number 1
[    5.230000] ehci-platform ehci-platform: irq 3, io mem 0x1b000000
[    5.260000] ehci-platform ehci-platform: USB 2.0 started, EHCI 1.00
[    5.260000] hub 1-0:1.0: USB hub found
[    5.270000] hub 1-0:1.0: 1 port detected
kmod: ran 1 iterations
[    7.300000] jffs2: notice: (311) jffs2_build_xattr_subsystem: complete building xattr subsystem, 1 of xdatum (1 unchecked, 0 orphan) and 15 of xref (0 dead,
 0 orphan) found.
mount_root: jffs2 is ready
[    7.370000] jffs2: notice: (308) jffs2_build_xattr_subsystem: complete building xattr subsystem, 1 of xdatum (1 unchecked, 0 orphan) and 15 of xref (0 dead,
 0 orphan) found.
[    7.410000] eth0: link down
procd: - early -
procd: - watchdog -
procd: - ubus -
procd: - init -
ALERT: overload_########################### - 2.47 - up: 007291
ALERT: overload_############################ - 2.84 - up: 007368
ALERT: overload_############################# - 3.59 - up: 007415
Dec 05 06:52:56 ' [up 7585s 5132288 bytes free] /usr/sbin/cron.monitoring [daemon.alert] heartbeat() empty wifimac, rebuilding netparam
Thu Jan  1 01:00:08 CET 1970 [ERROR] reboot without reason/crash
[    8.810000] zram: Created 1 device(s) ...
[    9.880000] Adding 14332k swap on /dev/zram0.  Priority:0 extents:1 across:14332k SS
[   18.980000] NET: Registered protocol family 10
[   18.990000] tun: Universal TUN/TAP device driver, 1.6
[   18.990000] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[   19.010000] nf_conntrack version 0.5.0 (453 buckets, 1812 max)
[   19.020000] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   19.050000] batman_adv: B.A.T.M.A.N. advanced 2013.4.0 (compatibility version 14) loaded
[   19.070000] u32 classifier
[   19.080000]     input device check on
[   19.080000]     Actions configured
[   19.090000] Mirror/redirect action on
[   19.090000] Failed to load ipt action
[   19.120000] Loading modules backported from Linux version master-2013-11-05-0-gafa3093
[   19.130000] Backport generated by backports.git backports-20130802-0-gdb67a3f
[   19.180000] Ebtables v2.0 registered
[   19.190000] ip_tables: (C) 2000-2006 Netfilter Core Team
[   19.280000] usbcore: registered new interface driver usblp
[   19.340000] xt_time: kernel timezone is -0000
[   19.390000] cfg80211: Calling CRDA to update world regulatory domain
[   19.390000] cfg80211: World regulatory domain updated:
[   19.400000] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[   19.410000] cfg80211:   (2400000 KHz - 2494000 KHz @ 40000 KHz), (N/A, 3000 mBm)
[   19.410000] cfg80211:   (4910000 KHz - 5895000 KHz @ 40000 KHz), (N/A, 3000 mBm)
[   19.560000] PPP generic driver version 2.4.2
[   19.560000] NET: Registered protocol family 24
[   19.650000] ieee80211 phy0: Atheros AR9100 MAC/BB Rev:7 AR2133 RF Rev:a2 mem=0xb80c0000, irq=2
[   19.660000] cfg80211: Calling CRDA for country: US
[   19.660000] cfg80211: Regulatory domain changed to country: US
[   19.670000] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[   19.680000] cfg80211:   (2400000 KHz - 2494000 KHz @ 40000 KHz), (N/A, 3000 mBm)
[   19.690000] cfg80211:   (4910000 KHz - 5895000 KHz @ 40000 KHz), (N/A, 3000 mBm)
[   25.570000] eth0: link up (1000Mbps/Full duplex)
[   25.590000] device eth0 entered promiscuous mode
[   25.900000] batman_adv: bat0: Adding interface: eth0.1
[   25.900000] batman_adv: bat0: The MTU of interface eth0.1 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interfa
ce will be fragmented on layer2 which could impact the performance. Setting the MTU to 1528 would solve the problem.
[   25.930000] batman_adv: bat0: Interface activated: eth0.1
[   25.940000] device bat0 entered promiscuous mode
[   25.940000] br-mybridge: port 1(bat0) entered forwarding state
[   25.950000] br-mybridge: port 1(bat0) entered forwarding state
[   25.960000] batman_adv: bat0: Interface deactivated: eth0.1
[   25.960000] batman_adv: bat0: Removing interface: eth0.1
[   26.010000] batman_adv: bat0: Adding interface: eth0.1
[   26.010000] batman_adv: bat0: The MTU of interface eth0.1 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interfa
ce will be fragmented on layer2 which could impact the performance. Setting the MTU to 1528 would solve the problem.
[   26.040000] batman_adv: bat0: Interface activated: eth0.1
[   26.240000] br-mybridge: port 1(bat0) entered disabled state
[   26.240000] device bat0 left promiscuous mode
[   26.250000] br-mybridge: port 1(bat0) entered disabled state
[   36.490000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[   36.650000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[   46.630000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[   46.790000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[   56.770000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[   56.930000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[   66.760000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[   66.910000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[   67.070000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[   76.900000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[   77.050000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[   77.210000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[   87.040000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[   87.210000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[   87.350000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[   97.180000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[   97.360000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[   97.490000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  107.320000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  107.500000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  107.630000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  117.460000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  117.640000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  117.770000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  127.600000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  127.780000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  127.910000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
EG-labor-AP
[  137.860000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  137.860000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  138.130000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  148.010000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  148.010000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  148.270000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  158.150000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  158.160000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  158.410000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  168.290000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  168.300000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  168.550000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
EG-labor-AP
up: 174.45 load: 2.77 rest: 1.19 0.45 2/36 2632
[  178.430000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  178.440000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  178.690000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  188.570000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  188.580000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  188.830000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  198.710000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  198.720000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  198.970000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  208.850000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  208.860000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  209.110000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  218.990000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  219.000000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  219.250000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  229.130000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  229.140000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  229.390000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
EG-labor-AP
up: 234.45 load: 2.92 rest: 1.52 0.61 1/36 2643
[  239.270000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  239.280000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  239.530000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  249.410000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  249.420000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  249.670000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  259.550000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  259.560000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  259.810000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  269.690000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  269.700000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  269.950000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  279.830000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  279.840000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  280.090000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  289.970000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  289.980000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  290.230000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
EG-labor-AP
up: 294.45 load: 2.97 rest: 1.79 0.76 1/36 2683
[  300.110000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  300.120000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  300.370000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  310.250000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  310.260000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  310.510000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  320.430000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  320.430000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  320.690000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  330.570000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  330.580000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  330.830000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  340.710000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  340.720000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  340.970000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  350.850000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  350.860000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  351.110000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
EG-labor-AP
up: 354.46 load: 2.99 rest: 2.01 0.90 1/38 2757
[  360.990000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  361.000000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  361.250000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  371.130000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  371.140000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  371.390000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  381.320000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  381.320000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  381.590000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  391.470000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  391.470000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  391.730000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  401.610000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  401.620000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  401.620000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  401.870000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  411.750000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  411.760000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  412.010000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
EG-labor-AP
up: 414.46 load: 3.00 rest: 2.19 1.03 2/42 2912
[  421.890000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  421.900000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  422.150000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  432.030000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  432.040000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  432.290000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  442.110000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  442.240000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  442.500000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  452.250000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  452.380000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  452.640000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  462.390000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  462.520000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  462.780000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  472.530000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  472.660000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  472.920000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
EG-labor-AP
up: 474.46 load: 3.04 rest: 2.35 1.16 2/38 3047
[  482.680000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  482.800000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  483.060000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  492.820000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  492.940000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  493.200000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
procd: - init complete -
[  502.960000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  503.080000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  503.340000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  513.100000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  513.220000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  513.480000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  523.240000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  523.360000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  523.620000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  533.380000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  533.500000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  533.760000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
EG-labor-AP
up: 534.46 load: 3.02 rest: 2.47 1.27 2/36 3122
[  543.520000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  543.640000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  543.900000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  553.660000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  553.780000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  554.040000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  563.800000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  563.920000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  564.180000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  573.940000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  574.060000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  574.320000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  584.080000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  584.200000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  584.460000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  594.220000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  594.340000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
EG-labor-AP
up: 594.46 load: 3.01 rest: 2.57 1.38 2/36 3160
[  594.600000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  604.360000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  604.480000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  604.740000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
[  614.500000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  614.620000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1
[  614.880000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1
:[  624.640000] unregister_netdevice: waiting for bat0 to become free. Usage count = 2
[  624.760000] unregister_netdevice: waiting for wlan0 to become free. Usage count = 1                                                                        [
  625.020000] unregister_netdevice: waiting for br-mybridge to become free. Usage count = 1

comment:7 Changed 4 years ago by bittorf@…

important: after a simple reboot, the device works again...

comment:8 Changed 4 years ago by bittorf@…

config:

root@EG-labor-AP:~ uci show network
network.loopback=interface
network.loopback.ifname=lo
network.loopback.proto=static
network.loopback.ipaddr=127.0.0.1
network.loopback.netmask=255.0.0.0
network.globals=globals
network.globals.ula_prefix=fd74:cc28:9a66::/48
network.wan6=interface
network.wan6.ifname=@wan
network.wan6.proto=dhcpv6
network.@switch[0]=switch
network.@switch[0].name=switch0
network.@switch[0].reset=1
network.@switch[0].enable_vlan=1
network.@switch_vlan[0]=switch_vlan
network.@switch_vlan[0].device=switch0
network.@switch_vlan[0].vlan=1
network.@switch_vlan[0].ports=1 2 3 4 5t
network.@switch_vlan[1]=switch_vlan
network.@switch_vlan[1].device=switch0
network.@switch_vlan[1].vlan=2
network.@switch_vlan[1].ports=0 5t

network.@alias[0]=alias
network.@alias[0].interface=lan
network.@alias[0].proto=batadv
network.@alias[0].mesh=bat0
network.@alias[1]=alias
network.@alias[1].interface=wan
network.@alias[1].proto=batadv
network.@alias[1].mesh=bat0
network.@alias[2]=alias
network.@alias[2].interface=lan
network.@alias[2].proto=batadv
network.@alias[2].mesh=bat0

network.lan=interface
network.lan.ifname=eth0.1
network.lan.proto=static
network.lan.ipaddr=10.63.99.33
network.lan.netmask=255.255.255.224
network.lan.macaddr=02:00:ca:b1:00:99

network.wan=interface
network.wan.ifname=eth0.2
network.wan.proto=pppoe
network.wan.username=XXX
network.wan.password=XXX
network.wan.authfail=1
network.wan.macaddr=02:00:de:ad:00:99

network.wlan=interface
network.wlan.proto=static
network.wlan.ipaddr=10.63.99.1
network.wlan.netmask=255.0.0.0

network.mybridge=interface
network.mybridge.type=bridge
network.mybridge.ifname=bat0
network.mybridge.proto=static
network.mybridge.ipaddr=192.168.99.1
network.mybridge.netmask=255.255.0.0

wireless:

root@EG-labor-AP:~ uci show wireless
wireless.radio0=wifi-device
wireless.radio0.type=mac80211
wireless.radio0.country=US
wireless.radio0.frag=off
wireless.radio0.noscan=1
wireless.radio0.path=platform/ath9k
wireless.radio0.hwmode=11ng
wireless.radio0.ht_capab=SHORT-GI-40 DSSS_CCK-40
wireless.radio0.beacon_int=100
wireless.radio0.htmode=HT20
wireless.radio0.channel=5
wireless.@wifi-iface[0]=wifi-iface
wireless.@wifi-iface[0].device=radio0
wireless.@wifi-iface[0].network=wlan
wireless.@wifi-iface[0].mode=ap
wireless.@wifi-iface[0].max_num_sta=64
wireless.@wifi-iface[0].max_inactivity=300
wireless.@wifi-iface[0].ssid=Schlachtfest

bridge:

root@EG-labor-AP:~ brctl show
bridge name     bridge id               STP enabled     interfaces
br-mybridge             7fff.3272b2de0dd6       no              bat0
                                                        wlan0

batctl, show that only LAN is used:

root@EG-labor-AP:~ batctl if
eth0.1: active

comment:9 Changed 4 years ago by bittorf@…

i can see it again with r39097

comment:10 Changed 4 years ago by bittorf@…

i can see it again with r39139

comment:11 Changed 4 years ago by bittorf@…

i can see it again with r39139

comment:12 Changed 4 years ago by anonymous

Just great... when will this get fixed? Anything new? It's really annoying, and makes it impossible to do a remote reboot.

comment:13 Changed 4 years ago by bittorf@…

we use a workaround, maybe this can help you too:
in /etc/init.d/done we do:

sleep 120
pidof network && reboot -f
pidof iw && reboot -f

it's terrible, but works...

comment:14 Changed 4 years ago by anonymous

What does that do? Also wouldnt it delay everything for 120 seconds? done isnt really the last script in the boot order.

comment:15 follow-up: Changed 4 years ago by anonymous

I tried it by this:

#!/bin/sh /etc/rc.common
# Copyright (C) 2006 OpenWrt.org

START=95
boot() {
	[ -d /tmp/root ] && {
		lock /tmp/.switch2jffs
		switch2jffs
		lock -u /tmp/.switch2jffs
	}

	# process user commands
	[ -f /etc/rc.local ] && {
		sh /etc/rc.local
	}

	# set leds to normal state
	. /etc/diag.sh
	set_state done

	sleep 120
	pidof network && reboot -f
	pidof iw && reboot -f
}

Doesnt work. The router mostly crashs by a reboot, and takes several times to become stable by rebooting it again and again until it works.

comment:16 in reply to: ↑ 15 Changed 4 years ago by bittorf@…

Replying to anonymous:

Doesnt work. The router mostly crashs by a reboot, and takes several times to become stable by rebooting it again and again until it works.

read carefully. it is a _workaround_ and not the solution, but still better than an unreachable box...

comment:17 Changed 4 years ago by anonymous

Read carefully. I didnt say it "fixed" the issue, I said, it didnt work. My box stays unreachable.

comment:18 Changed 4 years ago by bittorf@…

write carefully:

again and again until it works.
                      ^^^^^^^^^

or what does 'it works' means?

comment:19 Changed 4 years ago by anonymous

I think this is a different problem then I have than what this ticket is about? Or maybe they are both somehow based around the same bug. My problem is, that my box, and three other I administrate, have this "bug", "again" since a few trunk versions, that there is a high possibility, I would say 3 out of 5, that the box becomes unreachable during boot and stays unreachable, until you reboot it via reset, and reboot it, and reboot it, until it works. And with working I mean just normally reachable via wifi and lan, and just normally behaves. This problem happened before like a year or so, but somewhen now and then it got fixed, but since a few trunk versions, it is back again.

comment:20 Changed 4 years ago by anonymous

Why wont this get fixed? No word from the devs yet about this?? I made another ticket (14754) because I am not sure if this is the same problem, but I guess it is.

comment:21 Changed 4 years ago by jow

  • Milestone changed from Attitude Adjustment 12.09 to Barrier Breaker 14.07

Milestone Attitude Adjustment 12.09 deleted

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.