WDR3500 stuck on bootup after sysupgrade from 21.02.1 to 21.02.2

beeweezer · February 19, 2022, 10:24pm

So after succesfully updating three routers (of type wdr3600 and archer C7 v2) I now run into trouble on my WDR3500 v1. The sysupgrade seems to finish but after the reboot the device got stuck: initially it flashes all LED's once, then only the left most LED keeps burning and that's it.
After using the reset button for 3 seconds as soon as the router's LED flash once, the router succesfully reboots. UNTIL I initiate the next reboot, then the device will be stuck again.
Next steps for me will be making a config backup, doing a factory reset and hopefully a making a succesful config restore. I'll report back.

I can't see something special (I'm no expert) but here's the full kernel log:

[    0.000000] Linux version 5.4.179 (builder@buildhost) (gcc version 8.4.0 (OpenWrt GCC 8.4.0 r16495-bf0c965af0)) #0 Wed Feb 16 20:29:10 2022
[    0.000000] printk: bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 0001974c (MIPS 74Kc)
[    0.000000] MIPS: machine is TP-Link TL-WDR3500 v1
[    0.000000] SoC: Atheros AR9344 rev 2
[    0.000000] Initrd not found or empty - disabling initrd
[    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] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] On node 0 totalpages: 32768
[    0.000000]   Normal zone: 288 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 32768 pages, LIFO batch:7
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 32480
[    0.000000] Kernel command line: console=ttyS0,115200 rootfstype=squashfs,jffs2
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 122180K/131072K available (5257K kernel code, 194K rwdata, 688K rodata, 1212K init, 205K bss, 8892K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS: 51
[    0.000000] random: get_random_bytes called from 0x80661a28 with crng_init=0
[    0.000000] CPU clock: 560.000 MHz
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6825930166 ns
[    0.000009] sched_clock: 32 bits at 280MHz, resolution 3ns, wraps every 7669584382ns
[    0.008316] Calibrating delay loop... 278.78 BogoMIPS (lpj=557568)
[    0.050855] pid_max: default: 32768 minimum: 301
[    0.055946] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.063740] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.077007] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.087426] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
[    0.094788] pinctrl core: initialized pinctrl subsystem
[    0.103009] NET: Registered protocol family 16
[    0.109930] GPIO line 12 (tp-link:power:usb) hogged as output/high
[    0.144407] clocksource: Switched to clocksource MIPS
[    0.150831] thermal_sys: Registered thermal governor 'step_wise'
[    0.151209] NET: Registered protocol family 2
[    0.162479] IP idents hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    0.171040] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
[    0.180020] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.188193] TCP bind hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.195711] TCP: Hash tables configured (established 1024 bind 1024)
[    0.202611] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.209619] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.217321] NET: Registered protocol family 1
[    0.222032] PCI: CLS 0 bytes, default 32
[    0.230284] workingset: timestamp_bits=14 max_order=15 bucket_order=1
[    0.244709] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.250951] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.275911] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.288053] pinctrl-single 1804002c.pinmux: 544 pins, size 68
[    0.295273] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.304649] printk: console [ttyS0] disabled
[    0.309284] 18020000.uart: ttyS0 at MMIO 0x18020000 (irq = 9, base_baud = 2500000) is a 16550A
[    0.318489] printk: console [ttyS0] enabled
[    0.327395] printk: bootconsole [early0] disabled
[    0.347436] spi-nor spi0.0: en25q64 (8192 Kbytes)
[    0.352318] 3 fixed-partitions partitions found on MTD device spi0.0
[    0.358781] Creating 3 MTD partitions on "spi0.0":
[    0.363670] 0x000000000000-0x000000020000 : "u-boot"
[    0.369754] 0x000000020000-0x0000007f0000 : "firmware"
[    0.378909] 2 tplink-fw partitions found on MTD device firmware
[    0.384979] Creating 2 MTD partitions on "firmware":
[    0.390045] 0x000000000000-0x0000001f4717 : "kernel"
[    0.396075] 0x0000001f4718-0x0000007d0000 : "rootfs"
[    0.402079] mtd: device 3 (rootfs) set to be root filesystem
[    0.409788] 1 squashfs-split partitions found on MTD device rootfs
[    0.416129] 0x000000520000-0x0000007d0000 : "rootfs_data"
[    0.422627] 0x0000007f0000-0x000000800000 : "art"
[    0.765506] ag71xx 19000000.eth: Could not connect to PHY device. Deferring probe.
[    1.160401] random: fast init done
[    1.413644] switch0: Atheros AR8229 rev. 1 switch registered on mdio.0
[    1.458004] ag71xx 1a000000.eth: connected to PHY at fixed-0:00 [uid=00000000, driver=Generic PHY]
[    1.467808] eth0: Atheros AG71xx at 0xba000000, irq 5, mode: gmii
[    1.474430] i2c /dev entries driver
[    1.480720] NET: Registered protocol family 10
[    1.491297] Segment Routing with IPv6
[    1.495222] NET: Registered protocol family 17
[    1.499839] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    1.513004] 8021q: 802.1Q VLAN Support v1.8
[    1.518295] PCI host bridge /ahb/pcie-controller@180c0000 ranges:
[    1.524568]  MEM 0x0000000010000000..0x0000000013ffffff
[    1.529889]   IO 0x0000000000000000..0x0000000000000000
[    1.535393] PCI host bridge to bus 0000:00
[    1.539600] pci_bus 0000:00: root bus resource [mem 0x10000000-0x13ffffff]
[    1.546586] pci_bus 0000:00: root bus resource [io  0x0000]
[    1.552252] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[    1.559150] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    1.567237] pci 0000:00:00.0: [168c:0033] type 00 class 0x028000
[    1.573400] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x0001ffff 64bit]
[    1.580344] pci 0000:00:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[    1.587231] pci 0000:00:00.0: supports D1
[    1.591317] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[    1.598443] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[    1.605217] pci 0000:00:00.0: BAR 0: assigned [mem 0x10000000-0x1001ffff 64bit]
[    1.612670] pci 0000:00:00.0: BAR 6: assigned [mem 0x10020000-0x1002ffff pref]
[    1.938303] ag71xx 19000000.eth: connected to PHY at mdio.0:1f:04 [uid=004dd042, driver=Generic PHY]
[    1.948601] eth1: Atheros AG71xx at 0xb9000000, irq 4, mode: mii
[    1.956106] hctosys: unable to open rtc device (rtc0)
[    1.969428] VFS: Mounted root (squashfs filesystem) readonly on device 31:3.
[    1.982172] Freeing unused kernel memory: 1212K
[    1.986802] This architecture does not have kernel memory protection.
[    1.993345] Run /sbin/init as init process
[    2.626007] init: Console is alive
[    2.629769] init: - watchdog -
[    3.698192] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    3.748876] usbcore: registered new interface driver usbfs
[    3.754578] usbcore: registered new interface driver hub
[    3.760106] usbcore: registered new device driver usb
[    3.772010] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    3.780518] ehci-fsl: Freescale EHCI Host controller driver
[    3.787723] ehci-platform: EHCI generic platform driver
[    3.793363] ehci-platform 1b000000.usb: EHCI Host Controller
[    3.799189] ehci-platform 1b000000.usb: new USB bus registered, assigned bus number 1
[    3.807254] ehci-platform 1b000000.usb: irq 3, io mem 0x1b000000
[    3.828491] ehci-platform 1b000000.usb: USB 2.0 started, EHCI 1.00
[    3.835728] hub 1-0:1.0: USB hub found
[    3.839990] hub 1-0:1.0: 1 port detected
[    3.847484] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    3.866013] init: - preinit -
[    5.272681] random: jshn: uninitialized urandom read (4 bytes read)
[    5.604984] random: jshn: uninitialized urandom read (4 bytes read)
[    5.770280] random: jshn: uninitialized urandom read (4 bytes read)
[    6.568678] eth0: link up (1000Mbps/Full duplex)
[    6.581065] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    6.598426] IPv6: ADDRCONF(NETDEV_CHANGE): eth0.1: link becomes ready
[    6.657123] urandom_read: 4 callbacks suppressed
[    6.657132] random: procd: uninitialized urandom read (4 bytes read)
[   10.840924] jffs2: notice: (567) jffs2_build_xattr_subsystem: complete building xattr subsystem, 12 of xdatum (10 unchecked, 1 orphan) and 15 of xref (1 dead, 0 orphan) found.
[   10.858575] mount_root: switching to jffs2 overlay
[   10.870664] overlayfs: upper fs does not support tmpfile.
[   10.882383] urandom-seed: Seeding with /etc/urandom.seed
[   10.988973] eth0: link down
[   11.013478] procd: - early -
[   11.017031] procd: - watchdog -
[   11.600541] procd: - watchdog -
[   11.605269] procd: - ubus -
[   11.679035] random: ubusd: uninitialized urandom read (4 bytes read)
[   11.688021] random: ubusd: uninitialized urandom read (4 bytes read)
[   11.705393] procd: - init -
[   12.750282] kmodloader: loading kernel modules from /etc/modules.d/*
[   12.835387] Loading modules backported from Linux version v5.10.85-0-ge4f2aee6612e
[   12.843138] Backport generated by backports.git v5.10.85-1-0-gf988973f
[   12.909984] xt_time: kernel timezone is -0000
[   13.175868] PPP generic driver version 2.4.2
[   13.193300] NET: Registered protocol family 24
[   13.292483] urngd: v1.0.2 started.
[   13.308877] ath: EEPROM regdomain sanitized
[   13.308889] ath: EEPROM regdomain: 0x64
[   13.308893] ath: EEPROM indicates we should expect a direct regpair map
[   13.308917] ath: Country alpha2 being used: 00
[   13.308921] ath: Regpair used: 0x64
[   13.321951] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   13.324099] ieee80211 phy0: Atheros AR9340 Rev:2 mem=0xb8100000, irq=12
[   13.331239] ath9k 0000:00:00.0: enabling device (0000 -> 0002)
[   13.433869] ath: EEPROM regdomain sanitized
[   13.433881] ath: EEPROM regdomain: 0x64
[   13.433886] ath: EEPROM indicates we should expect a direct regpair map
[   13.433908] ath: Country alpha2 being used: 00
[   13.433912] ath: Regpair used: 0x64
[   13.444088] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[   13.446488] ieee80211 phy1: Atheros AR9300 Rev:4 mem=0xb0000000, irq=14
[   13.484838] kmodloader: done loading kernel modules from /etc/modules.d/*
[   13.827069] crng init done
[   31.775089] eth0: link up (1000Mbps/Full duplex)
[   31.788780] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   31.797089] br-lan: port 1(eth0.1) entered blocking state
[   31.802626] br-lan: port 1(eth0.1) entered disabled state
[   31.808627] device eth0.1 entered promiscuous mode
[   31.813537] device eth0 entered promiscuous mode
[   31.852766] br-lan: port 1(eth0.1) entered blocking state
[   31.858295] br-lan: port 1(eth0.1) entered forwarding state
[   31.928288] br-lan: port 2(eth1) entered blocking state
[   31.933650] br-lan: port 2(eth1) entered disabled state
[   31.939311] device eth1 entered promiscuous mode
[   33.966504] eth1: link up (100Mbps/Full duplex)
[   33.985171] br-lan: port 2(eth1) entered blocking state
[   33.990528] br-lan: port 2(eth1) entered forwarding state
[   35.118759] ath: EEPROM regdomain: 0x8210
[   35.122886] ath: EEPROM indicates we should expect a country code
[   35.129093] ath: doing EEPROM country->regdmn map search
[   35.134490] ath: country maps to regdmn code: 0x37
[   35.139365] ath: Country alpha2 being used: NL
[   35.143885] ath: Regpair used: 0x37
[   35.147432] ath: regdomain 0x8210 dynamically updated by user
[   35.153337] ath: EEPROM regdomain: 0x8210
[   35.157428] ath: EEPROM indicates we should expect a country code
[   35.163618] ath: doing EEPROM country->regdmn map search
[   35.169021] ath: country maps to regdmn code: 0x37
[   35.173893] ath: Country alpha2 being used: NL
[   35.178407] ath: Regpair used: 0x37
[   35.181955] ath: regdomain 0x8210 dynamically updated by user
[   37.434398] br-lan: port 3(wlan0) entered blocking state
[   37.439946] br-lan: port 3(wlan0) entered disabled state
[   37.445776] device wlan0 entered promiscuous mode
[   38.103343] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   38.110049] br-lan: port 3(wlan0) entered blocking state
[   38.115492] br-lan: port 3(wlan0) entered forwarding state
[   39.594131] br-lan: port 4(wlan1) entered blocking state
[   39.599641] br-lan: port 4(wlan1) entered disabled state
[   39.605454] device wlan1 entered promiscuous mode
[   40.336286] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[   40.343107] br-lan: port 4(wlan1) entered blocking state
[   40.348582] br-lan: port 4(wlan1) entered forwarding state

anon89577378 · February 19, 2022, 11:00pm

If it happens again, see if you can get into Failsafe mode...

beeweezer · February 20, 2022, 12:53am

Thanks for the suggestion! Only this was not possible. The device got stuck before checking to invoke the failsafe mode. After switching the power on, all leds flash once and after about a second only the power led lit up and then it froze.
So, honestly I don't know what made the device boot again after I pressed the reset button as described in my first post! After I pressed the button the device would lit the WPS led (the two arrows) for a couple of seconds, after that it booted as usual (once! next reboot resulted in a stuck router again).
I tried a soft reset, a hard reset, flashing 21.02.2 sysupgrade again, flasing a 21.02.2 factory image, all resulted in the same problem. For now I downgraded back to 21.02.1 again and it all works.
Is there, besides this forum, a place to report the bug?

anon89577378 · February 20, 2022, 12:54am

Odd...I upgraded a C7 V2 to 21.02.2 yesterday afternoon, which has the same target and package architecture as the TL-WDR3500, and no issues.

Have you tried upgrading without keeping the 21.02.1 configs?

Note: I wouldn't try the factory image unless you're going from stock to OpenWrt. Could result in a brick.

beeweezer · February 20, 2022, 2:09am

Yes, really odd... As I wrote, I also upgraded my C7 V2 and TL-WDR3600 without any issue.
I also tried upgrading without configs. Did a hard factory reset before and after upgrading, same problem.

slh · February 20, 2022, 6:07am

I would suggest to rule out the easiest (potential) issue first, use the 21.02.2 sysupgrade image (confirm the hashsum first) and flash it again without retaining your settings. If that sorts out the problem, great - then you 'just' had an issue with a faulty flash or a broken overlay.

The second step would be flashing back the 21.02.1 image (again, not keeping settings) and to check if the issue is gone now.

hnyman · February 20, 2022, 8:25am

That looks pretty normal.

The failsafe entry point would be early in the boot, at the preinit stage.

I can't see anything special in the ath79 commits, so strange that there would be problems.

aquamacho1 · February 20, 2022, 11:09am

Can confirm, same router flashed with sysupgrade from 21.02.1 -> 21.02.2 with keep the settings option.
After reboot only power led is active. trying to recover now.
Btw, I downloaded my firmware .img from the firmware picker if this is important.

Update: Router is not bricked, firmware is flashed (loving the new luci UI theme btw great job ) but for some reason router cant boot the firmware with normal restart, only with the reset button pressed upon boot until two arrows appear.
System Log:

Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.000000] Linux version 5.4.179 (builder@buildhost) (gcc version 8.4.0 (OpenWrt GCC 8.4.0 r16495-bf0c965af0)) #0 Wed Feb 16 20:29:10 2022
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] printk: bootconsole [early0] enabled
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] CPU0 revision is: 0001974c (MIPS 74Kc)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] MIPS: machine is TP-Link TL-WDR3500 v1
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] SoC: Atheros AR9344 rev 2
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] Initrd not found or empty - disabling initrd
Wed Feb 16 20:29:20 2022 kern.warn kernel: [    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
Wed Feb 16 20:29:20 2022 kern.warn kernel: [    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] Zone ranges:
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] Movable zone start for each node
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] Early memory node ranges
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
Wed Feb 16 20:29:20 2022 kern.debug kernel: [    0.000000] On node 0 totalpages: 32768
Wed Feb 16 20:29:20 2022 kern.debug kernel: [    0.000000]   Normal zone: 288 pages used for memmap
Wed Feb 16 20:29:20 2022 kern.debug kernel: [    0.000000]   Normal zone: 0 pages reserved
Wed Feb 16 20:29:20 2022 kern.debug kernel: [    0.000000]   Normal zone: 32768 pages, LIFO batch:7
Wed Feb 16 20:29:20 2022 kern.debug kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Wed Feb 16 20:29:20 2022 kern.debug kernel: [    0.000000] pcpu-alloc: [0] 0
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 32480
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.000000] Kernel command line: console=ttyS0,115200 rootfstype=squashfs,jffs2
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] Writing ErrCtl register=00000000
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] Readback ErrCtl register=00000000
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] Memory: 122180K/131072K available (5257K kernel code, 194K rwdata, 688K rodata, 1212K init, 205K bss, 8892K reserved, 0K cma-reserved)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] NR_IRQS: 51
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.000000] random: get_random_bytes called from 0x80661a28 with crng_init=0
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] CPU clock: 560.000 MHz
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6825930166 ns
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.000009] sched_clock: 32 bits at 280MHz, resolution 3ns, wraps every 7669584382ns
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.008316] Calibrating delay loop... 278.78 BogoMIPS (lpj=557568)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.050855] pid_max: default: 32768 minimum: 301
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.055946] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.063740] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.077009] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.087426] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.094788] pinctrl core: initialized pinctrl subsystem
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.103006] NET: Registered protocol family 16
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.109920] GPIO line 12 (tp-link:power:usb) hogged as output/high
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.143353] clocksource: Switched to clocksource MIPS
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.149786] thermal_sys: Registered thermal governor 'step_wise'
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.150165] NET: Registered protocol family 2
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.161436] IP idents hash table entries: 2048 (order: 2, 16384 bytes, linear)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.169994] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.178978] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.187161] TCP bind hash table entries: 1024 (order: 0, 4096 bytes, linear)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.194678] TCP: Hash tables configured (established 1024 bind 1024)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.201579] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.208594] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.216311] NET: Registered protocol family 1
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.221020] PCI: CLS 0 bytes, default 32
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.230297] workingset: timestamp_bits=14 max_order=15 bucket_order=1
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.244745] squashfs: version 4.0 (2009/01/31) Phillip Lougher
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.251006] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.275992] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.288164] pinctrl-single 1804002c.pinmux: 544 pins, size 68
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.295400] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.304748] printk: console [ttyS0] disabled
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.309405] 18020000.uart: ttyS0 at MMIO 0x18020000 (irq = 9, base_baud = 2500000) is a 16550A
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.318615] printk: console [ttyS0] enabled
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.327522] printk: bootconsole [early0] disabled
Wed Feb 16 20:29:20 2022 kern.info kernel: [    0.359251] spi-nor spi0.0: s25fl064k (8192 Kbytes)
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.364276] 3 fixed-partitions partitions found on MTD device spi0.0
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.370741] Creating 3 MTD partitions on "spi0.0":
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.375633] 0x000000000000-0x000000020000 : "u-boot"
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.381718] 0x000000020000-0x0000007f0000 : "firmware"
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.389992] 2 tplink-fw partitions found on MTD device firmware
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.396064] Creating 2 MTD partitions on "firmware":
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.401128] 0x000000000000-0x0000001f4717 : "kernel"
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.407161] 0x0000001f4718-0x0000007d0000 : "rootfs"
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.413170] mtd: device 3 (rootfs) set to be root filesystem
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.420873] 1 squashfs-split partitions found on MTD device rootfs
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.427209] 0x000000520000-0x0000007d0000 : "rootfs_data"
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    0.433702] 0x0000007f0000-0x000000800000 : "art"
Wed Feb 16 20:29:20 2022 kern.err kernel: [    0.776452] ag71xx 19000000.eth: Could not connect to PHY device. Deferring probe.
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    1.151348] random: fast init done
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.424609] switch0: Atheros AR8229 rev. 1 switch registered on mdio.0
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.468945] ag71xx 1a000000.eth: connected to PHY at fixed-0:00 [uid=00000000, driver=Generic PHY]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.478747] eth0: Atheros AG71xx at 0xba000000, irq 5, mode: gmii
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.485370] i2c /dev entries driver
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.491648] NET: Registered protocol family 10
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.502143] Segment Routing with IPv6
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.506064] NET: Registered protocol family 17
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.510676] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.523841] 8021q: 802.1Q VLAN Support v1.8
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.529131] PCI host bridge /ahb/pcie-controller@180c0000 ranges:
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.535410]  MEM 0x0000000010000000..0x0000000013ffffff
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.540732]   IO 0x0000000000000000..0x0000000000000000
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.546236] PCI host bridge to bus 0000:00
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.550445] pci_bus 0000:00: root bus resource [mem 0x10000000-0x13ffffff]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.557439] pci_bus 0000:00: root bus resource [io  0x0000]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.563106] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.570004] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.578089] pci 0000:00:00.0: [168c:0033] type 00 class 0x028000
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.584253] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x0001ffff 64bit]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.591198] pci 0000:00:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.598085] pci 0000:00:00.0: supports D1
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.602170] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.609296] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.616070] pci 0000:00:00.0: BAR 0: assigned [mem 0x10000000-0x1001ffff 64bit]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.623523] pci 0000:00:00.0: BAR 6: assigned [mem 0x10020000-0x1002ffff pref]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.949250] ag71xx 19000000.eth: connected to PHY at mdio.0:1f:04 [uid=004dd042, driver=Generic PHY]
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.959545] eth1: Atheros AG71xx at 0xb9000000, irq 4, mode: mii
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.967031] hctosys: unable to open rtc device (rtc0)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.978481] VFS: Mounted root (squashfs filesystem) readonly on device 31:3.
Wed Feb 16 20:29:20 2022 kern.info kernel: [    1.991278] Freeing unused kernel memory: 1212K
Wed Feb 16 20:29:20 2022 kern.warn kernel: [    1.995911] This architecture does not have kernel memory protection.
Wed Feb 16 20:29:20 2022 kern.info kernel: [    2.002456] Run /sbin/init as init process
Wed Feb 16 20:29:20 2022 user.info kernel: [    2.636094] init: Console is alive
Wed Feb 16 20:29:20 2022 user.info kernel: [    2.639849] init: - watchdog -
Wed Feb 16 20:29:20 2022 user.info kernel: [    3.708352] kmodloader: loading kernel modules from /etc/modules-boot.d/*
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.758943] usbcore: registered new interface driver usbfs
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.764652] usbcore: registered new interface driver hub
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.770178] usbcore: registered new device driver usb
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.782110] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.790531] ehci-fsl: Freescale EHCI Host controller driver
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.797767] ehci-platform: EHCI generic platform driver
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.803444] ehci-platform 1b000000.usb: EHCI Host Controller
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.809247] ehci-platform 1b000000.usb: new USB bus registered, assigned bus number 1
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.817314] ehci-platform 1b000000.usb: irq 3, io mem 0x1b000000
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.839436] ehci-platform 1b000000.usb: USB 2.0 started, EHCI 1.00
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.846683] hub 1-0:1.0: USB hub found
Wed Feb 16 20:29:20 2022 kern.info kernel: [    3.850939] hub 1-0:1.0: 1 port detected
Wed Feb 16 20:29:20 2022 user.info kernel: [    3.858424] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
Wed Feb 16 20:29:20 2022 user.info kernel: [    3.876952] init: - preinit -
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    5.287065] random: jshn: uninitialized urandom read (4 bytes read)
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    5.629554] random: jshn: uninitialized urandom read (4 bytes read)
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    5.795307] random: jshn: uninitialized urandom read (4 bytes read)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    6.591957] eth0: link up (1000Mbps/Full duplex)
Wed Feb 16 20:29:20 2022 kern.info kernel: [    6.604319] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Wed Feb 16 20:29:20 2022 kern.info kernel: [    6.621740] IPv6: ADDRCONF(NETDEV_CHANGE): eth0.1: link becomes ready
Wed Feb 16 20:29:20 2022 kern.warn kernel: [    6.680339] urandom_read: 4 callbacks suppressed
Wed Feb 16 20:29:20 2022 kern.notice kernel: [    6.680347] random: procd: uninitialized urandom read (4 bytes read)
Wed Feb 16 20:29:20 2022 kern.notice kernel: [   10.858541] jffs2: notice: (568) jffs2_build_xattr_subsystem: complete building xattr subsystem, 8 of xdatum (0 unchecked, 8 orphan) and 10 of xref (1 dead, 9 orphan) found.
Wed Feb 16 20:29:20 2022 user.info kernel: [   10.875831] mount_root: overlay filesystem has not been fully initialized yet
Wed Feb 16 20:29:20 2022 user.info kernel: [   10.883989] mount_root: switching to jffs2 overlay
Wed Feb 16 20:29:20 2022 kern.warn kernel: [   10.891293] overlayfs: upper fs does not support tmpfile.
Wed Feb 16 20:29:20 2022 user.warn kernel: [   10.900630] urandom-seed: Seed file not found (/etc/urandom.seed)
Wed Feb 16 20:29:20 2022 kern.info kernel: [   10.997779] eth0: link down
Wed Feb 16 20:29:20 2022 user.info kernel: [   11.022205] procd: - early -
Wed Feb 16 20:29:20 2022 user.info kernel: [   11.025378] procd: - watchdog -
Wed Feb 16 20:29:20 2022 user.info kernel: [   11.607486] procd: - watchdog -
Wed Feb 16 20:29:20 2022 user.info kernel: [   11.631546] procd: - ubus -
Wed Feb 16 20:29:20 2022 kern.notice kernel: [   11.704883] random: ubusd: uninitialized urandom read (4 bytes read)
Wed Feb 16 20:29:20 2022 kern.notice kernel: [   11.713891] random: ubusd: uninitialized urandom read (4 bytes read)
Wed Feb 16 20:29:20 2022 user.info kernel: [   11.731128] procd: - init -
Wed Feb 16 20:29:20 2022 user.info kernel: [   12.748081] kmodloader: loading kernel modules from /etc/modules.d/*
Wed Feb 16 20:29:20 2022 kern.info kernel: [   12.796652] Loading modules backported from Linux version v5.10.85-0-ge4f2aee6612e
Wed Feb 16 20:29:20 2022 kern.info kernel: [   12.804402] Backport generated by backports.git v5.10.85-1-0-gf988973f
Wed Feb 16 20:29:20 2022 kern.info kernel: [   12.867198] xt_time: kernel timezone is -0000
Wed Feb 16 20:29:20 2022 kern.info kernel: [   13.134835] PPP generic driver version 2.4.2
Wed Feb 16 20:29:20 2022 kern.info kernel: [   13.152246] NET: Registered protocol family 24
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   13.261872] ath: EEPROM regdomain sanitized
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   13.261884] ath: EEPROM regdomain: 0x64
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   13.261889] ath: EEPROM indicates we should expect a direct regpair map
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   13.261912] ath: Country alpha2 being used: 00
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   13.261916] ath: Regpair used: 0x64
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   13.275016] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
Wed Feb 16 20:29:20 2022 kern.info kernel: [   13.277240] ieee80211 phy0: Atheros AR9340 Rev:2 mem=0xb8100000, irq=12
Wed Feb 16 20:29:20 2022 kern.info kernel: [   13.284381] ath9k 0000:00:00.0: enabling device (0000 -> 0002)
Wed Feb 16 20:29:20 2022 kern.warn kernel: [   13.361796] ath9k 0000:00:00.0: Direct firmware load for ath9k-eeprom-pci-0000:00:00.0.bin failed with error -2
Wed Feb 16 20:29:20 2022 kern.warn kernel: [   13.372102] ath9k 0000:00:00.0: Falling back to sysfs fallback for: ath9k-eeprom-pci-0000:00:00.0.bin
Wed Feb 16 20:29:20 2022 user.info kernel: [   13.385165] urngd: v1.0.2 started.
Wed Feb 16 20:29:20 2022 kern.notice kernel: [   13.662369] crng init done
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   15.402499] ath: EEPROM regdomain sanitized
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   15.402512] ath: EEPROM regdomain: 0x64
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   15.402517] ath: EEPROM indicates we should expect a direct regpair map
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   15.402541] ath: Country alpha2 being used: 00
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   15.402545] ath: Regpair used: 0x64
Wed Feb 16 20:29:20 2022 kern.debug kernel: [   15.412861] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
Wed Feb 16 20:29:20 2022 kern.info kernel: [   15.415202] ieee80211 phy1: Atheros AR9300 Rev:4 mem=0xb0000000, irq=14
Wed Feb 16 20:29:20 2022 user.info kernel: [   15.470571] kmodloader: done loading kernel modules from /etc/modules.d/*
Wed Feb 16 20:29:21 2022 user.notice dnsmasq: DNS rebinding protection is active, will discard upstream RFC1918 responses!
Wed Feb 16 20:29:21 2022 user.notice dnsmasq: Allowing 127.0.0.0/8 responses
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: Connected to system UBus
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: started, version 2.85 cachesize 150
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: DNS service limited to local subnets
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: compile time options: IPv6 GNU-getopt no-DBus UBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-cryptohash no-DNSSEC no-ID loop-detect inotify dumpfile
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: UBus support enabled: connected to system bus
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: using only locally-known addresses for domain test
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: using only locally-known addresses for domain onion
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: using only locally-known addresses for domain localhost
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: using only locally-known addresses for domain local
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: using only locally-known addresses for domain invalid
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: using only locally-known addresses for domain bind
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: using only locally-known addresses for domain lan
Wed Feb 16 20:29:22 2022 daemon.warn dnsmasq[1433]: no servers found in /tmp/resolv.conf.d/resolv.conf.auto, will retry
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: read /etc/hosts - 4 addresses
Wed Feb 16 20:29:22 2022 daemon.info dnsmasq[1433]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses
Wed Feb 16 20:29:42 2022 authpriv.info dropbear[1499]: Not backgrounding
Wed Feb 16 20:29:44 2022 daemon.notice wpa_supplicant[1598]: Successfully initialized wpa_supplicant
Wed Feb 16 20:29:44 2022 user.notice : Added device handler type: bonding
Wed Feb 16 20:29:44 2022 user.notice : Added device handler type: 8021ad
Wed Feb 16 20:29:44 2022 user.notice : Added device handler type: 8021q
Wed Feb 16 20:29:44 2022 user.notice : Added device handler type: macvlan
Wed Feb 16 20:29:44 2022 user.notice : Added device handler type: veth
Wed Feb 16 20:29:44 2022 user.notice : Added device handler type: bridge
Wed Feb 16 20:29:44 2022 user.notice : Added device handler type: Network device
Wed Feb 16 20:29:44 2022 user.notice : Added device handler type: tunnel
Wed Feb 16 20:29:47 2022 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp
Wed Feb 16 20:29:47 2022 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network
Wed Feb 16 20:29:48 2022 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/luci-splash
Wed Feb 16 20:29:48 2022 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/qos
Wed Feb 16 20:29:48 2022 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/miniupnpd
Wed Feb 16 20:29:48 2022 user.notice ucitrack: Setting up /etc/config/dhcp reload dependency on /etc/config/odhcpd
Wed Feb 16 20:29:49 2022 user.notice ucitrack: Setting up non-init /etc/config/fstab reload handler: /sbin/block mount
Wed Feb 16 20:29:49 2022 user.notice ucitrack: Setting up /etc/config/system reload trigger for non-procd /etc/init.d/led
Wed Feb 16 20:29:49 2022 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/luci_statistics
Wed Feb 16 20:29:50 2022 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/dhcp
Wed Feb 16 20:29:52 2022 kern.info kernel: [   54.341831] eth0: link up (1000Mbps/Full duplex)
Wed Feb 16 20:29:52 2022 kern.info kernel: [   54.359438] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Wed Feb 16 20:29:52 2022 kern.info kernel: [   54.367204] br-lan: port 1(eth0.1) entered blocking state
Wed Feb 16 20:29:52 2022 kern.info kernel: [   54.372758] br-lan: port 1(eth0.1) entered disabled state
Wed Feb 16 20:29:52 2022 kern.info kernel: [   54.378717] device eth0.1 entered promiscuous mode
Wed Feb 16 20:29:52 2022 kern.info kernel: [   54.383634] device eth0 entered promiscuous mode
Wed Feb 16 20:29:52 2022 kern.info kernel: [   54.413548] br-lan: port 1(eth0.1) entered blocking state
Wed Feb 16 20:29:52 2022 kern.info kernel: [   54.419076] br-lan: port 1(eth0.1) entered forwarding state
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Interface 'lan' is enabled
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Interface 'lan' is setting up now
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Interface 'lan' is now up
Wed Feb 16 20:29:52 2022 daemon.notice netifd: bridge 'br-lan' link is up
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Interface 'lan' has link connectivity
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Interface 'loopback' is enabled
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Interface 'loopback' is setting up now
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Interface 'loopback' is now up
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Interface 'wan' is enabled
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Interface 'wan6' is enabled
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Network device 'eth0' link is up
Wed Feb 16 20:29:52 2022 daemon.notice netifd: VLAN 'eth0.1' link is up
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Network device 'lo' link is up
Wed Feb 16 20:29:52 2022 daemon.notice netifd: Interface 'loopback' has link connectivity
Wed Feb 16 20:29:53 2022 user.notice firewall: Reloading firewall due to ifup of lan (br-lan)
Wed Feb 16 20:29:53 2022 kern.info kernel: [   55.339579] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
Wed Feb 16 20:29:53 2022 daemon.err odhcpd[1714]: Failed to send to ff02::1%lan@br-lan (Address not available)
Wed Feb 16 20:29:53 2022 daemon.info procd: - init complete -
Wed Feb 16 20:29:54 2022 daemon.info urandom_seed[2302]: Seed saved (/etc/urandom.seed)
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[1433]: exiting on receipt of SIGTERM
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: Connected to system UBus
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: started, version 2.85 cachesize 150
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: DNS service limited to local subnets
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: compile time options: IPv6 GNU-getopt no-DBus UBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-cryptohash no-DNSSEC no-ID loop-detect inotify dumpfile
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: UBus support enabled: connected to system bus
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq-dhcp[2471]: DHCP, IP range 192.168.1.100 -- 192.168.1.249, lease time 12h
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: using only locally-known addresses for domain test
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: using only locally-known addresses for domain onion
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: using only locally-known addresses for domain localhost
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: using only locally-known addresses for domain local
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: using only locally-known addresses for domain invalid
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: using only locally-known addresses for domain bind
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: using only locally-known addresses for domain lan
Wed Feb 16 20:29:58 2022 daemon.warn dnsmasq[2471]: no servers found in /tmp/resolv.conf.d/resolv.conf.auto, will retry
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: read /etc/hosts - 4 addresses
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: read /tmp/hosts/dhcp.cfg01411c - 1 addresses
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq-dhcp[2471]: read /etc/ethers - 0 addresses
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: read /etc/hosts - 4 addresses
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq[2471]: read /tmp/hosts/dhcp.cfg01411c - 1 addresses
Wed Feb 16 20:29:58 2022 daemon.info dnsmasq-dhcp[2471]: read /etc/ethers - 0 addresses
Wed Feb 16 20:40:58 2022 daemon.info dnsmasq-dhcp[2471]: DHCPREQUEST(br-lan) 192.168.1.200 90:2b:34:5e:f2:64
Wed Feb 16 20:40:58 2022 daemon.info dnsmasq-dhcp[2471]: DHCPACK(br-lan) 192.168.1.200 90:2b:34:5e:f2:64 HOME-PC
Wed Feb 16 20:40:59 2022 daemon.info dnsmasq[2471]: read /etc/hosts - 4 addresses
Wed Feb 16 20:40:59 2022 daemon.info dnsmasq[2471]: read /tmp/hosts/odhcpd - 0 addresses
Wed Feb 16 20:40:59 2022 daemon.info dnsmasq[2471]: read /tmp/hosts/dhcp.cfg01411c - 1 addresses
Wed Feb 16 20:40:59 2022 daemon.info dnsmasq-dhcp[2471]: read /etc/ethers - 0 addresses
Wed Feb 16 20:41:01 2022 daemon.info dnsmasq[2471]: read /etc/hosts - 4 addresses
Wed Feb 16 20:41:01 2022 daemon.info dnsmasq[2471]: read /tmp/hosts/odhcpd - 1 addresses
Wed Feb 16 20:41:01 2022 daemon.info dnsmasq[2471]: read /tmp/hosts/dhcp.cfg01411c - 1 addresses
Wed Feb 16 20:41:01 2022 daemon.info dnsmasq-dhcp[2471]: read /etc/ethers - 0 addresses
Wed Feb 16 20:41:20 2022 user.info : luci: accepted login on / for root from 192.168.1.200
Wed Feb 16 20:54:12 2022 daemon.info dnsmasq-dhcp[2471]: DHCPREQUEST(br-lan) 192.168.1.200 90:2b:34:5e:f2:64
Wed Feb 16 20:54:12 2022 daemon.info dnsmasq-dhcp[2471]: DHCPACK(br-lan) 192.168.1.200 90:2b:34:5e:f2:64 HOME-PC
Wed Feb 16 20:54:14 2022 daemon.info dnsmasq[2471]: read /etc/hosts - 4 addresses
Wed Feb 16 20:54:14 2022 daemon.info dnsmasq[2471]: read /tmp/hosts/odhcpd - 0 addresses
Wed Feb 16 20:54:14 2022 daemon.info dnsmasq[2471]: read /tmp/hosts/dhcp.cfg01411c - 1 addresses
Wed Feb 16 20:54:14 2022 daemon.info dnsmasq-dhcp[2471]: read /etc/ethers - 0 addresses
Wed Feb 16 20:54:15 2022 daemon.info dnsmasq[2471]: read /etc/hosts - 4 addresses
Wed Feb 16 20:54:15 2022 daemon.info dnsmasq[2471]: read /tmp/hosts/odhcpd - 1 addresses
Wed Feb 16 20:54:15 2022 daemon.info dnsmasq[2471]: read /tmp/hosts/dhcp.cfg01411c - 1 addresses
Wed Feb 16 20:54:15 2022 daemon.info dnsmasq-dhcp[2471]: read /etc/ethers - 0 addresses

Update2: Reverted back to 21.02.1 for now, everything is working.

beeweezer · February 20, 2022, 5:26pm

The interesting thing to me is that the device locks up before the failsafe entry point, I wonder what pressing the reset button before that moment does exactly (besides lighting the two arrow / wps led). Is that also called 'preinit stage'? To be clear: failsafe mode can be initiated, but only if the reset button was pressed for about 3 seconds before the failsafe entry point. At that point it does not help invoking the failsafe mode because the device does boot as normal (until it gets stuck again at the next reboot).

aquamacho1 · February 20, 2022, 5:35pm

Scary stuff, imagine if a reset button thingy did not work upon boot like that, would be a hard brick i think, with only option flash it with soldering a cable to the board

hnyman · February 20, 2022, 5:37pm

Almost sounds like the button definitions would have changed between .1 and .2

But at least at the first glance, I do not notice possible commits in that direction.

zandatsu07 · February 20, 2022, 5:38pm

Do you guys recommend to sysupgrade all the way and keep the config? or backup your config first and then fresh installation?

aquamacho1 · February 20, 2022, 5:39pm

On this device (wdr3500)? oh hell no, i would stay away from this update for now.

If you asking question not related to this issue, well in normal circumstances you can keep the config between small updates like 22.02.1 to 21.02.2 with no issue, i even retained my configs while upgrading from 19.XX to 21.XX with no issue, it depends on what has changed. Do both

You can always reset if something goes sideways.

aquamacho1 · February 22, 2022, 7:27pm

Will this issue be addressed before 21.02.2 officially rolls out? or do we submit a bug report on github?

beeweezer · March 15, 2022, 10:53pm

Anyone knows if there are any updates on this? As far as I can tell there's no new update in the firmware locator (last image was dated 2022-02-17 21:48:55). In other words - the official update currently does not work? @anon89577378 suggested that version may not have been 'final' but I think it is, right?

anon89577378 · March 15, 2022, 11:05pm

The "official" release was announced on 2/25.

I installed it on my C7 v2 on 2/15, and had no issues.

The firmware may have been built on 2/17, but the packages may not have been (would need to check dates).

Otherwise, it seems that reverting to 21.02.1 worked for @aquamacho1 above.

slh · March 16, 2022, 4:35am

The tl-wdr3500 is a very rare device (and the closely related tl-wdr3600/ tl-wdr4300/ tl-wdr4310 appear to work fine), as none of the developers has access to an affected device, someone with the device will have to work on this. Ideally starting with providing serial console logs for the good/ bad case, maybe that already provides some hints. Other tests might involve Building a custom firmware image (stripped down kernel/ rootfs, to rule out a potential issue with grown kernel sizes (bootloader bug)) or git-bisecting between known-good (21.02.1?) and known-bad (21.02.2?) states. Without this help from someone who can reproduce the issue, it either remains broken or tl-wdr3500 would have to be disabled (to prevent further breakage).

aquamacho1 · March 16, 2022, 1:44pm

Im actually still using 21.02.2 as is (Uptime: 8d 12h 20m 59s), with this bug, everything besides
this issue seems to be working like 21.02.1.
But yes, reverting back works just fine to.

I have UART (CA-42 nokia) cable on hands, but its a chinese ripoff (not official cable), i lost the drivers to it long time ago (it had drivers on disk from WinXP era, and i can`t find theese on the internet anymore).

I can`t buy normal serial/uart cable and bisect the issue myself currently, because i live in Ukraine, and its hard to buy anything as of now.

beeweezer · March 16, 2022, 4:09pm

I think I can provide the console logs (never did that before), will look into it as soon as I can. @aquamacho1 all the very best to you and your country.

slh · March 16, 2022, 8:33pm

If you have the opportunity (no, I wouldn't think about this in your situation now, as there's always a small risk for the device), booting a (live-) linux distribution might be the easiest approach, many of these USB serial devices (including fake knock-offs) are supported on linux just fine.