/overlay reverts to tmps after successful firmware update

JeremySTX · April 3, 2019, 3:44am

Hi,

If this looks familiar it's because this posting is a re-stating of Configuration loss after reboot but hopefully with a better problem definition.

We have a custom-built device (we call it our "board") based on the TP-Link MR3020 but with a Winbond 16M flash chip instead of the standard 4M flash. We use it for monitoring solar inverters and other A/C equipment, with our application logging a measurement to flash every minute. The measurement data is uploaded to a cloud server at regular intervals. All of this worked mostly trouble-free on OpenWrt Barrier Breaker.

I have been working on upgrading our firmware to OpenWrt18 and it's mostly working. The one issue which remains to be solved is this sequence of events:

Firmware is upgraded using sysupgrade -v newfirmware.bin. The upgrade works fine; the board reboots with the pre-upgrade configuration files (/etc/config/*) and measurement data (/storage/solarmon/db/*) intact. Assorted uci-defaults scripts run exit with status 0 so that they get deleted from /etc/uci-defaults/ (because they're supposed to run only on initial boot).
I confirm the uci-defaults have in fact been deleted, the board's network configuration is intact, and our measurement logging program is working as it should.
Some number of hours later (at least 20) a cron job reboots the board using the reboot command. (This is intentional.) On boot, the board re-runs the uci-defaults scripts and some of our configuration data is lost. The files in /storage/solarmon/db are intact.
I manually re-configure the network settings etc and it runs without problems. I notice the uci-defaults scripts have been deleted (as they should be).
Another 20+ hours later the board reboots again courtesy of the cron job mentioned earlier, and this time it reboots without issue: the configuration files are intact and the uci-defaults scripts do not run, and our measurement application continues to work without any assistance from me. The board happily reboots every day thereafter with no issues.

dmesg gives some useful information. On the initial boot (immediately following sysupgrade) I see this:

[    6.245094] jffs2_scan_eraseblock(): End of filesystem marker found at 0x110000
[    6.251077] jffs2_build_filesystem(): unlocking the mtd device... 
[    6.257016] done.
[    6.258866] jffs2_build_filesystem(): erasing all blocks after the end marker... 
[   31.698400] done.
[   31.698936] jffs2: notice: (386) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[   31.716645] mount_root: overlay filesystem has not been fully initialized yet
[   32.178279] mount_root: switching to jffs2 overlay

which to my untrained eye looks perfectly correct.

On the next boot (the one which causes my problems) I see this instead of the jffs2 messages:

[    5.829324] mount_root: no usable overlay filesystem found, using tmpfs overlay

and then later:

[   60.659610] jffs2: notice: (1615) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.

The reboot after that one (where everything works again) looks normal:

[    5.967663] jffs2: notice: (386) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[    5.984589] mount_root: switching to jffs2 overlay

Another thing I've noticed is that on a board which has been configured but has no measurement data, the reboot always works. Which leads me to think the issue is related to the size of the /overlay partition.

Full dmesg logs follow in next posting.
Thanks for any assistance.

JeremySTX · April 3, 2019, 3:45am

Here is the full dmesg log from the initial boot:

[    0.000000] Linux version 4.9.164 (jeremy@devtest) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r7405-06a20af) ) #0 Mon Mar 25 09:51:50 2019
[    0.000000] MyLoader: sysp=07c3cb73, boardp=69db762d, parts=24de692d
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019374 (MIPS 24Kc)
[    0.000000] SoC: Atheros AR9330 rev 1
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 04000000 @ 00000000 (usable)
[    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-0x0000000003ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] On node 0 totalpages: 16384
[    0.000000] free_area_init_node: node 0, pgdat 804373e4, node_mem_map 81000020
[    0.000000]   Normal zone: 128 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 16384 pages, LIFO batch:3
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 16256
[    0.000000] Kernel command line:  board=TL-MR3020  console=ttyATH0,115200 rootfstype=squashfs,jffs2 noinitrd
[    0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
[    0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 60100K/65536K available (2991K kernel code, 164K rwdata, 780K rodata, 280K init, 203K bss, 5436K 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] Clocks: CPU:400.000MHz, DDR:400.000MHz, AHB:200.000MHz, Ref:25.000MHz
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 9556302233 ns
[    0.000014] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 10737418237ns
[    0.007568] Calibrating delay loop... 265.42 BogoMIPS (lpj=1327104)
[    0.088749] pid_max: default: 32768 minimum: 301
[    0.093333] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.099505] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.110135] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.118179] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.124880] NET: Registered protocol family 16
[    0.130241] MIPS: machine is TP-LINK TL-MR3020
[    0.425682] clocksource: Switched to clocksource MIPS
[    0.430600] NET: Registered protocol family 2
[    0.434614] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.440086] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.446162] TCP: Hash tables configured (established 1024 bind 1024)
[    0.452340] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.457828] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.464074] NET: Registered protocol family 1
[    0.467990] PCI: CLS 0 bytes, default 32
[    0.474461] Crashlog allocated RAM at address 0x3f00000
[    0.479732] workingset: timestamp_bits=30 max_order=14 bucket_order=0
[    0.494418] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.498664] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.520513] io scheduler noop registered
[    0.522879] io scheduler deadline registered (default)
[    0.528383] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.534441] ar933x-uart: ttyATH0 at MMIO 0x18020000 (irq = 11, base_baud = 1562500) is a AR933X UART
[    0.543083] console [ttyATH0] enabled
[    0.549886] bootconsole [early0] disabled
[    0.562471] m25p80 spi0.0: found w25q128, expected m25p80
[    0.578000] m25p80 spi0.0: w25q128 (16384 Kbytes)
[    0.581604] 5 tp-link partitions found on MTD device spi0.0
[    0.586840] Creating 5 MTD partitions on "spi0.0":
[    0.591594] 0x000000000000-0x000000020000 : "u-boot"
[    0.599264] 0x000000020000-0x0000001655a8 : "kernel"
[    0.605646] 0x0000001655a8-0x000000ff0000 : "rootfs"
[    0.611443] mtd: device 2 (rootfs) set to be root filesystem
[    0.615781] 1 squashfs-split partitions found on MTD device rootfs
[    0.621832] 0x000000700000-0x000000ff0000 : "rootfs_data"
[    0.630217] 0x000000ff0000-0x000001000000 : "art"
[    0.636393] 0x000000020000-0x000000ff0000 : "firmware"
[    0.644334] libphy: Fixed MDIO Bus: probed
[    0.666690] libphy: ag71xx_mdio: probed
[    1.297394] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.1:04 [uid=004dd041, driver=Generic PHY]
[    1.306179] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:MII
[    1.312018] NET: Registered protocol family 17
[    1.315608] 8021q: 802.1Q VLAN Support v1.8
[    1.323041] hctosys: unable to open rtc device (rtc0)
[    1.331375] VFS: Mounted root (squashfs filesystem) readonly on device 31:2.
[    1.339211] Freeing unused kernel memory: 280K
[    1.342191] This architecture does not have kernel memory protection.
[    2.209296] init: Console is alive
[    2.211541] init: - watchdog -
[    2.505698] random: fast init done
[    4.267413] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    4.410845] usbcore: registered new interface driver usbfs
[    4.414987] usbcore: registered new interface driver hub
[    4.420393] usbcore: registered new device driver usb
[    4.430983] ds1302: RTC found.
[    4.432975] rtc_time	: 01:41:59
[    4.432975] rtc_date	: 2019-04-01
[    4.444108] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    4.467568] ar933x_lcd initialized
[    4.469517] ar933x_lcd: ar933x_lcd device registered with major 251
[    4.489489] SCSI subsystem initialized
[    4.498690] ehci-platform: EHCI generic platform driver
[    4.502616] ehci-platform ehci-platform: EHCI Host Controller
[    4.508320] ehci-platform ehci-platform: new USB bus registered, assigned bus number 1
[    4.518250] ehci-platform ehci-platform: irq 3, io mem 0x1b000000
[    4.545739] ehci-platform ehci-platform: USB 2.0 started, EHCI 1.00
[    4.551787] hub 1-0:1.0: USB hub found
[    4.554847] hub 1-0:1.0: 1 port detected
[    4.565502] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    4.572344] ohci-platform: OHCI generic platform driver
[    4.585366] usbcore: registered new interface driver usb-storage
[    4.590952] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    4.601373] init: - preinit -
[    5.226043] usb 1-1: new high-speed USB device number 2 using ehci-platform
[    5.465847] hub 1-1:1.0: USB hub found
[    5.469196] hub 1-1:1.0: 4 ports detected
[    5.536134] random: procd: uninitialized urandom read (4 bytes read)
[    5.885985] usb 1-1.1: new full-speed USB device number 3 using ehci-platform
[    6.186636] usb 1-1.4: new full-speed USB device number 4 using ehci-platform
[    6.245094] jffs2_scan_eraseblock(): End of filesystem marker found at 0x110000
[    6.251077] jffs2_build_filesystem(): unlocking the mtd device... 
[    6.257016] done.
[    6.258866] jffs2_build_filesystem(): erasing all blocks after the end marker... 
[   31.698400] done.
[   31.698936] jffs2: notice: (386) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[   31.716645] mount_root: overlay filesystem has not been fully initialized yet
[   32.178279] mount_root: switching to jffs2 overlay
[  118.187840] urandom-seed: Seed file not found (/etc/urandom.seed)
[  118.455366] procd: - early -
[  118.457132] procd: - watchdog -
[  119.087622] random: jshn: uninitialized urandom read (4 bytes read)
[  119.231464] procd: - watchdog -
[  119.233517] procd: - ubus -
[  119.733656] random: ubusd: uninitialized urandom read (4 bytes read)
[  119.794013] random: jshn: uninitialized urandom read (4 bytes read)
[  119.815616] procd: - init -
[  120.604809] kmodloader: loading kernel modules from /etc/modules.d/*
[  120.701231] tun: Universal TUN/TAP device driver, 1.6
[  120.704878] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[  120.769514] i2c /dev entries driver
[  120.815785] random: crng init done
[  120.817743] random: 6 urandom warning(s) missed due to ratelimiting
[  121.009034] hidraw: raw HID events driver (C) Jiri Kosina
[  121.109353] Bluetooth: Core ver 2.22
[  121.111590] NET: Registered protocol family 31
[  121.115970] Bluetooth: HCI device and connection manager initialized
[  121.122253] Bluetooth: HCI socket layer initialized
[  121.127133] Bluetooth: L2CAP socket layer initialized
[  121.132175] Bluetooth: SCO socket layer initialized
[  121.223301] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[  121.227252] Bluetooth: BNEP filters: protocol multicast
[  121.232387] Bluetooth: BNEP socket layer initialized
[  121.295432] usbcore: registered new interface driver btusb
[  121.318857] usbcore: registered new interface driver cdc_acm
[  121.323073] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[  121.397698] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
[  121.404252] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
[  121.452773] Bluetooth: HCI UART driver ver 2.3
[  121.455855] Bluetooth: HCI UART protocol H4 registered
[  121.460884] Bluetooth: HCI UART protocol BCSP registered
[  121.511532] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[  121.516132] Bluetooth: HIDP socket layer initialized
[  121.649168] nf_conntrack version 0.5.0 (1024 buckets, 4096 max)
[  121.795858] Bluetooth: RFCOMM TTY layer initialized
[  121.799324] Bluetooth: RFCOMM socket layer initialized
[  121.804446] Bluetooth: RFCOMM ver 1.11
[  121.831066] usbcore: registered new interface driver usbhid
[  121.835190] usbhid: USB HID core driver
[  121.867238] usbcore: registered new interface driver usbserial
[  121.871747] usbcore: registered new interface driver usbserial_generic
[  121.878353] usbserial: USB Serial support registered for generic
[  121.956499] xt_time: kernel timezone is -0000
[  121.968670] usbcore: registered new interface driver cdc_ether
[  122.049449] usbcore: registered new interface driver ftdi_sio
[  122.053868] usbserial: USB Serial support registered for FTDI USB Serial Device
[  122.088677] ip_tables: (C) 2000-2006 Netfilter Core Team
[  122.117251] usbcore: registered new interface driver keyspan
[  122.121589] usbserial: USB Serial support registered for Keyspan - (without firmware)
[  122.129512] usbserial: USB Serial support registered for Keyspan 1 port adapter
[  122.136771] usbserial: USB Serial support registered for Keyspan 2 port adapter
[  122.144000] usbserial: USB Serial support registered for Keyspan 4 port adapter
[  122.299375] usbcore: registered new interface driver pl2303
[  122.303640] usbserial: USB Serial support registered for pl2303
[  122.309606] pl2303 1-1.1:1.0: pl2303 converter detected
[  122.329613] usb 1-1.1: pl2303 converter now attached to ttyUSB0
[  122.350713] PPP generic driver version 2.4.2
[  122.368014] NET: Registered protocol family 24
[  122.375227] usbcore: registered new interface driver rndis_host
[  122.504678] ath: EEPROM regdomain: 0x0
[  122.504694] ath: EEPROM indicates default country code should be used
[  122.504698] ath: doing EEPROM country->regdmn map search
[  122.504719] ath: country maps to regdmn code: 0x3a
[  122.504726] ath: Country alpha2 being used: US
[  122.504732] ath: Regpair used: 0x3a
[  122.516724] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[  122.522634] ieee80211 phy0: Atheros AR9330 Rev:1 mem=0xb8100000, irq=2
[  122.576351] kmodloader: done loading kernel modules from /etc/modules.d/*
[  142.369866] br-lan: port 1(eth0) entered blocking state
[  142.373653] br-lan: port 1(eth0) entered disabled state
[  142.379448] device eth0 entered promiscuous mode
[  144.026686] ath: EEPROM regdomain: 0x8024
[  144.026701] ath: EEPROM indicates we should expect a country code
[  144.026707] ath: doing EEPROM country->regdmn map search
[  144.026714] ath: country maps to regdmn code: 0x21
[  144.026721] ath: Country alpha2 being used: AU
[  144.026727] ath: Regpair used: 0x21
[  144.026737] ath: regdomain 0x8024 dynamically updated by user
[  148.885423] wlan0: authenticate with 0e:02:8e:95:a3:6a
[  148.900880] wlan0: send auth to 0e:02:8e:95:a3:6a (try 1/3)
[  148.916125] wlan0: authenticated
[  148.935866] wlan0: associate with 0e:02:8e:95:a3:6a (try 1/3)
[  148.964607] wlan0: RX AssocResp from 0e:02:8e:95:a3:6a (capab=0x1431 status=0 aid=1)
[  148.971343] wlan0: associated
[  149.125624] wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 0e:02:8e:95:a3:6a
[  153.257814] jffs2: Newly-erased block contained word 0x19852003 at offset 0x00000000

JeremySTX · April 3, 2019, 3:45am

Here is the full dmesg log from the problematic boot:

[    0.000000] Linux version 4.9.164 (jeremy@devtest) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r7405-06a20af) ) #0 Mon Mar 25 09:51:50 2019
[    0.000000] MyLoader: sysp=07c3cb73, boardp=69db762d, parts=24de692d
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019374 (MIPS 24Kc)
[    0.000000] SoC: Atheros AR9330 rev 1
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 04000000 @ 00000000 (usable)
[    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-0x0000000003ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] On node 0 totalpages: 16384
[    0.000000] free_area_init_node: node 0, pgdat 804373e4, node_mem_map 81000020
[    0.000000]   Normal zone: 128 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 16384 pages, LIFO batch:3
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 16256
[    0.000000] Kernel command line:  board=TL-MR3020  console=ttyATH0,115200 rootfstype=squashfs,jffs2 noinitrd
[    0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
[    0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 60100K/65536K available (2991K kernel code, 164K rwdata, 780K rodata, 280K init, 203K bss, 5436K 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] Clocks: CPU:400.000MHz, DDR:400.000MHz, AHB:200.000MHz, Ref:25.000MHz
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 9556302233 ns
[    0.000014] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 10737418237ns
[    0.007568] Calibrating delay loop... 265.42 BogoMIPS (lpj=1327104)
[    0.088749] pid_max: default: 32768 minimum: 301
[    0.093333] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.099505] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.110135] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.118179] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.124879] NET: Registered protocol family 16
[    0.130241] MIPS: machine is TP-LINK TL-MR3020
[    0.425684] clocksource: Switched to clocksource MIPS
[    0.430608] NET: Registered protocol family 2
[    0.434622] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.440094] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.446170] TCP: Hash tables configured (established 1024 bind 1024)
[    0.452348] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.457836] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.464082] NET: Registered protocol family 1
[    0.467999] PCI: CLS 0 bytes, default 32
[    0.474470] Crashlog allocated RAM at address 0x3f00000
[    0.479739] workingset: timestamp_bits=30 max_order=14 bucket_order=0
[    0.494427] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.498672] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.520526] io scheduler noop registered
[    0.522895] io scheduler deadline registered (default)
[    0.528400] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.534458] ar933x-uart: ttyATH0 at MMIO 0x18020000 (irq = 11, base_baud = 1562500) is a AR933X UART
[    0.543099] console [ttyATH0] enabled
[    0.549902] bootconsole [early0] disabled
[    0.562489] m25p80 spi0.0: found w25q128, expected m25p80
[    0.578017] m25p80 spi0.0: w25q128 (16384 Kbytes)
[    0.581629] 5 tp-link partitions found on MTD device spi0.0
[    0.586865] Creating 5 MTD partitions on "spi0.0":
[    0.591620] 0x000000000000-0x000000020000 : "u-boot"
[    0.599289] 0x000000020000-0x0000001655a8 : "kernel"
[    0.605743] 0x0000001655a8-0x000000ff0000 : "rootfs"
[    0.611466] mtd: device 2 (rootfs) set to be root filesystem
[    0.615798] 1 squashfs-split partitions found on MTD device rootfs
[    0.621848] 0x000000700000-0x000000ff0000 : "rootfs_data"
[    0.630235] 0x000000ff0000-0x000001000000 : "art"
[    0.636410] 0x000000020000-0x000000ff0000 : "firmware"
[    0.644351] libphy: Fixed MDIO Bus: probed
[    0.666705] libphy: ag71xx_mdio: probed
[    1.297396] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.1:04 [uid=004dd041, driver=Generic PHY]
[    1.306179] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:MII
[    1.312017] NET: Registered protocol family 17
[    1.315608] 8021q: 802.1Q VLAN Support v1.8
[    1.323042] hctosys: unable to open rtc device (rtc0)
[    1.331383] VFS: Mounted root (squashfs filesystem) readonly on device 31:2.
[    1.339220] Freeing unused kernel memory: 280K
[    1.342199] This architecture does not have kernel memory protection.
[    2.209152] init: Console is alive
[    2.211399] init: - watchdog -
[    2.505700] random: fast init done
[    4.269368] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    4.412623] usbcore: registered new interface driver usbfs
[    4.416863] usbcore: registered new interface driver hub
[    4.422072] usbcore: registered new device driver usb
[    4.432730] ds1302: RTC found.
[    4.434718] rtc_time	: 02:15:17
[    4.434718] rtc_date	: 2019-04-02
[    4.446005] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    4.469374] ar933x_lcd initialized
[    4.471330] ar933x_lcd: ar933x_lcd device registered with major 251
[    4.491287] SCSI subsystem initialized
[    4.500482] ehci-platform: EHCI generic platform driver
[    4.504401] ehci-platform ehci-platform: EHCI Host Controller
[    4.510107] ehci-platform ehci-platform: new USB bus registered, assigned bus number 1
[    4.520036] ehci-platform ehci-platform: irq 3, io mem 0x1b000000
[    4.545740] ehci-platform ehci-platform: USB 2.0 started, EHCI 1.00
[    4.551784] hub 1-0:1.0: USB hub found
[    4.554745] hub 1-0:1.0: 1 port detected
[    4.565612] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    4.572363] ohci-platform: OHCI generic platform driver
[    4.585373] usbcore: registered new interface driver usb-storage
[    4.590837] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    4.607795] init: - preinit -
[    5.234327] usb 1-1: new high-speed USB device number 2 using ehci-platform
[    5.474339] hub 1-1:1.0: USB hub found
[    5.479474] hub 1-1:1.0: 4 ports detected
[    5.546192] random: procd: uninitialized urandom read (4 bytes read)
[    5.829324] mount_root: no usable overlay filesystem found, using tmpfs overlay
[    5.888514] urandom-seed: Seed file not found (/etc/urandom.seed)
[    5.894017] usb 1-1.1: new full-speed USB device number 3 using ehci-platform
[    6.299189] procd: - early -
[    6.300744] procd: - watchdog -
[    6.353777] usb 1-1.4: new full-speed USB device number 4 using ehci-platform
[    6.945298] random: jshn: uninitialized urandom read (4 bytes read)
[    7.119574] procd: - watchdog -
[    7.121649] procd: - ubus -
[    7.566861] random: jshn: uninitialized urandom read (4 bytes read)
[    7.585589] random: ubusd: uninitialized urandom read (4 bytes read)
[    7.639209] procd: - init -
[    8.432708] kmodloader: loading kernel modules from /etc/modules.d/*
[    8.529541] tun: Universal TUN/TAP device driver, 1.6
[    8.533143] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    8.858595] i2c /dev entries driver
[    8.897308] hidraw: raw HID events driver (C) Jiri Kosina
[    8.956618] Bluetooth: Core ver 2.22
[    8.958853] NET: Registered protocol family 31
[    8.963168] Bluetooth: HCI device and connection manager initialized
[    8.969582] Bluetooth: HCI socket layer initialized
[    8.974372] Bluetooth: L2CAP socket layer initialized
[    8.979457] Bluetooth: SCO socket layer initialized
[    9.064245] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[    9.068204] Bluetooth: BNEP filters: protocol multicast
[    9.073337] Bluetooth: BNEP socket layer initialized
[    9.131172] usbcore: registered new interface driver btusb
[    9.149071] usbcore: registered new interface driver cdc_acm
[    9.153284] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    9.197773] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
[    9.204325] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
[    9.231698] Bluetooth: HCI UART driver ver 2.3
[    9.234695] Bluetooth: HCI UART protocol H4 registered
[    9.239898] Bluetooth: HCI UART protocol BCSP registered
[    9.266524] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[    9.271045] Bluetooth: HIDP socket layer initialized
[    9.389393] nf_conntrack version 0.5.0 (1024 buckets, 4096 max)
[    9.505844] Bluetooth: RFCOMM TTY layer initialized
[    9.509306] Bluetooth: RFCOMM socket layer initialized
[    9.514432] Bluetooth: RFCOMM ver 1.11
[    9.550908] usbcore: registered new interface driver usbhid
[    9.555029] usbhid: USB HID core driver
[    9.582207] usbcore: registered new interface driver usbserial
[    9.586840] usbcore: registered new interface driver usbserial_generic
[    9.593226] usbserial: USB Serial support registered for generic
[    9.676684] xt_time: kernel timezone is -0000
[    9.688695] usbcore: registered new interface driver cdc_ether
[    9.829386] usbcore: registered new interface driver ftdi_sio
[    9.833809] usbserial: USB Serial support registered for FTDI USB Serial Device
[    9.858666] ip_tables: (C) 2000-2006 Netfilter Core Team
[    9.889083] usbcore: registered new interface driver keyspan
[    9.893425] usbserial: USB Serial support registered for Keyspan - (without firmware)
[    9.901333] usbserial: USB Serial support registered for Keyspan 1 port adapter
[    9.908600] usbserial: USB Serial support registered for Keyspan 2 port adapter
[    9.915906] usbserial: USB Serial support registered for Keyspan 4 port adapter
[   10.066309] usbcore: registered new interface driver pl2303
[   10.070580] usbserial: USB Serial support registered for pl2303
[   10.076506] pl2303 1-1.1:1.0: pl2303 converter detected
[   10.108625] usb 1-1.1: pl2303 converter now attached to ttyUSB0
[   10.124700] PPP generic driver version 2.4.2
[   10.147671] NET: Registered protocol family 24
[   10.154941] usbcore: registered new interface driver rndis_host
[   10.350160] ath: EEPROM regdomain: 0x0
[   10.350175] ath: EEPROM indicates default country code should be used
[   10.350180] ath: doing EEPROM country->regdmn map search
[   10.350200] ath: country maps to regdmn code: 0x3a
[   10.350208] ath: Country alpha2 being used: US
[   10.350213] ath: Regpair used: 0x3a
[   10.362107] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   10.368122] ieee80211 phy0: Atheros AR9330 Rev:1 mem=0xb8100000, irq=2
[   10.476384] kmodloader: done loading kernel modules from /etc/modules.d/*
[   29.860267] br-lan: port 1(eth0) entered blocking state
[   29.864053] br-lan: port 1(eth0) entered disabled state
[   29.869824] device eth0 entered promiscuous mode
[   60.659610] jffs2: notice: (1615) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[   66.591637] device eth0 left promiscuous mode
[   66.591736] br-lan: port 1(eth0) entered disabled state
[   66.672945] br-lan: port 1(eth0) entered blocking state
[   66.672966] br-lan: port 1(eth0) entered disabled state
[   66.673449] device eth0 entered promiscuous mode
[   68.769594] device eth0 left promiscuous mode
[   68.769690] br-lan: port 1(eth0) entered disabled state
[   69.476943] ath: EEPROM regdomain: 0x8024
[   69.476958] ath: EEPROM indicates we should expect a country code
[   69.476965] ath: doing EEPROM country->regdmn map search
[   69.476971] ath: country maps to regdmn code: 0x21
[   69.476979] ath: Country alpha2 being used: AU
[   69.476984] ath: Regpair used: 0x21
[   69.476994] ath: regdomain 0x8024 dynamically updated by user
[   75.920188] br-lan: port 1(eth0) entered blocking state
[   75.920210] br-lan: port 1(eth0) entered disabled state
[   75.920702] device eth0 entered promiscuous mode
[   76.551599] device eth0 left promiscuous mode
[   76.551699] br-lan: port 1(eth0) entered disabled state
[   84.177585] br-lan: port 1(eth0) entered blocking state
[   84.177606] br-lan: port 1(eth0) entered disabled state
[   84.178124] device eth0 entered promiscuous mode
[  102.645838] random: crng init done
[  102.645862] random: 6 urandom warning(s) missed due to rate limiting

JeremySTX · April 3, 2019, 3:46am

And finally, here is the dmesg log from subsequent (successful) boot:

[    0.000000] Linux version 4.9.164 (jeremy@devtest) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r7405-06a20af) ) #0 Mon Mar 25 09:51:50 2019
[    0.000000] MyLoader: sysp=07c3cb73, boardp=69db762d, parts=24de692d
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019374 (MIPS 24Kc)
[    0.000000] SoC: Atheros AR9330 rev 1
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 04000000 @ 00000000 (usable)
[    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-0x0000000003ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] On node 0 totalpages: 16384
[    0.000000] free_area_init_node: node 0, pgdat 804373e4, node_mem_map 81000020
[    0.000000]   Normal zone: 128 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 16384 pages, LIFO batch:3
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 16256
[    0.000000] Kernel command line:  board=TL-MR3020  console=ttyATH0,115200 rootfstype=squashfs,jffs2 noinitrd
[    0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
[    0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 60100K/65536K available (2991K kernel code, 164K rwdata, 780K rodata, 280K init, 203K bss, 5436K 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] Clocks: CPU:400.000MHz, DDR:400.000MHz, AHB:200.000MHz, Ref:25.000MHz
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 9556302233 ns
[    0.000014] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 10737418237ns
[    0.007568] Calibrating delay loop... 265.42 BogoMIPS (lpj=1327104)
[    0.088749] pid_max: default: 32768 minimum: 301
[    0.093333] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.099505] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.110136] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.118179] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.124880] NET: Registered protocol family 16
[    0.130242] MIPS: machine is TP-LINK TL-MR3020
[    0.425684] clocksource: Switched to clocksource MIPS
[    0.430608] NET: Registered protocol family 2
[    0.434623] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.440094] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.446170] TCP: Hash tables configured (established 1024 bind 1024)
[    0.452348] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.457836] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.464083] NET: Registered protocol family 1
[    0.467999] PCI: CLS 0 bytes, default 32
[    0.474472] Crashlog allocated RAM at address 0x3f00000
[    0.479740] workingset: timestamp_bits=30 max_order=14 bucket_order=0
[    0.494427] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.498673] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.520520] io scheduler noop registered
[    0.522887] io scheduler deadline registered (default)
[    0.528393] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.534450] ar933x-uart: ttyATH0 at MMIO 0x18020000 (irq = 11, base_baud = 1562500) is a AR933X UART
[    0.543091] console [ttyATH0] enabled
[    0.549895] bootconsole [early0] disabled
[    0.562480] m25p80 spi0.0: found w25q128, expected m25p80
[    0.578010] m25p80 spi0.0: w25q128 (16384 Kbytes)
[    0.581620] 5 tp-link partitions found on MTD device spi0.0
[    0.586855] Creating 5 MTD partitions on "spi0.0":
[    0.591613] 0x000000000000-0x000000020000 : "u-boot"
[    0.599280] 0x000000020000-0x0000001655a8 : "kernel"
[    0.605651] 0x0000001655a8-0x000000ff0000 : "rootfs"
[    0.611448] mtd: device 2 (rootfs) set to be root filesystem
[    0.615782] 1 squashfs-split partitions found on MTD device rootfs
[    0.621831] 0x000000700000-0x000000ff0000 : "rootfs_data"
[    0.630217] 0x000000ff0000-0x000001000000 : "art"
[    0.636390] 0x000000020000-0x000000ff0000 : "firmware"
[    0.644324] libphy: Fixed MDIO Bus: probed
[    0.666676] libphy: ag71xx_mdio: probed
[    1.297397] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.1:04 [uid=004dd041, driver=Generic PHY]
[    1.306178] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:MII
[    1.312016] NET: Registered protocol family 17
[    1.315609] 8021q: 802.1Q VLAN Support v1.8
[    1.323043] hctosys: unable to open rtc device (rtc0)
[    1.331384] VFS: Mounted root (squashfs filesystem) readonly on device 31:2.
[    1.339219] Freeing unused kernel memory: 280K
[    1.342198] This architecture does not have kernel memory protection.
[    2.209289] init: Console is alive
[    2.211539] init: - watchdog -
[    2.505700] random: fast init done
[    4.269328] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    4.412269] usbcore: registered new interface driver usbfs
[    4.416506] usbcore: registered new interface driver hub
[    4.421720] usbcore: registered new device driver usb
[    4.432346] ds1302: RTC found.
[    4.434338] rtc_time	: 02:15:17
[    4.434338] rtc_date	: 2019-04-03
[    4.445543] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    4.469000] ar933x_lcd initialized
[    4.470949] ar933x_lcd: ar933x_lcd device registered with major 251
[    4.490324] SCSI subsystem initialized
[    4.499543] ehci-platform: EHCI generic platform driver
[    4.503464] ehci-platform ehci-platform: EHCI Host Controller
[    4.509171] ehci-platform ehci-platform: new USB bus registered, assigned bus number 1
[    4.519096] ehci-platform ehci-platform: irq 3, io mem 0x1b000000
[    4.545741] ehci-platform ehci-platform: USB 2.0 started, EHCI 1.00
[    4.551789] hub 1-0:1.0: USB hub found
[    4.554839] hub 1-0:1.0: 1 port detected
[    4.565578] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    4.572310] ohci-platform: OHCI generic platform driver
[    4.585316] usbcore: registered new interface driver usb-storage
[    4.590898] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    4.601341] init: - preinit -
[    5.227890] usb 1-1: new high-speed USB device number 2 using ehci-platform
[    5.461753] hub 1-1:1.0: USB hub found
[    5.472742] hub 1-1:1.0: 4 ports detected
[    5.546204] random: procd: uninitialized urandom read (4 bytes read)
[    5.876007] usb 1-1.1: new full-speed USB device number 3 using ehci-platform
[    5.967663] jffs2: notice: (386) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[    5.984589] mount_root: switching to jffs2 overlay
[    6.047523] urandom-seed: Seeding with /etc/urandom.seed
[    6.271970] usb 1-1.4: new full-speed USB device number 4 using ehci-platform
[    6.337896] procd: - early -
[    6.339452] procd: - watchdog -
[    6.981968] random: jshn: uninitialized urandom read (4 bytes read)
[    7.125262] procd: - watchdog -
[    7.127735] procd: - ubus -
[    7.626022] random: ubusd: uninitialized urandom read (4 bytes read)
[    7.689498] random: jshn: uninitialized urandom read (4 bytes read)
[    7.711368] procd: - init -
[    8.517833] kmodloader: loading kernel modules from /etc/modules.d/*
[    8.894542] tun: Universal TUN/TAP device driver, 1.6
[    8.898224] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    8.947969] i2c /dev entries driver
[    8.986995] hidraw: raw HID events driver (C) Jiri Kosina
[    9.046814] Bluetooth: Core ver 2.22
[    9.049046] NET: Registered protocol family 31
[    9.053359] Bluetooth: HCI device and connection manager initialized
[    9.059789] Bluetooth: HCI socket layer initialized
[    9.064564] Bluetooth: L2CAP socket layer initialized
[    9.069651] Bluetooth: SCO socket layer initialized
[    9.168067] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[    9.171931] Bluetooth: BNEP filters: protocol multicast
[    9.177236] Bluetooth: BNEP socket layer initialized
[    9.234657] usbcore: registered new interface driver btusb
[    9.249105] usbcore: registered new interface driver cdc_acm
[    9.253320] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    9.287689] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
[    9.294239] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
[    9.321599] Bluetooth: HCI UART driver ver 2.3
[    9.324600] Bluetooth: HCI UART protocol H4 registered
[    9.329822] Bluetooth: HCI UART protocol BCSP registered
[    9.357758] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[    9.362363] Bluetooth: HIDP socket layer initialized
[    9.400425] nf_conntrack version 0.5.0 (1024 buckets, 4096 max)
[    9.625832] Bluetooth: RFCOMM TTY layer initialized
[    9.629300] Bluetooth: RFCOMM socket layer initialized
[    9.634423] Bluetooth: RFCOMM ver 1.11
[    9.662495] usbcore: registered new interface driver usbhid
[    9.666721] usbhid: USB HID core driver
[    9.712050] usbcore: registered new interface driver usbserial
[    9.716677] usbcore: registered new interface driver usbserial_generic
[    9.723090] usbserial: USB Serial support registered for generic
[    9.798312] xt_time: kernel timezone is -0000
[    9.808983] usbcore: registered new interface driver cdc_ether
[    9.889474] usbcore: registered new interface driver ftdi_sio
[    9.893911] usbserial: USB Serial support registered for FTDI USB Serial Device
[    9.918661] ip_tables: (C) 2000-2006 Netfilter Core Team
[    9.956791] usbcore: registered new interface driver keyspan
[    9.961137] usbserial: USB Serial support registered for Keyspan - (without firmware)
[    9.969035] usbserial: USB Serial support registered for Keyspan 1 port adapter
[    9.976286] usbserial: USB Serial support registered for Keyspan 2 port adapter
[    9.983538] usbserial: USB Serial support registered for Keyspan 4 port adapter
[   10.135014] usbcore: registered new interface driver pl2303
[   10.139387] usbserial: USB Serial support registered for pl2303
[   10.145127] pl2303 1-1.1:1.0: pl2303 converter detected
[   10.208175] usb 1-1.1: pl2303 converter now attached to ttyUSB0
[   10.230661] PPP generic driver version 2.4.2
[   10.257640] NET: Registered protocol family 24
[   10.284320] usbcore: registered new interface driver rndis_host
[   10.534128] ath: EEPROM regdomain: 0x0
[   10.534143] ath: EEPROM indicates default country code should be used
[   10.534148] ath: doing EEPROM country->regdmn map search
[   10.534168] ath: country maps to regdmn code: 0x3a
[   10.534176] ath: Country alpha2 being used: US
[   10.534181] ath: Regpair used: 0x3a
[   10.546235] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   10.552159] ieee80211 phy0: Atheros AR9330 Rev:1 mem=0xb8100000, irq=2
[   10.662780] kmodloader: done loading kernel modules from /etc/modules.d/*
[   28.967278] br-lan: port 1(eth0) entered blocking state
[   28.971068] br-lan: port 1(eth0) entered disabled state
[   28.976845] device eth0 entered promiscuous mode
[   30.665874] ath: EEPROM regdomain: 0x8024
[   30.665890] ath: EEPROM indicates we should expect a country code
[   30.665896] ath: doing EEPROM country->regdmn map search
[   30.665903] ath: country maps to regdmn code: 0x21
[   30.665910] ath: Country alpha2 being used: AU
[   30.665916] ath: Regpair used: 0x21
[   30.665925] ath: regdomain 0x8024 dynamically updated by user
[   35.225082] wlan0: authenticate with 0e:02:8e:95:a3:6a
[   35.240534] wlan0: send auth to 0e:02:8e:95:a3:6a (try 1/3)
[   35.258939] wlan0: authenticated
[   35.266086] wlan0: associate with 0e:02:8e:95:a3:6a (try 1/3)
[   35.298320] wlan0: RX AssocResp from 0e:02:8e:95:a3:6a (capab=0x1431 status=0 aid=1)
[   35.304984] wlan0: associated
[   35.421052] wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 0e:02:8e:95:a3:6a
[  103.088057] random: crng init done
[  103.088080] random: 6 urandom warning(s) missed due to rate limiting