OpenWrt Loses Network Device (v19.07) - Needs manually removing and re-adding from interface config

m31d4ri0n · August 6, 2020, 3:03pm

During times of peak speed for the system, OpenWrt appears to bug and reports 'Error: Network Device is not present'.

It is not enough to manually restart the interface. I must edit the interface config > Set a different possible network device > Save & Apply > edit the interface again > set the original network device > Save & Apply.

Then it works.

I've recorded a video of that here:

I've not SSH'd in yet to take a look. I shall do now. I'll try and monitor syslog for the error. Not troubleshooted anything on OpenWrt so advice of where my logs are if they are non-standard would be a big help. I am reaching out to the wider community here as I'm pretty sure this is going to be something I won't know how to fix.

The system:

Raspberry Pi B3+
Huawei E3722 4g Dongle (firmware 21.x.x.x.x stick mode (NCM))

The RPi serves an AP using the built in WIFI chip and uses the 4g Dongle as a WAN interface.

Simple setup, low power, perfect for my needs in my camper van.

m31d4ri0n · August 6, 2020, 3:22pm

Slight update here...

I rebooted the RPi and the issue was present from the beginning this time.

I had to:

Go to interfaces
Change network device
Changed it back; Was then informed it was an unsupported modem
Changed it again
Changed it back.
WORKS haha

Here's a video of the described issue (also attached below are some log dumps:

Kernel Log

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.14.180 (builder@buildhost) (gcc version 7.5.0 (OpenWrt GCC 7.5.0 r11063-85e04e9f46)) #0 SMP Sat May 16 18:32:20 2020
[    0.000000] Boot CPU: AArch64 Processor [410fd034]
[    0.000000] Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
[    0.000000] cma: Reserved 16 MiB at 0x000000003a400000
[    0.000000] On node 0 totalpages: 242688
[    0.000000]   DMA zone: 3792 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 242688 pages, LIFO batch:31
[    0.000000] random: get_random_bytes called from 0xffffff8008680818 with crng_init=0
[    0.000000] percpu: Embedded 18 pages/cpu s36632 r8192 d28904 u73728
[    0.000000] pcpu-alloc: s36632 r8192 d28904 u73728 alloc=18*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 238896
[    0.000000] Kernel command line: 8250.nr_uarts=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 root=/dev/sda2 rootfstype=squashfs,ext4 rootwait
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.000000] Memory: 928576K/970752K available (5182K kernel code, 340K rwdata, 916K rodata, 320K init, 484K bss, 25792K reserved, 16384K cma-reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     modules : 0xffffff8000000000 - 0xffffff8008000000   (   128 MB)
[    0.000000]     vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000   (   250 GB)
[    0.000000]       .text : 0xffffff8008080000 - 0xffffff8008590000   (  5184 KB)
[    0.000000]     .rodata : 0xffffff8008590000 - 0xffffff8008680000   (   960 KB)
[    0.000000]       .init : 0xffffff8008680000 - 0xffffff80086d0000   (   320 KB)
[    0.000000]       .data : 0xffffff80086d0000 - 0xffffff8008725008   (   341 KB)
[    0.000000]        .bss : 0xffffff8008725008 - 0xffffff800879e190   (   485 KB)
[    0.000000]     fixed   : 0xffffffbefe7fb000 - 0xffffffbefec00000   (  4116 KB)
[    0.000000]     PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000   (    16 MB)
[    0.000000]     vmemmap : 0xffffffbf00000000 - 0xffffffc000000000   (     4 GB maximum)
[    0.000000]               0xffffffbf00000000 - 0xffffffbf00ed0000   (    14 MB actual)
[    0.000000]     memory  : 0xffffffc000000000 - 0xffffffc03b400000   (   948 MB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] 	CONFIG_RCU_FANOUT set to non-default value of 32
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000006] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000190] Console: colour dummy device 80x25
[    0.000678] console [tty1] enabled
[    0.000710] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.000741] pid_max: default: 32768 minimum: 301
[    0.000890] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.000926] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.002011] ASID allocator initialised with 32768 entries
[    0.002127] Hierarchical SRCU implementation.
[    0.002694] smp: Bringing up secondary CPUs ...
[    0.003118] Detected VIPT I-cache on CPU1
[    0.003170] CPU1: Booted secondary processor [410fd034]
[    0.003736] Detected VIPT I-cache on CPU2
[    0.003772] CPU2: Booted secondary processor [410fd034]
[    0.004264] Detected VIPT I-cache on CPU3
[    0.004298] CPU3: Booted secondary processor [410fd034]
[    0.004413] smp: Brought up 1 node, 4 CPUs
[    0.004495] SMP: Total of 4 processors activated.
[    0.004513] CPU features: detected: 32-bit EL0 Support
[    0.004537] CPU: All CPU(s) started at EL2
[    0.004571] alternatives: patching kernel code
[    0.013442] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.013491] futex hash table entries: 1024 (order: 5, 131072 bytes)
[    0.014240] pinctrl core: initialized pinctrl subsystem
[    0.015063] NET: Registered protocol family 16
[    0.018929] cpuidle: using governor ladder
[    0.018969] cpuidle: using governor menu
[    0.019210] vdso: 2 pages (1 code @ ffffff8008594000, 1 data @ ffffff80086d4000)
[    0.020637] DMA: preallocated 256 KiB pool for atomic allocations
[    0.020725] Serial: AMBA PL011 UART driver
[    0.022214] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.022628] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
[    0.046331] bcm2835-dma 3f007000.dma: DMA legacy API manager at ffffff800802d000, dmachans=0x1
[    0.048105] SCSI subsystem initialized
[    0.048312] usbcore: registered new interface driver usbfs
[    0.048373] usbcore: registered new interface driver hub
[    0.048439] usbcore: registered new device driver usb
[    0.050064] raspberrypi-firmware soc:firmware: Attached to firmware from 2018-11-23 19:36
[    0.051029] clocksource: Switched to clocksource arch_sys_counter
[    0.055851] NET: Registered protocol family 2
[    0.056435] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[    0.056563] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[    0.056773] TCP: Hash tables configured (established 8192 bind 8192)
[    0.056971] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.057027] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.057223] NET: Registered protocol family 1
[    0.058511] Crashlog allocated RAM at address 0x3f00000
[    0.058666] workingset: timestamp_bits=62 max_order=18 bucket_order=0
[    0.063471] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.065085] io scheduler noop registered
[    0.065105] io scheduler deadline registered
[    0.065216] io scheduler cfq registered (default)
[    0.067123] BCM2708FB: allocated DMA memory fa440000
[    0.067168] BCM2708FB: allocated DMA channel 0 @ ffffff800802d000
[    0.074170] Console: switching to colour frame buffer device 82x26
[    0.078216] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    0.080764] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    0.084740] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    0.094207] brd: module loaded
[    0.099698] loop: module loaded
[    0.101772] libphy: Fixed MDIO Bus: probed
[    0.103502] usbcore: registered new interface driver lan78xx
[    0.105210] usbcore: registered new interface driver smsc95xx
[    0.106809] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    0.133870] dwc_otg 3f980000.usb: base=0x089b0000
[    0.335609] Core Release: 2.80a
[    0.337141] Setting default values for core params
[    0.338685] Finished setting default values for core params
[    0.540414] Using Buffer DMA mode
[    0.541908] Periodic Transfer Interrupt Enhancement - disabled
[    0.543478] Multiprocessor Interrupt Enhancement - disabled
[    0.545078] OTG VER PARAM: 0, OTG VER FLAG: 0
[    0.546668] Dedicated Tx FIFOs mode
[    0.548655] WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0x089c1000 dma = 0xfa454000 len=9024
[    0.551958] FIQ FSM acceleration enabled for :
[    0.551958] Non-periodic Split Transactions
[    0.551958] Periodic Split Transactions
[    0.551958] High-Speed Isochronous Endpoints
[    0.551958] Interrupt/Control Split Transaction hack enabled
[    0.559507] dwc_otg: Microframe scheduler enabled
[    0.559560] WARN::hcd_init_fiq:486: MPHI regs_base at 0x08045000
[    0.561172] dwc_otg 3f980000.usb: DWC OTG Controller
[    0.562740] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    0.564396] dwc_otg 3f980000.usb: irq 15, io mem 0x00000000
[    0.566030] Init: Port Power? op_state=1
[    0.567601] Init: Power Port (0)
[    0.569342] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    0.570977] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.572625] usb usb1: Product: DWC OTG Controller
[    0.574222] usb usb1: Manufacturer: Linux 4.14.180 dwc_otg_hcd
[    0.575823] usb usb1: SerialNumber: 3f980000.usb
[    0.577799] hub 1-0:1.0: USB hub found
[    0.579376] hub 1-0:1.0: 1 port detected
[    0.581333] dwc_otg: FIQ enabled
[    0.581337] dwc_otg: NAK holdoff enabled
[    0.581340] dwc_otg: FIQ split-transaction FSM enabled
[    0.581348] Module dwc_common_port init
[    0.581629] usbcore: registered new interface driver uas
[    0.583218] usbcore: registered new interface driver usb-storage
[    0.584736] mousedev: PS/2 mouse device common for all mice
[    0.586177] i2c /dev entries driver
[    0.588081] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
[    0.589841] bcm2835-cpufreq: min=600000 max=1400000
[    0.591771] sdhci: Secure Digital Host Controller Interface driver
[    0.593340] sdhci: Copyright(c) Pierre Ossman
[    0.595171] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
[    0.597029] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
[    0.598717] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.604226] vchiq: vchiq_init_state: slot_zero = ffffff8008b0c000, is_master = 0
[    0.607391] NET: Registered protocol family 10
[    0.608558] Segment Routing with IPv6
[    0.609321] NET: Registered protocol family 17
[    0.610073] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    0.611621] 8021q: 802.1Q VLAN Support v1.8
[    0.615727] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    0.616632] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 72, base_baud = 0) is a PL011 rev2
[    0.618996] console [ttyS0] disabled
[    0.619843] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 151, base_baud = 31250000) is a 16550
[    0.811077] Indeed it is in host mode hprt0 = 00021501
[    0.811404] console [ttyS0] enabled
[    1.021026] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    1.027218] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
[    1.033042] Indeed it is in host mode hprt0 = 00001101
[    1.037115] mmc-bcm2835 3f300000.mmc: DMA channel allocated
[    1.150293] random: fast init done
[    1.561198] usb 1-1: New USB device found, idVendor=0424, idProduct=2514
[    1.568901] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    1.578605] hub 1-1:1.0: USB hub found
[    1.584480] hub 1-1:1.0: 4 ports detected
[    1.591436] sdhost: log_buf @ ffffff80087e5000 (fa453000)
[    1.632873] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    1.641872] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.650767] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.660778] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    1.671023] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    1.677820] of_cfs_init
[    1.681077] of_cfs_init: OK
[    1.685014] Waiting for root device /dev/sda2...
[    1.765986] mmc1: new high speed SDIO card at address 0001
[    1.911027] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    2.041339] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514
[    2.049831] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.059274] hub 1-1.1:1.0: USB hub found
[    2.064821] hub 1-1.1:1.0: 3 ports detected
[    2.161046] usb 1-1.2: new high-speed USB device number 4 using dwc_otg
[    2.296996] usb 1-1.2: New USB device found, idVendor=18a5, idProduct=0302
[    2.305368] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.314191] usb 1-1.2: Product: STORE N GO
[    2.319680] usb 1-1.2: Manufacturer: Verbatim
[    2.325473] usb 1-1.2: SerialNumber: 07105723398E7593
[    2.332410] usb-storage 1-1.2:1.0: USB Mass Storage device detected
[    2.340411] scsi host0: usb-storage 1-1.2:1.0
[    2.721049] usb 1-1.1.1: new high-speed USB device number 5 using dwc_otg
[    2.851487] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800
[    2.860242] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.002414] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Enabling LEDS
[    3.038227] random: crng init done
[    3.144708] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
[    3.156957] libphy: lan78xx-mdiobus: probed
[    3.165395] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
[    3.353267] scsi 0:0:0:0: Direct-Access     Verbatim STORE N GO       5.00 PQ: 0 ANSI: 6
[    4.239839] sd 0:0:0:0: [sda] 15133248 512-byte logical blocks: (7.75 GB/7.22 GiB)
[    4.251632] sd 0:0:0:0: [sda] Write Protect is off
[    4.258496] sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
[    4.258744] sd 0:0:0:0: [sda] No Caching mode page found
[    4.266156] sd 0:0:0:0: [sda] Assuming drive cache: write through
[    4.278287]  sda: sda1 sda2
[    4.283494] sd 0:0:0:0: [sda] Attached SCSI removable disk
[    4.303971] EXT4-fs (sda2): mounted filesystem without journal. Opts: (null)
[    4.312069] VFS: Mounted root (ext4 filesystem) readonly on device 8:2.
[    4.319961] Freeing unused kernel memory: 320K
[    4.444963] init: Console is alive
[    4.450530] init: - watchdog -
[    4.698227] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    4.729358] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    4.747776] init: - preinit -
[    4.895699] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    4.902510] 8021q: adding VLAN 0 to HW filter on device eth0
[    7.980664] mount_root: mounting /dev/root
[    7.987260] EXT4-fs (sda2): warning: mounting unchecked fs, running e2fsck is recommended
[    8.607728] EXT4-fs (sda2): re-mounted. Opts: (null)
[    8.616611] urandom-seed: Seeding with /etc/urandom.seed
[    8.676039] procd: - early -
[    8.680892] procd: - watchdog -
[    9.355618] procd: - watchdog -
[    9.359820] procd: - ubus -
[    9.514371] procd: - init -
[    9.694336] urngd: v1.0.2 started.
[    9.697975] kmodloader: loading kernel modules from /etc/modules.d/*
[    9.733691] ip6_tables: (C) 2000-2006 Netfilter Core Team
[    9.747834] hidraw: raw HID events driver (C) Jiri Kosina
[    9.758492] bcm2835_alsa bcm2835_alsa: card created with 8 channels
[    9.818528] usbcore: registered new interface driver cdc_ncm
[    9.825665] usbcore: registered new interface driver cdc_wdm
[    9.832733] Loading modules backported from Linux version v4.19.120-0-gfdc072324f3c
[    9.841964] Backport generated by backports.git v4.19.120-1-0-g60c3a249
[    9.850298] usbcore: registered new interface driver huawei_cdc_ncm
[    9.858243] ip_tables: (C) 2000-2006 Netfilter Core Team
[    9.867828] nf_conntrack version 0.5.0 (7680 buckets, 30720 max)
[    9.884500] usbcore: registered new interface driver qmi_wwan
[    9.892604] usbcore: registered new interface driver usbhid
[    9.899003] usbhid: USB HID core driver
[    9.905004] usbcore: registered new interface driver usbserial
[    9.911797] usbcore: registered new interface driver usbserial_generic
[    9.919262] usbserial: USB Serial support registered for generic
[    9.930118] xt_time: kernel timezone is -0000
[    9.949806] PPP generic driver version 2.4.2
[    9.956247] NET: Registered protocol family 24
[    9.971913] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[   10.016990] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt failed with error -2
[   10.031004] brcmfmac mmc1:0001:1: Falling back to user helper
[   10.780066] firmware brcm!brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt: firmware_loading_store: map pages failed
[   10.889731] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[   10.911842] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar  1 2015 07:29:38 version 7.45.18 (r538002) FWID 01-6a2c8ad4
[   10.956731] usbcore: registered new interface driver brcmfmac
[   10.965863] usbcore: registered new interface driver option
[   10.973584] usbserial: USB Serial support registered for GSM modem (1-port)
[   10.985203] kmodloader: done loading kernel modules from /etc/modules.d/*
[   11.941075] usb 1-1.1.2: new high-speed USB device number 6 using dwc_otg
[   12.102114] usb 1-1.1.2: New USB device found, idVendor=12d1, idProduct=14fe
[   12.110472] usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[   12.120370] usb 1-1.1.2: Product: HUAWEI_MOBILE
[   12.126148] usb 1-1.1.2: Manufacturer: HUAWEI_MOBILE
[   12.132331] usb 1-1.1.2: SerialNumber: 0123456789ABCDEF
[   12.184022] usb-storage 1-1.1.2:1.0: USB Mass Storage device detected
[   12.195218] scsi host1: usb-storage 1-1.1.2:1.0
[   12.413364] usb 1-1.1.2: USB disconnect, device number 6
[   12.768531] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   12.775579] 8021q: adding VLAN 0 to HW filter on device eth0
[   13.093644] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   13.221076] usb 1-1.1.2: new high-speed USB device number 7 using dwc_otg
[   13.274947] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   13.351985] usb 1-1.1.2: New USB device found, idVendor=12d1, idProduct=1506
[   13.360092] usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[   13.369534] usb 1-1.1.2: Product: HUAWEI_MOBILE
[   13.375055] usb 1-1.1.2: Manufacturer: HUAWEI_MOBILE
[   13.454621] option 1-1.1.2:1.0: GSM modem (1-port) converter detected
[   13.462509] usb 1-1.1.2: GSM modem (1-port) converter now attached to ttyUSB0
[   13.471322] option 1-1.1.2:1.1: GSM modem (1-port) converter detected
[   13.479503] usb 1-1.1.2: GSM modem (1-port) converter now attached to ttyUSB1
[   13.584658] huawei_cdc_ncm 1-1.1.2:1.2: MAC-Address: 00:1e:10:1f:00:00
[   13.592320] huawei_cdc_ncm 1-1.1.2:1.2: setting rx_max = 16384
[   13.606651] huawei_cdc_ncm 1-1.1.2:1.2: NDP will be placed at end of frame for this device.
[   13.617497] huawei_cdc_ncm 1-1.1.2:1.2: cdc-wdm0: USB WDM device
[   13.625350] huawei_cdc_ncm 1-1.1.2:1.2 wwan0: register 'huawei_cdc_ncm' at usb-3f980000.usb-1.1.2, Huawei CDC NCM device, 00:1e:10:1f:00:00
[   13.646680] usb-storage 1-1.1.2:1.3: USB Mass Storage device detected
[   13.654700] scsi host1: usb-storage 1-1.1.2:1.3
[   13.689658] usb-storage 1-1.1.2:1.4: USB Mass Storage device detected
[   13.697723] scsi host2: usb-storage 1-1.1.2:1.4
[   14.723539] scsi 2:0:0:0: Direct-Access     HUAWEI   TF CARD Storage  2.31 PQ: 0 ANSI: 2
[   14.736436] scsi 1:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
[   14.750510] sd 2:0:0:0: [sdb] Attached SCSI removable disk

m31d4ri0n · August 6, 2020, 3:24pm

System Log before recording video above:

Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] Booting Linux on physical CPU 0x0
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000] Linux version 4.14.180 (builder@buildhost) (gcc version 7.5.0 (OpenWrt GCC 7.5.0 r11063-85e04e9f46)) #0 SMP Sat May 16 18:32:20 2020
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] Boot CPU: AArch64 Processor [410fd034]
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] cma: Reserved 16 MiB at 0x000000003a400000
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    0.000000] On node 0 totalpages: 242688
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    0.000000]   DMA zone: 3792 pages used for memmap
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    0.000000]   DMA zone: 0 pages reserved
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    0.000000]   DMA zone: 242688 pages, LIFO batch:31
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000] random: get_random_bytes called from 0xffffff8008680818 with crng_init=0
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] percpu: Embedded 18 pages/cpu s36632 r8192 d28904 u73728
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    0.000000] pcpu-alloc: s36632 r8192 d28904 u73728 alloc=18*4096
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] Detected VIPT I-cache on CPU0
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 238896
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000] Kernel command line: 8250.nr_uarts=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 root=/dev/sda2 rootfstype=squashfs,ext4 rootwait
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] Memory: 928576K/970752K available (5182K kernel code, 340K rwdata, 916K rodata, 320K init, 484K bss, 25792K reserved, 16384K cma-reserved)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000] Virtual kernel memory layout:
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]     modules : 0xffffff8000000000 - 0xffffff8008000000   (   128 MB)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]     vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000   (   250 GB)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]       .text : 0xffffff8008080000 - 0xffffff8008590000   (  5184 KB)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]     .rodata : 0xffffff8008590000 - 0xffffff8008680000   (   960 KB)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]       .init : 0xffffff8008680000 - 0xffffff80086d0000   (   320 KB)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]       .data : 0xffffff80086d0000 - 0xffffff8008725008   (   341 KB)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]        .bss : 0xffffff8008725008 - 0xffffff800879e190   (   485 KB)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]     fixed   : 0xffffffbefe7fb000 - 0xffffffbefec00000   (  4116 KB)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]     PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000   (    16 MB)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]     vmemmap : 0xffffffbf00000000 - 0xffffffc000000000   (     4 GB maximum)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]               0xffffffbf00000000 - 0xffffffbf00ed0000   (    14 MB actual)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.000000]     memory  : 0xffffffc000000000 - 0xffffffc03b400000   (   948 MB)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] Hierarchical RCU implementation.
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] 	CONFIG_RCU_FANOUT set to non-default value of 32
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000006] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000190] Console: colour dummy device 80x25
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000678] console [tty1] enabled
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000710] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000741] pid_max: default: 32768 minimum: 301
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000890] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.000926] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.002011] ASID allocator initialised with 32768 entries
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.002127] Hierarchical SRCU implementation.
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.002694] smp: Bringing up secondary CPUs ...
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.003118] Detected VIPT I-cache on CPU1
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.003170] CPU1: Booted secondary processor [410fd034]
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.003736] Detected VIPT I-cache on CPU2
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.003772] CPU2: Booted secondary processor [410fd034]
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.004264] Detected VIPT I-cache on CPU3
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.004298] CPU3: Booted secondary processor [410fd034]
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.004413] smp: Brought up 1 node, 4 CPUs
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.004495] SMP: Total of 4 processors activated.
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.004513] CPU features: detected: 32-bit EL0 Support
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.004537] CPU: All CPU(s) started at EL2
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.004571] alternatives: patching kernel code
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.013442] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.013491] futex hash table entries: 1024 (order: 5, 131072 bytes)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.014240] pinctrl core: initialized pinctrl subsystem
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.015063] NET: Registered protocol family 16
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.018929] cpuidle: using governor ladder
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.018969] cpuidle: using governor menu
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.019210] vdso: 2 pages (1 code @ ffffff8008594000, 1 data @ ffffff80086d4000)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.020637] DMA: preallocated 256 KiB pool for atomic allocations
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.020725] Serial: AMBA PL011 UART driver
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.022214] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.022628] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.046331] bcm2835-dma 3f007000.dma: DMA legacy API manager at ffffff800802d000, dmachans=0x1
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    0.048105] SCSI subsystem initialized
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.048312] usbcore: registered new interface driver usbfs
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.048373] usbcore: registered new interface driver hub
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.048439] usbcore: registered new device driver usb
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.050064] raspberrypi-firmware soc:firmware: Attached to firmware from 2018-11-23 19:36
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.051029] clocksource: Switched to clocksource arch_sys_counter
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.055851] NET: Registered protocol family 2
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.056435] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.056563] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.056773] TCP: Hash tables configured (established 8192 bind 8192)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.056971] UDP hash table entries: 512 (order: 2, 16384 bytes)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.057027] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.057223] NET: Registered protocol family 1
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.058511] Crashlog allocated RAM at address 0x3f00000
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.058666] workingset: timestamp_bits=62 max_order=18 bucket_order=0
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.063471] squashfs: version 4.0 (2009/01/31) Phillip Lougher
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.065085] io scheduler noop registered
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.065105] io scheduler deadline registered
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.065216] io scheduler cfq registered (default)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.067123] BCM2708FB: allocated DMA memory fa440000
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.067168] BCM2708FB: allocated DMA channel 0 @ ffffff800802d000
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.074170] Console: switching to colour frame buffer device 82x26
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.078216] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.080764] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.084740] cacheinfo: Unable to detect cache hierarchy for CPU 0
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.094207] brd: module loaded
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.099698] loop: module loaded
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.101772] libphy: Fixed MDIO Bus: probed
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.103502] usbcore: registered new interface driver lan78xx
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.105210] usbcore: registered new interface driver smsc95xx
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.106809] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.133870] dwc_otg 3f980000.usb: base=0x089b0000
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.335609] Core Release: 2.80a
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.337141] Setting default values for core params
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.338685] Finished setting default values for core params
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.540414] Using Buffer DMA mode
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.541908] Periodic Transfer Interrupt Enhancement - disabled
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.543478] Multiprocessor Interrupt Enhancement - disabled
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.545078] OTG VER PARAM: 0, OTG VER FLAG: 0
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.546668] Dedicated Tx FIFOs mode
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.548655] WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0x089c1000 dma = 0xfa454000 len=9024
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.551958] FIQ FSM acceleration enabled for :
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.551958] Non-periodic Split Transactions
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.551958] Periodic Split Transactions
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.551958] High-Speed Isochronous Endpoints
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.551958] Interrupt/Control Split Transaction hack enabled
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    0.559507] dwc_otg: Microframe scheduler enabled
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.559560] WARN::hcd_init_fiq:486: MPHI regs_base at 0x08045000
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.561172] dwc_otg 3f980000.usb: DWC OTG Controller
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.562740] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.564396] dwc_otg 3f980000.usb: irq 15, io mem 0x00000000
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.566030] Init: Port Power? op_state=1
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.567601] Init: Power Port (0)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.569342] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.570977] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.572625] usb usb1: Product: DWC OTG Controller
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.574222] usb usb1: Manufacturer: Linux 4.14.180 dwc_otg_hcd
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.575823] usb usb1: SerialNumber: 3f980000.usb
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.577799] hub 1-0:1.0: USB hub found
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.579376] hub 1-0:1.0: 1 port detected
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    0.581333] dwc_otg: FIQ enabled
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    0.581337] dwc_otg: NAK holdoff enabled
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    0.581340] dwc_otg: FIQ split-transaction FSM enabled
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    0.581348] Module dwc_common_port init
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.581629] usbcore: registered new interface driver uas
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.583218] usbcore: registered new interface driver usb-storage
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.584736] mousedev: PS/2 mouse device common for all mice
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.586177] i2c /dev entries driver
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.588081] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.589841] bcm2835-cpufreq: min=600000 max=1400000
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.591771] sdhci: Secure Digital Host Controller Interface driver
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.593340] sdhci: Copyright(c) Pierre Ossman
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.595171] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.597029] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.598717] sdhci-pltfm: SDHCI platform and OF driver helper
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.604226] vchiq: vchiq_init_state: slot_zero = ffffff8008b0c000, is_master = 0
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.607391] NET: Registered protocol family 10
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.608558] Segment Routing with IPv6
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.609321] NET: Registered protocol family 17
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.610073] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.611621] 8021q: 802.1Q VLAN Support v1.8
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.615727] uart-pl011 3f201000.serial: cts_event_workaround enabled
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.616632] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 72, base_baud = 0) is a PL011 rev2
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.618996] console [ttyS0] disabled
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.619843] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 151, base_baud = 31250000) is a 16550
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    0.811077] Indeed it is in host mode hprt0 = 00021501
Thu Aug  6 15:07:04 2020 kern.info kernel: [    0.811404] console [ttyS0] enabled
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.021026] usb 1-1: new high-speed USB device number 2 using dwc_otg
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.027218] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    1.033042] Indeed it is in host mode hprt0 = 00001101
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.037115] mmc-bcm2835 3f300000.mmc: DMA channel allocated
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    1.150293] random: fast init done
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.561198] usb 1-1: New USB device found, idVendor=0424, idProduct=2514
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.568901] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.578605] hub 1-1:1.0: USB hub found
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.584480] hub 1-1:1.0: 4 ports detected
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.591436] sdhost: log_buf @ ffffff80087e5000 (fa453000)
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    1.632873] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    1.641872] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    1.650767] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    1.660778] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.671023] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.677820] of_cfs_init
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.681077] of_cfs_init: OK
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.685014] Waiting for root device /dev/sda2...
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.765986] mmc1: new high speed SDIO card at address 0001
Thu Aug  6 15:07:04 2020 kern.info kernel: [    1.911027] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.041339] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.049831] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.059274] hub 1-1.1:1.0: USB hub found
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.064821] hub 1-1.1:1.0: 3 ports detected
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.161046] usb 1-1.2: new high-speed USB device number 4 using dwc_otg
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.296996] usb 1-1.2: New USB device found, idVendor=18a5, idProduct=0302
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.305368] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.314191] usb 1-1.2: Product: STORE N GO
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.319680] usb 1-1.2: Manufacturer: Verbatim
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.325473] usb 1-1.2: SerialNumber: 07105723398E7593
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.332410] usb-storage 1-1.2:1.0: USB Mass Storage device detected
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.340411] scsi host0: usb-storage 1-1.2:1.0
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.721049] usb 1-1.1.1: new high-speed USB device number 5 using dwc_otg
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.851487] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800
Thu Aug  6 15:07:04 2020 kern.info kernel: [    2.860242] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Thu Aug  6 15:07:04 2020 kern.info kernel: [    3.002414] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Enabling LEDS
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    3.038227] random: crng init done
Thu Aug  6 15:07:04 2020 kern.info kernel: [    3.144708] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
Thu Aug  6 15:07:04 2020 kern.info kernel: [    3.156957] libphy: lan78xx-mdiobus: probed
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    3.165395] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    3.353267] scsi 0:0:0:0: Direct-Access     Verbatim STORE N GO       5.00 PQ: 0 ANSI: 6
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    4.239839] sd 0:0:0:0: [sda] 15133248 512-byte logical blocks: (7.75 GB/7.22 GiB)
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    4.251632] sd 0:0:0:0: [sda] Write Protect is off
Thu Aug  6 15:07:04 2020 kern.debug kernel: [    4.258496] sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
Thu Aug  6 15:07:04 2020 kern.err kernel: [    4.258744] sd 0:0:0:0: [sda] No Caching mode page found
Thu Aug  6 15:07:04 2020 kern.err kernel: [    4.266156] sd 0:0:0:0: [sda] Assuming drive cache: write through
Thu Aug  6 15:07:04 2020 kern.info kernel: [    4.278287]  sda: sda1 sda2
Thu Aug  6 15:07:04 2020 kern.notice kernel: [    4.283494] sd 0:0:0:0: [sda] Attached SCSI removable disk
Thu Aug  6 15:07:04 2020 kern.info kernel: [    4.303971] EXT4-fs (sda2): mounted filesystem without journal. Opts: (null)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    4.312069] VFS: Mounted root (ext4 filesystem) readonly on device 8:2.
Thu Aug  6 15:07:04 2020 kern.info kernel: [    4.319961] Freeing unused kernel memory: 320K
Thu Aug  6 15:07:04 2020 user.info kernel: [    4.444963] init: Console is alive
Thu Aug  6 15:07:04 2020 user.info kernel: [    4.450530] init: - watchdog -
Thu Aug  6 15:07:04 2020 user.info kernel: [    4.698227] kmodloader: loading kernel modules from /etc/modules-boot.d/*
Thu Aug  6 15:07:04 2020 user.info kernel: [    4.729358] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
Thu Aug  6 15:07:04 2020 user.info kernel: [    4.747776] init: - preinit -
Thu Aug  6 15:07:04 2020 kern.info kernel: [    4.895699] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Thu Aug  6 15:07:04 2020 kern.info kernel: [    4.902510] 8021q: adding VLAN 0 to HW filter on device eth0
Thu Aug  6 15:07:04 2020 user.notice kernel: [    7.980664] mount_root: mounting /dev/root
Thu Aug  6 15:07:04 2020 kern.warn kernel: [    7.987260] EXT4-fs (sda2): warning: mounting unchecked fs, running e2fsck is recommended
Thu Aug  6 15:07:04 2020 kern.info kernel: [    8.607728] EXT4-fs (sda2): re-mounted. Opts: (null)
Thu Aug  6 15:07:04 2020 user.warn kernel: [    8.616611] urandom-seed: Seeding with /etc/urandom.seed
Thu Aug  6 15:07:04 2020 user.info kernel: [    8.676039] procd: - early -
Thu Aug  6 15:07:04 2020 user.info kernel: [    8.680892] procd: - watchdog -
Thu Aug  6 15:07:04 2020 user.info kernel: [    9.355618] procd: - watchdog -
Thu Aug  6 15:07:04 2020 user.info kernel: [    9.359820] procd: - ubus -
Thu Aug  6 15:07:04 2020 user.info kernel: [    9.514371] procd: - init -
Thu Aug  6 15:07:04 2020 user.info kernel: [    9.694336] urngd: v1.0.2 started.
Thu Aug  6 15:07:04 2020 user.info kernel: [    9.697975] kmodloader: loading kernel modules from /etc/modules.d/*
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.733691] ip6_tables: (C) 2000-2006 Netfilter Core Team
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.747834] hidraw: raw HID events driver (C) Jiri Kosina
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.758492] bcm2835_alsa bcm2835_alsa: card created with 8 channels
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.818528] usbcore: registered new interface driver cdc_ncm
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.825665] usbcore: registered new interface driver cdc_wdm
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.832733] Loading modules backported from Linux version v4.19.120-0-gfdc072324f3c
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.841964] Backport generated by backports.git v4.19.120-1-0-g60c3a249
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.850298] usbcore: registered new interface driver huawei_cdc_ncm
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.858243] ip_tables: (C) 2000-2006 Netfilter Core Team
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.867828] nf_conntrack version 0.5.0 (7680 buckets, 30720 max)
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.884500] usbcore: registered new interface driver qmi_wwan
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.892604] usbcore: registered new interface driver usbhid
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.899003] usbhid: USB HID core driver
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.905004] usbcore: registered new interface driver usbserial
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.911797] usbcore: registered new interface driver usbserial_generic
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.919262] usbserial: USB Serial support registered for generic
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.930118] xt_time: kernel timezone is -0000
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.949806] PPP generic driver version 2.4.2
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.956247] NET: Registered protocol family 24
Thu Aug  6 15:07:04 2020 kern.info kernel: [    9.971913] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Thu Aug  6 15:07:04 2020 kern.warn kernel: [   10.016990] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt failed with error -2
Thu Aug  6 15:07:04 2020 kern.warn kernel: [   10.031004] brcmfmac mmc1:0001:1: Falling back to user helper
Thu Aug  6 15:07:04 2020 kern.err kernel: [   10.780066] firmware brcm!brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt: firmware_loading_store: map pages failed

m31d4ri0n · August 6, 2020, 3:24pm

Thu Aug  6 15:07:04 2020 kern.info kernel: [   10.889731] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Thu Aug  6 15:07:04 2020 kern.info kernel: [   10.911842] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar  1 2015 07:29:38 version 7.45.18 (r538002) FWID 01-6a2c8ad4
Thu Aug  6 15:07:04 2020 kern.info kernel: [   10.956731] usbcore: registered new interface driver brcmfmac
Thu Aug  6 15:07:04 2020 kern.info kernel: [   10.965863] usbcore: registered new interface driver option
Thu Aug  6 15:07:04 2020 kern.info kernel: [   10.973584] usbserial: USB Serial support registered for GSM modem (1-port)
Thu Aug  6 15:07:04 2020 user.info kernel: [   10.985203] kmodloader: done loading kernel modules from /etc/modules.d/*
Thu Aug  6 15:07:04 2020 user.notice dnsmasq: DNS rebinding protection is active, will discard upstream RFC1918 responses!
Thu Aug  6 15:07:04 2020 user.notice dnsmasq: Allowing 127.0.0.0/8 responses
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: started, version 2.80 cachesize 150
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: DNS service limited to local subnets
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC no-ID loop-detect inotify dumpfile
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: using local addresses only for domain test
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: using local addresses only for domain onion
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: using local addresses only for domain localhost
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: using local addresses only for domain local
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: using local addresses only for domain invalid
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: using local addresses only for domain bind
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: using nameserver 8.8.4.4#53
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: using nameserver 8.8.8.8#53
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: using nameserver 1.1.1.1#53
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: using local addresses only for domain lan
Thu Aug  6 15:07:05 2020 daemon.warn dnsmasq[863]: no servers found in /tmp/resolv.conf.auto, will retry
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: read /etc/hosts - 4 addresses
Thu Aug  6 15:07:05 2020 daemon.info dnsmasq[863]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses
Thu Aug  6 15:07:05 2020 authpriv.info dropbear[896]: Not backgrounding
Thu Aug  6 15:07:05 2020 kern.info kernel: [   11.941075] usb 1-1.1.2: new high-speed USB device number 6 using dwc_otg
Thu Aug  6 15:07:05 2020 user.notice : Added device handler type: 8021ad
Thu Aug  6 15:07:05 2020 user.notice : Added device handler type: 8021q
Thu Aug  6 15:07:05 2020 user.notice : Added device handler type: macvlan
Thu Aug  6 15:07:05 2020 user.notice : Added device handler type: veth
Thu Aug  6 15:07:05 2020 user.notice : Added device handler type: bridge
Thu Aug  6 15:07:05 2020 user.notice : Added device handler type: Network device
Thu Aug  6 15:07:05 2020 user.notice : Added device handler type: tunnel
Thu Aug  6 15:07:05 2020 kern.info kernel: [   12.102114] usb 1-1.1.2: New USB device found, idVendor=12d1, idProduct=14fe
Thu Aug  6 15:07:05 2020 kern.info kernel: [   12.110472] usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Thu Aug  6 15:07:05 2020 kern.info kernel: [   12.120370] usb 1-1.1.2: Product: HUAWEI_MOBILE
Thu Aug  6 15:07:05 2020 kern.info kernel: [   12.126148] usb 1-1.1.2: Manufacturer: HUAWEI_MOBILE
Thu Aug  6 15:07:05 2020 kern.info kernel: [   12.132331] usb 1-1.1.2: SerialNumber: 0123456789ABCDEF
Thu Aug  6 15:07:05 2020 kern.info kernel: [   12.184022] usb-storage 1-1.1.2:1.0: USB Mass Storage device detected
Thu Aug  6 15:07:05 2020 kern.info kernel: [   12.195218] scsi host1: usb-storage 1-1.1.2:1.0
Thu Aug  6 15:07:05 2020 user.info adblock-4.0.6[1031]: adblock instance started ::: action: start, priority: 0, pid: 1031
Thu Aug  6 15:07:05 2020 daemon.notice procd: /etc/rc.d/S50uhttpd: 4+0 records in
Thu Aug  6 15:07:05 2020 daemon.notice procd: /etc/rc.d/S50uhttpd: 4+0 records out
Thu Aug  6 15:07:05 2020 kern.info kernel: [   12.413364] usb 1-1.1.2: USB disconnect, device number 6
Thu Aug  6 15:07:05 2020 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp
Thu Aug  6 15:07:05 2020 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/radvd
Thu Aug  6 15:07:05 2020 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network
Thu Aug  6 15:07:06 2020 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/luci-splash
Thu Aug  6 15:07:06 2020 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/qos
Thu Aug  6 15:07:06 2020 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/miniupnpd
Thu Aug  6 15:07:06 2020 user.notice ucitrack: Setting up /etc/config/dhcp reload dependency on /etc/config/odhcpd
Thu Aug  6 15:07:06 2020 daemon.warn netifd: You have delegated IPv6-prefixes but haven't assigned them to any interface. Did you forget to set option ip6assign on your lan-interfaces?
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'WAN' is setting up now
Thu Aug  6 15:07:06 2020 user.notice ucitrack: Setting up non-init /etc/config/fstab reload handler: /sbin/block mount
Thu Aug  6 15:07:06 2020 kern.info kernel: [   12.768531] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Thu Aug  6 15:07:06 2020 kern.info kernel: [   12.775579] 8021q: adding VLAN 0 to HW filter on device eth0
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'lan' is enabled
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'loopback' is enabled
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'loopback' is setting up now
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'loopback' is now up
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Network device 'lo' link is up
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'loopback' has link connectivity
Thu Aug  6 15:07:06 2020 daemon.notice netifd: WAN (1444): Control device not valid
Thu Aug  6 15:07:06 2020 user.notice ucitrack: Setting up /etc/config/system reload trigger for non-procd /etc/init.d/led
Thu Aug  6 15:07:06 2020 daemon.notice netifd: WAN (1462): Stopping network WAN
Thu Aug  6 15:07:06 2020 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/luci_statistics
Thu Aug  6 15:07:06 2020 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/dhcp
Thu Aug  6 15:07:06 2020 daemon.notice netifd: radio0 (1453): command failed: No error information (-524)
Thu Aug  6 15:07:06 2020 user.notice mac80211: Failed command: iw dev wlan0 del
Thu Aug  6 15:07:06 2020 daemon.notice netifd: WAN (1462): Can't open device /dev/ttyUSB0.
Thu Aug  6 15:07:06 2020 daemon.notice netifd: WAN (1462): Failed to disconnect
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'WAN' is now down
Thu Aug  6 15:07:06 2020 user.notice mac80211: Failed command: iw phy phy0 set antenna 0xffffffff 0xffffffff
Thu Aug  6 15:07:06 2020 daemon.notice netifd: radio0 (1453): command failed: Not supported (-95)
Thu Aug  6 15:07:06 2020 user.notice mac80211: Failed command: iw phy phy0 set antenna_gain 0
Thu Aug  6 15:07:06 2020 daemon.notice netifd: radio0 (1453): command failed: I/O error (-5)
Thu Aug  6 15:07:06 2020 user.notice mac80211: Failed command: iw phy phy0 set distance 0
Thu Aug  6 15:07:06 2020 daemon.notice netifd: radio0 (1453): command failed: Too many open files in system (-23)
Thu Aug  6 15:07:06 2020 user.notice mac80211: Failed command: iw phy phy0 interface add wlan0 type __ap
Thu Aug  6 15:07:06 2020 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.093644] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Thu Aug  6 15:07:06 2020 daemon.info procd: - init complete -
Thu Aug  6 15:07:06 2020 daemon.warn hostapd: wlan0: Could not connect to kernel driver
Thu Aug  6 15:07:06 2020 daemon.err hostapd: Using interface wlan0 with hwaddr b8:27:eb:96:b5:39 and ssid "Swift"
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.221076] usb 1-1.1.2: new high-speed USB device number 7 using dwc_otg
Thu Aug  6 15:07:06 2020 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->ENABLED
Thu Aug  6 15:07:06 2020 daemon.notice hostapd: wlan0: AP-ENABLED
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.274947] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Network device 'wlan0' link is up
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'WLAN' is enabled
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'WLAN' is setting up now
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'WLAN' is now up
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'WLAN' has link connectivity
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.351985] usb 1-1.1.2: New USB device found, idVendor=12d1, idProduct=1506
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.360092] usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.369534] usb 1-1.1.2: Product: HUAWEI_MOBILE
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.375055] usb 1-1.1.2: Manufacturer: HUAWEI_MOBILE
Thu Aug  6 15:07:06 2020 user.notice firewall: Reloading firewall due to ifup of WLAN (wlan0)
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.454621] option 1-1.1.2:1.0: GSM modem (1-port) converter detected
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.462509] usb 1-1.1.2: GSM modem (1-port) converter now attached to ttyUSB0
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.471322] option 1-1.1.2:1.1: GSM modem (1-port) converter detected
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.479503] usb 1-1.1.2: GSM modem (1-port) converter now attached to ttyUSB1
Thu Aug  6 15:07:06 2020 daemon.notice netifd: Interface 'WAN' is setting up now
Thu Aug  6 15:07:06 2020 daemon.notice netifd: WAN (1721): ls: /sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/ttyUSB0/../../*/net: No such file or directory
Thu Aug  6 15:07:06 2020 daemon.notice netifd: WAN (1721): The interface could not be found.
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.584658] huawei_cdc_ncm 1-1.1.2:1.2: MAC-Address: 00:1e:10:1f:00:00
Thu Aug  6 15:07:06 2020 kern.info kernel: [   13.592320] huawei_cdc_ncm 1-1.1.2:1.2: setting rx_max = 16384
Thu Aug  6 15:07:06 2020 daemon.notice netifd: WAN (1745): Stopping network WAN
Thu Aug  6 15:07:07 2020 kern.info kernel: [   13.606651] huawei_cdc_ncm 1-1.1.2:1.2: NDP will be placed at end of frame for this device.
Thu Aug  6 15:07:07 2020 kern.info kernel: [   13.617497] huawei_cdc_ncm 1-1.1.2:1.2: cdc-wdm0: USB WDM device
Thu Aug  6 15:07:07 2020 kern.info kernel: [   13.625350] huawei_cdc_ncm 1-1.1.2:1.2 wwan0: register 'huawei_cdc_ncm' at usb-3f980000.usb-1.1.2, Huawei CDC NCM device, 00:1e:10:1f:00:00
Thu Aug  6 15:07:07 2020 kern.info kernel: [   13.646680] usb-storage 1-1.1.2:1.3: USB Mass Storage device detected
Thu Aug  6 15:07:07 2020 kern.info kernel: [   13.654700] scsi host1: usb-storage 1-1.1.2:1.3
Thu Aug  6 15:07:07 2020 kern.info kernel: [   13.689658] usb-storage 1-1.1.2:1.4: USB Mass Storage device detected
Thu Aug  6 15:07:07 2020 kern.info kernel: [   13.697723] scsi host2: usb-storage 1-1.1.2:1.4
Thu Aug  6 15:07:07 2020 daemon.notice netifd: WAN (1745): sending ->
Thu Aug  6 15:07:08 2020 kern.notice kernel: [   14.723539] scsi 2:0:0:0: Direct-Access     HUAWEI   TF CARD Storage  2.31 PQ: 0 ANSI: 2
Thu Aug  6 15:07:08 2020 kern.notice kernel: [   14.736436] scsi 1:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
Thu Aug  6 15:07:08 2020 kern.notice kernel: [   14.750510] sd 2:0:0:0: [sdb] Attached SCSI removable disk
Thu Aug  6 15:07:11 2020 user.info adblock-4.0.6[1031]: download of 'disconnect' failed, url: https://s3.amazonaws.com/lists.disconnect.me/simple_malvertising.txt, rule: /^([[:alnum:]_-]{1,63}\.)+[[:alpha:]]+([[:space:]]|$)/{print tolower($1)}, categories: -, rc: 6, log: curl: (6) Couldn't resolve host 's3.amazonaws.com'
Thu Aug  6 15:07:11 2020 user.info adblock-4.0.6[1031]: download of 'adguard' failed, url: https://filters.adtidy.org/windows/filters/15.txt, rule: BEGIN{FS="[/|^|\r]"}/^\|\|([[:alnum:]_-]{1,63}\.)+[[:alpha:]]+[\/\^\r]+$/{print tolower($3)}, categories: -, rc: 6, log: curl: (6) Couldn't resolve host 'filters.adtidy.org'
Thu Aug  6 15:07:11 2020 user.info adblock-4.0.6[1031]: download of 'yoyo' failed, url: https://pgl.yoyo.org/adservers/serverlist.php?hostformat=nohtml&showintro=0&mimetype=plaintext, rule: /^([[:alnum:]_-]{1,63}\.)+[[:alpha:]]+([[:space:]]|$)/{print tolower($1)}, categories: -, rc: 6, log: curl: (6) Couldn't resolve host 'pgl.yoyo.org'
Thu Aug  6 15:07:11 2020 user.info adblock-4.0.6[1031]: download of 'adaway' failed, url: https://raw.githubusercontent.com/AdAway/adaway.github.io/master/hosts.txt, rule: /^127\.0\.0\.1[[:space:]]+([[:alnum:]_-]{1,63}\.)+[[:alpha:]]+([[:space:]]|$)/{print tolower($2)}, categories: -, rc: 6, log: curl: (6) Couldn't resolve host 'raw.githubusercontent.com'
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[863]: exiting on receipt of SIGTERM
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: started, version 2.80 cachesize 150
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: DNS service limited to local subnets
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC no-ID loop-detect inotify dumpfile
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq-dhcp[2203]: DHCP, IP range 192.168.0.100 -- 192.168.0.249, lease time 12h
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: using local addresses only for domain test
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: using local addresses only for domain onion
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: using local addresses only for domain localhost
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: using local addresses only for domain local
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: using local addresses only for domain invalid
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: using local addresses only for domain bind
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: using nameserver 8.8.4.4#53
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: using nameserver 8.8.8.8#53
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: using nameserver 1.1.1.1#53
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: using local addresses only for domain lan
Thu Aug  6 15:07:11 2020 daemon.warn dnsmasq[2203]: no servers found in /tmp/resolv.conf.auto, will retry
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: read /etc/hosts - 4 addresses
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: read /tmp/hosts/dhcp.cfg01411c - 1 addresses
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq-dhcp[2203]: read /etc/ethers - 0 addresses
Thu Aug  6 15:07:11 2020 daemon.info dnsmasq[2203]: exiting on receipt of SIGTERM
Thu Aug  6 15:07:11 2020 user.notice dnsmasq: DNS rebinding protection is active, will discard upstream RFC1918 responses!
Thu Aug  6 15:07:11 2020 user.notice dnsmasq: Allowing 127.0.0.0/8 responses
Thu Aug  6 15:07:11 2020 daemon.info hostapd: wlan0: STA 20:c9:d0:bf:cd:d1 IEEE 802.11: associated
Thu Aug  6 15:07:11 2020 daemon.notice hostapd: wlan0: AP-STA-CONNECTED 20:c9:d0:bf:cd:d1
Thu Aug  6 15:07:11 2020 daemon.info hostapd: wlan0: STA 20:c9:d0:bf:cd:d1 WPA: pairwise key handshake completed (RSN)
Thu Aug  6 15:07:11 2020 daemon.notice netifd: Interface 'WAN' is now down
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: started, version 2.80 cachesize 150
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: DNS service limited to local subnets
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC no-ID loop-detect inotify dumpfile
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq-dhcp[2345]: DHCP, IP range 192.168.0.100 -- 192.168.0.249, lease time 12h
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: using local addresses only for domain test
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: using local addresses only for domain onion
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: using local addresses only for domain localhost
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: using local addresses only for domain local
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: using local addresses only for domain invalid
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: using local addresses only for domain bind
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: using nameserver 8.8.4.4#53
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: using nameserver 8.8.8.8#53
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: using nameserver 1.1.1.1#53
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: using local addresses only for domain lan
Thu Aug  6 15:07:14 2020 daemon.warn dnsmasq[2345]: no servers found in /tmp/resolv.conf.auto, will retry
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: read /etc/hosts - 4 addresses
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq[2345]: read /tmp/hosts/dhcp.cfg01411c - 1 addresses
Thu Aug  6 15:07:14 2020 daemon.info dnsmasq-dhcp[2345]: read /etc/ethers - 0 addresses
Thu Aug  6 15:07:15 2020 daemon.info dnsmasq-dhcp[2345]: DHCPREQUEST(wlan0) 192.168.0.158 20:c9:d0:bf:cd:d1
Thu Aug  6 15:07:15 2020 daemon.info dnsmasq-dhcp[2345]: DHCPACK(wlan0) 192.168.0.158 20:c9:d0:bf:cd:d1 macac
Thu Aug  6 15:07:17 2020 daemon.err uhttpd[1268]: luci: accepted login on / for root from 192.168.0.158

System Log after recording video above:

Thu Aug  6 15:09:21 2020 user.err adblock-4.0.6[1031]: dns backend restart with adblock blocklist failed
Thu Aug  6 15:09:21 2020 daemon.info dnsmasq[2345]: exiting on receipt of SIGTERM
Thu Aug  6 15:09:21 2020 user.notice dnsmasq: DNS rebinding protection is active, will discard upstream RFC1918 responses!
Thu Aug  6 15:09:21 2020 user.notice dnsmasq: Allowing 127.0.0.0/8 responses
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: started, version 2.80 cachesize 150
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: DNS service limited to local subnets
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC no-ID loop-detect inotify dumpfile
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq-dhcp[2745]: DHCP, IP range 192.168.0.100 -- 192.168.0.249, lease time 12h
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: using local addresses only for domain test
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: using local addresses only for domain onion
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: using local addresses only for domain localhost
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: using local addresses only for domain local
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: using local addresses only for domain invalid
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: using local addresses only for domain bind
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: using nameserver 8.8.4.4#53
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: using nameserver 8.8.8.8#53
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: using nameserver 1.1.1.1#53
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: using local addresses only for domain lan
Thu Aug  6 15:09:24 2020 daemon.warn dnsmasq[2745]: no servers found in /tmp/resolv.conf.auto, will retry
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: read /etc/hosts - 4 addresses
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq[2745]: read /tmp/hosts/dhcp.cfg01411c - 1 addresses
Thu Aug  6 15:09:24 2020 daemon.info dnsmasq-dhcp[2745]: read /etc/ethers - 0 addresses
Thu Aug  6 15:10:53 2020 daemon.notice netifd: Interface 'WAN' is setting up now
Thu Aug  6 15:10:55 2020 daemon.notice netifd: WAN (2993): Configuring modem
Thu Aug  6 15:10:55 2020 daemon.notice netifd: WAN (2993): Starting network WAN
Thu Aug  6 15:10:55 2020 daemon.notice netifd: WAN (2993): Connecting modem
Thu Aug  6 15:10:56 2020 daemon.notice netifd: WAN (2993): sending ->
Thu Aug  6 15:11:21 2020 daemon.notice netifd: WAN (2993): Timeout running AT-command
Thu Aug  6 15:11:21 2020 daemon.notice netifd: WAN (2993): Failed to connect
Thu Aug  6 15:11:21 2020 daemon.notice netifd: WAN (3113): Stopping network WAN
Thu Aug  6 15:11:21 2020 daemon.notice netifd: WAN (3113): sending ->
Thu Aug  6 15:11:26 2020 daemon.notice netifd: Interface 'WAN' is now down
Thu Aug  6 15:11:26 2020 daemon.notice netifd: Interface 'WAN' is setting up now
Thu Aug  6 15:11:28 2020 daemon.notice netifd: WAN (3135): Configuring modem
Thu Aug  6 15:11:28 2020 daemon.notice netifd: WAN (3135): Starting network WAN
Thu Aug  6 15:11:28 2020 daemon.notice netifd: WAN (3135): Connecting modem
Thu Aug  6 15:11:28 2020 daemon.notice netifd: WAN (3135): sending ->
Thu Aug  6 15:11:39 2020 daemon.notice netifd: WAN (3218): Stopping network WAN
Thu Aug  6 15:11:39 2020 daemon.notice netifd: WAN (3218): sending ->
Thu Aug  6 15:11:44 2020 daemon.notice netifd: Interface 'WAN' is now down
Thu Aug  6 15:11:44 2020 daemon.notice netifd: Interface 'WAN' is setting up now
Thu Aug  6 15:11:46 2020 daemon.notice netifd: WAN (3252): WARNING: Variable '^hcsq:"lte",32,26,131,24' does not exist or is not an array/object
Thu Aug  6 15:11:46 2020 daemon.notice netifd: WAN (3252): Unsupported modem
Thu Aug  6 15:11:46 2020 daemon.notice netifd: WAN (3313): Stopping network WAN
Thu Aug  6 15:11:46 2020 daemon.notice netifd: WAN (3313): sending ->
Thu Aug  6 15:11:51 2020 daemon.notice netifd: Interface 'WAN' is now down
Thu Aug  6 15:11:55 2020 daemon.info hostapd: wlan0: STA 78:7b:8a:a4:e0:10 IEEE 802.11: associated
Thu Aug  6 15:11:55 2020 daemon.notice hostapd: wlan0: AP-STA-CONNECTED 78:7b:8a:a4:e0:10
Thu Aug  6 15:11:55 2020 daemon.info hostapd: wlan0: STA 78:7b:8a:a4:e0:10 WPA: pairwise key handshake completed (RSN)
Thu Aug  6 15:11:55 2020 daemon.info dnsmasq-dhcp[2745]: DHCPREQUEST(wlan0) 192.168.0.113 78:7b:8a:a4:e0:10
Thu Aug  6 15:11:55 2020 daemon.info dnsmasq-dhcp[2745]: DHCPACK(wlan0) 192.168.0.113 78:7b:8a:a4:e0:10 Davids-iPad
Thu Aug  6 15:12:18 2020 daemon.notice netifd: Interface 'WAN' is setting up now
Thu Aug  6 15:12:20 2020 daemon.notice netifd: WAN (3385): sending -> AT
Thu Aug  6 15:12:21 2020 daemon.notice netifd: WAN (3385): sending -> ATZ
Thu Aug  6 15:12:21 2020 daemon.notice netifd: WAN (3385): sending -> ATQ0
Thu Aug  6 15:12:22 2020 daemon.notice netifd: WAN (3385): sending -> ATV1
Thu Aug  6 15:12:22 2020 daemon.notice netifd: WAN (3385): sending -> ATE1
Thu Aug  6 15:12:23 2020 daemon.notice netifd: WAN (3385): sending -> ATS0=0
Thu Aug  6 15:12:24 2020 daemon.notice netifd: WAN (3385): sending -> AT+CGDCONT=1,"IP","3internet"
Thu Aug  6 15:12:24 2020 daemon.notice netifd: WAN (3385): Configuring modem
Thu Aug  6 15:12:24 2020 daemon.notice netifd: WAN (3385): Starting network WAN
Thu Aug  6 15:12:24 2020 daemon.notice netifd: WAN (3385): Connecting modem
Thu Aug  6 15:12:25 2020 daemon.notice netifd: WAN (3385): sending -> AT^NDISDUP=1,1,"3internet"
Thu Aug  6 15:12:25 2020 daemon.notice netifd: WAN (3385): Setting up wwan0
Thu Aug  6 15:12:25 2020 daemon.notice netifd: Interface 'WAN' is now up
Thu Aug  6 15:12:25 2020 daemon.notice netifd: Network device 'wwan0' link is up
Thu Aug  6 15:12:25 2020 daemon.notice netifd: Network alias 'wwan0' link is up
Thu Aug  6 15:12:25 2020 daemon.notice netifd: Interface 'WAN_4' is enabled
Thu Aug  6 15:12:25 2020 daemon.notice netifd: Interface 'WAN_4' has link connectivity
Thu Aug  6 15:12:25 2020 daemon.notice netifd: Interface 'WAN_4' is setting up now
Thu Aug  6 15:12:25 2020 daemon.notice netifd: WAN_4 (3470): udhcpc: started, v1.30.1
Thu Aug  6 15:12:25 2020 user.notice firewall: Reloading firewall due to ifup of WAN (wwan0)
Thu Aug  6 15:12:26 2020 daemon.notice netifd: WAN_4 (3470): udhcpc: sending discover
Thu Aug  6 15:12:29 2020 daemon.notice netifd: WAN_4 (3470): udhcpc: sending discover
Thu Aug  6 15:12:32 2020 daemon.notice netifd: WAN_4 (3470): udhcpc: sending discover
Thu Aug  6 15:12:52 2020 daemon.notice netifd: WAN (3541): Stopping network WAN
Thu Aug  6 15:12:52 2020 daemon.notice netifd: WAN_4 (3470): udhcpc: received SIGTERM
Thu Aug  6 15:12:52 2020 daemon.notice netifd: Interface 'WAN_4' is now down
Thu Aug  6 15:12:52 2020 daemon.notice netifd: Network alias '' link is down
Thu Aug  6 15:12:52 2020 daemon.notice netifd: Interface 'WAN_4' has link connectivity loss
Thu Aug  6 15:12:52 2020 daemon.notice netifd: Interface 'WAN_4' is disabled
Thu Aug  6 15:12:53 2020 daemon.notice netifd: WAN (3541): sending -> AT^NDISDUP=1,0
Thu Aug  6 15:12:53 2020 daemon.notice netifd: WAN (3541): Command failed: Permission denied
Thu Aug  6 15:12:53 2020 daemon.notice netifd: Interface 'WAN' is now down
Thu Aug  6 15:12:53 2020 daemon.notice netifd: Interface 'WAN' is setting up now
Thu Aug  6 15:12:55 2020 daemon.notice netifd: WAN (3586): sending -> AT
Thu Aug  6 15:12:56 2020 daemon.notice netifd: WAN (3586): sending -> ATZ
Thu Aug  6 15:12:56 2020 daemon.notice netifd: WAN (3586): sending -> ATQ0
Thu Aug  6 15:12:57 2020 daemon.notice netifd: WAN (3586): sending -> ATV1
Thu Aug  6 15:12:58 2020 daemon.notice netifd: WAN (3586): sending -> ATE1
Thu Aug  6 15:12:58 2020 daemon.notice netifd: WAN (3586): sending -> ATS0=0
Thu Aug  6 15:12:59 2020 daemon.notice netifd: WAN (3586): sending -> AT+CGDCONT=1,"IP","3internet"
Thu Aug  6 15:13:00 2020 daemon.notice netifd: WAN (3586): Configuring modem
Thu Aug  6 15:13:00 2020 daemon.notice netifd: WAN (3586): Starting network WAN
Thu Aug  6 15:13:00 2020 daemon.notice netifd: WAN (3586): Connecting modem
Thu Aug  6 15:13:00 2020 daemon.notice netifd: WAN (3586): sending -> AT^NDISDUP=1,1,"3internet"
Thu Aug  6 15:13:01 2020 daemon.notice netifd: WAN (3586): Setting up wwan0
Thu Aug  6 15:13:01 2020 daemon.notice netifd: Interface 'WAN' is now up
Thu Aug  6 15:13:01 2020 daemon.notice netifd: Network device 'wwan0' link is up
Thu Aug  6 15:13:01 2020 daemon.notice netifd: Network alias 'wwan0' link is up
Thu Aug  6 15:13:01 2020 daemon.notice netifd: Interface 'WAN_4' is enabled
Thu Aug  6 15:13:01 2020 daemon.notice netifd: Interface 'WAN_4' has link connectivity
Thu Aug  6 15:13:01 2020 daemon.notice netifd: Interface 'WAN_4' is setting up now
Thu Aug  6 15:13:01 2020 daemon.notice netifd: WAN_4 (3680): udhcpc: started, v1.30.1
Thu Aug  6 15:13:01 2020 user.notice firewall: Reloading firewall due to ifup of WAN (wwan0)
Thu Aug  6 15:13:01 2020 daemon.notice netifd: WAN_4 (3680): udhcpc: sending discover
Thu Aug  6 15:13:04 2020 daemon.notice netifd: WAN_4 (3680): udhcpc: sending discover
Thu Aug  6 15:13:04 2020 daemon.notice netifd: WAN_4 (3680): udhcpc: sending select for 92.40.27.191
Thu Aug  6 15:13:04 2020 daemon.notice netifd: WAN_4 (3680): udhcpc: lease of 92.40.27.191 obtained, lease time 518400
Thu Aug  6 15:13:04 2020 daemon.notice netifd: Interface 'WAN_4' is now up
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: reading /tmp/resolv.conf.auto
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using local addresses only for domain test
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using local addresses only for domain onion
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using local addresses only for domain localhost
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using local addresses only for domain local
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using local addresses only for domain invalid
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using local addresses only for domain bind
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using nameserver 8.8.4.4#53
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using nameserver 8.8.8.8#53
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using nameserver 1.1.1.1#53
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using local addresses only for domain lan
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using nameserver 217.171.132.0#53
Thu Aug  6 15:13:04 2020 daemon.info dnsmasq[2745]: using nameserver 217.171.135.0#53
Thu Aug  6 15:13:04 2020 user.notice firewall: Reloading firewall due to ifup of WAN_4 (wwan0)

m31d4ri0n · August 7, 2020, 8:34am

Here we can see the moment the network device is lost. I repeat the same method as before to get it operational again. I cannot understand why the device is being lost, nor why when it's back it's not used automatically and needs manual intervention

OPEN TO SUGGESTIONS

Thu Aug  6 15:57:29 2020 kern.info kernel: [ 2964.605288] usb 1-1.1.2: USB disconnect, device number 7
Thu Aug  6 15:57:29 2020 kern.info kernel: [ 2964.613220] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Thu Aug  6 15:57:29 2020 kern.info kernel: [ 2964.625484] option 1-1.1.2:1.0: device disconnected
Thu Aug  6 15:57:29 2020 kern.info kernel: [ 2964.631974] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
Thu Aug  6 15:57:29 2020 kern.info kernel: [ 2964.642399] option 1-1.1.2:1.1: device disconnected
Thu Aug  6 15:57:29 2020 kern.info kernel: [ 2964.648772] huawei_cdc_ncm 1-1.1.2:1.2 wwan0: unregister 'huawei_cdc_ncm' usb-3f980000.usb-1.1.2, Huawei CDC NCM device
Thu Aug  6 15:57:29 2020 daemon.notice netifd: Network device 'wwan0' link is down
Thu Aug  6 15:57:29 2020 daemon.notice netifd: Network alias 'wwan0' link is down
Thu Aug  6 15:57:29 2020 daemon.notice netifd: Interface 'WAN_4' has link connectivity loss
Thu Aug  6 15:57:29 2020 daemon.notice netifd: WAN (3998): Stopping network WAN
Thu Aug  6 15:57:29 2020 daemon.notice netifd: WAN_4 (3680): udhcpc: SIOCGIFINDEX: No such device
Thu Aug  6 15:57:29 2020 daemon.notice netifd: WAN_4 (3680): udhcpc: received SIGTERM
Thu Aug  6 15:57:29 2020 daemon.notice netifd: Interface 'WAN_4' is now down
Thu Aug  6 15:57:29 2020 daemon.notice netifd: Interface 'WAN_4' is disabled
Thu Aug  6 15:57:29 2020 daemon.notice netifd: WAN (3998): Can't open device /dev/ttyUSB0.
Thu Aug  6 15:57:29 2020 daemon.notice netifd: WAN (3998): Failed to disconnect
Thu Aug  6 15:57:29 2020 daemon.warn dnsmasq[2745]: no servers found in /tmp/resolv.conf.auto, will retry
Thu Aug  6 15:57:29 2020 daemon.notice netifd: Interface 'WAN' is now down
Thu Aug  6 15:57:38 2020 kern.info kernel: [ 2974.392206] usb 1-1.1.2: new high-speed USB device number 8 using dwc_otg
Thu Aug  6 15:57:39 2020 kern.info kernel: [ 2974.533221] usb 1-1.1.2: New USB device found, idVendor=12d1, idProduct=14fe
Thu Aug  6 15:57:39 2020 kern.info kernel: [ 2974.542487] usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Thu Aug  6 15:57:39 2020 kern.info kernel: [ 2974.554081] usb 1-1.1.2: Product: HUAWEI_MOBILE
Thu Aug  6 15:57:39 2020 kern.info kernel: [ 2974.560689] usb 1-1.1.2: Manufacturer: HUAWEI_MOBILE
Thu Aug  6 15:57:39 2020 kern.info kernel: [ 2974.567717] usb 1-1.1.2: SerialNumber: 0123456789ABCDEF
Thu Aug  6 15:57:39 2020 kern.info kernel: [ 2974.639048] usb-storage 1-1.1.2:1.0: USB Mass Storage device detected
Thu Aug  6 15:57:39 2020 kern.info kernel: [ 2974.648122] scsi host1: usb-storage 1-1.1.2:1.0
Thu Aug  6 15:57:39 2020 kern.info kernel: [ 2974.845320] usb 1-1.1.2: USB disconnect, device number 8
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2975.652191] usb 1-1.1.2: new high-speed USB device number 9 using dwc_otg
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2975.782874] usb 1-1.1.2: New USB device found, idVendor=12d1, idProduct=1506
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2975.791912] usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2975.803176] usb 1-1.1.2: Product: HUAWEI_MOBILE
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2975.809611] usb 1-1.1.2: Manufacturer: HUAWEI_MOBILE
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2975.928711] option 1-1.1.2:1.0: GSM modem (1-port) converter detected
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2975.936575] usb 1-1.1.2: GSM modem (1-port) converter now attached to ttyUSB0
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2975.945225] option 1-1.1.2:1.1: GSM modem (1-port) converter detected
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2975.953062] usb 1-1.1.2: GSM modem (1-port) converter now attached to ttyUSB1
Thu Aug  6 15:57:40 2020 daemon.notice netifd: Interface 'WAN' is setting up now
Thu Aug  6 15:57:40 2020 daemon.notice netifd: WAN (4609): ls: /sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/ttyUSB0/../../*/net: No such file or directory
Thu Aug  6 15:57:40 2020 daemon.notice netifd: WAN (4609): The interface could not be found.
Thu Aug  6 15:57:40 2020 daemon.notice netifd: WAN (4636): Stopping network WAN
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2976.055812] huawei_cdc_ncm 1-1.1.2:1.2: MAC-Address: 00:1e:10:1f:00:00
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2976.063553] huawei_cdc_ncm 1-1.1.2:1.2: setting rx_max = 16384
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2976.078863] huawei_cdc_ncm 1-1.1.2:1.2: NDP will be placed at end of frame for this device.
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2976.089455] huawei_cdc_ncm 1-1.1.2:1.2: cdc-wdm0: USB WDM device
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2976.097141] huawei_cdc_ncm 1-1.1.2:1.2 wwan0: register 'huawei_cdc_ncm' at usb-3f980000.usb-1.1.2, Huawei CDC NCM device, 00:1e:10:1f:00:00
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2976.112374] usb-storage 1-1.1.2:1.3: USB Mass Storage device detected
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2976.120204] scsi host1: usb-storage 1-1.1.2:1.3
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2976.126574] usb-storage 1-1.1.2:1.4: USB Mass Storage device detected
Thu Aug  6 15:57:40 2020 kern.info kernel: [ 2976.134918] scsi host2: usb-storage 1-1.1.2:1.4
Thu Aug  6 15:57:41 2020 daemon.notice netifd: WAN (4636): sending ->
Thu Aug  6 15:57:41 2020 kern.notice kernel: [ 2977.152148] scsi 2:0:0:0: Direct-Access     HUAWEI   TF CARD Storage  2.31 PQ: 0 ANSI: 2
Thu Aug  6 15:57:41 2020 kern.notice kernel: [ 2977.184804] sd 2:0:0:0: [sdb] Attached SCSI removable disk
Thu Aug  6 15:57:42 2020 kern.notice kernel: [ 2977.888743] scsi 1:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
Thu Aug  6 15:57:45 2020 daemon.notice netifd: Interface 'WAN' is now down
Thu Aug  6 17:37:41 2020 daemon.info hostapd: wlan0: STA f4:63:1f:95:e7:2f IEEE 802.11: associated
Thu Aug  6 17:37:41 2020 daemon.notice hostapd: wlan0: AP-STA-CONNECTED f4:63:1f:95:e7:2f
Thu Aug  6 17:37:41 2020 daemon.info hostapd: wlan0: STA f4:63:1f:95:e7:2f WPA: pairwise key handshake completed (RSN)
Thu Aug  6 17:37:41 2020 daemon.info dnsmasq-dhcp[2745]: DHCPREQUEST(wlan0) 192.168.1.18 f4:63:1f:95:e7:2f
Thu Aug  6 17:37:41 2020 daemon.info dnsmasq-dhcp[2745]: DHCPNAK(wlan0) 192.168.1.18 f4:63:1f:95:e7:2f wrong network
Thu Aug  6 17:37:41 2020 daemon.info dnsmasq-dhcp[2745]: DHCPREQUEST(wlan0) 192.168.1.18 f4:63:1f:95:e7:2f
Thu Aug  6 17:37:41 2020 daemon.info dnsmasq-dhcp[2745]: DHCPNAK(wlan0) 192.168.1.18 f4:63:1f:95:e7:2f wrong network
Thu Aug  6 17:37:44 2020 daemon.info dnsmasq-dhcp[2745]: DHCPDISCOVER(wlan0) f4:63:1f:95:e7:2f
Thu Aug  6 17:37:44 2020 daemon.info dnsmasq-dhcp[2745]: DHCPOFFER(wlan0) 192.168.0.167 f4:63:1f:95:e7:2f
Thu Aug  6 17:37:44 2020 daemon.info dnsmasq-dhcp[2745]: DHCPDISCOVER(wlan0) f4:63:1f:95:e7:2f
Thu Aug  6 17:37:44 2020 daemon.info dnsmasq-dhcp[2745]: DHCPOFFER(wlan0) 192.168.0.167 f4:63:1f:95:e7:2f
Thu Aug  6 17:37:44 2020 daemon.info dnsmasq-dhcp[2745]: DHCPREQUEST(wlan0) 192.168.0.167 f4:63:1f:95:e7:2f
Thu Aug  6 17:37:44 2020 daemon.info dnsmasq-dhcp[2745]: DHCPACK(wlan0) 192.168.0.167 f4:63:1f:95:e7:2f HUAWEI_P20_lite-673c5185c
Thu Aug  6 17:40:27 2020 daemon.info hostapd: wlan0: STA f4:63:1f:95:e7:2f IEEE 802.11: disassociated
Thu Aug  6 17:40:27 2020 daemon.notice hostapd: wlan0: AP-STA-DISCONNECTED f4:63:1f:95:e7:2f
Thu Aug  6 17:40:32 2020 daemon.info hostapd: wlan0: STA f4:63:1f:95:e7:2f IEEE 802.11: associated
Thu Aug  6 17:40:32 2020 daemon.notice hostapd: wlan0: AP-STA-CONNECTED f4:63:1f:95:e7:2f
Thu Aug  6 17:40:32 2020 daemon.info hostapd: wlan0: STA f4:63:1f:95:e7:2f WPA: pairwise key handshake completed (RSN)
Thu Aug  6 17:40:32 2020 daemon.info dnsmasq-dhcp[2745]: DHCPDISCOVER(wlan0) f4:63:1f:95:e7:2f
Thu Aug  6 17:40:32 2020 daemon.info dnsmasq-dhcp[2745]: DHCPOFFER(wlan0) 192.168.0.167 f4:63:1f:95:e7:2f
Thu Aug  6 17:40:32 2020 daemon.info dnsmasq-dhcp[2745]: DHCPREQUEST(wlan0) 192.168.0.167 f4:63:1f:95:e7:2f
Thu Aug  6 17:40:32 2020 daemon.info dnsmasq-dhcp[2745]: DHCPACK(wlan0) 192.168.0.167 f4:63:1f:95:e7:2f HUAWEI_P20_lite-673c5185c
Thu Aug  6 17:50:42 2020 daemon.err uhttpd[1268]: luci: failed login on /admin/status/syslog for root from 192.168.0.158
Thu Aug  6 17:50:44 2020 daemon.err uhttpd[1268]: luci: accepted login on /admin/status/syslog for root from 192.168.0.158

hnyman · August 7, 2020, 9:10am

It doesn't "lose interface".
It loses the underlying USB device.
The Huawei USB modem gets disconnected as seen from kernel.
(Interface as seen by netifd & co is higher level logical entity)

You might get better advice, if you change the title to be more descriptive.

One possible reason is that the device "changes mode" from modem to storage device, and that causes the brreakage.

Have you looked at the usb-modeswitch package ?
It is meant for adjusting the USB devices modes.

m31d4ri0n · August 7, 2020, 9:38am

Hi, Yeah thanks for that. It does indeed lose a network device, not an interface. I'll adjust the definition in the title.

The usb-modeswich package is installed along with comgt-ncm kmod-usb-net-huawei-cdc-ncm. I followed some of this documentation here as it correlates to my device:

https://openwrt.org/docs/guide-user/network/wan/wwan/ethernetoverusb_ncm

I am looking at the process of manually adding the network device again to the interface and I'm not sure I understand why this needs to be done each time and OpenWrt can't continue from where it was. As you can see in the vids, it cannot see the network device until I change the config and revert it.

Thu Aug  6 17:51:46 2020 daemon.notice netifd: Interface 'WAN' is setting up now
Thu Aug  6 17:51:48 2020 daemon.notice netifd: WAN (5213): Configuring modem
Thu Aug  6 17:51:48 2020 daemon.notice netifd: WAN (5213): Starting network WAN
Thu Aug  6 17:51:48 2020 daemon.notice netifd: WAN (5213): Connecting modem
Thu Aug  6 17:51:49 2020 daemon.notice netifd: WAN (5213): sending ->
Thu Aug  6 17:52:01 2020 daemon.notice netifd: WAN (5281): Stopping network WAN
Thu Aug  6 17:52:02 2020 daemon.notice netifd: WAN (5281): sending ->
Thu Aug  6 17:52:06 2020 daemon.notice netifd: Interface 'WAN' is now down
Thu Aug  6 17:52:06 2020 daemon.notice netifd: Interface 'WAN' is setting up now
Thu Aug  6 17:52:08 2020 daemon.notice netifd: WAN (5317): sending -> AT
Thu Aug  6 17:52:09 2020 daemon.notice netifd: WAN (5317): sending -> ATZ
Thu Aug  6 17:52:10 2020 daemon.notice netifd: WAN (5317): sending -> ATQ0
Thu Aug  6 17:52:10 2020 daemon.notice netifd: WAN (5317): sending -> ATV1
Thu Aug  6 17:52:11 2020 daemon.notice netifd: WAN (5317): sending -> ATE1
Thu Aug  6 17:52:12 2020 daemon.notice netifd: WAN (5317): sending -> ATS0=0
Thu Aug  6 17:52:12 2020 daemon.notice netifd: WAN (5317): sending -> AT+CGDCONT=1,"IP","3internet"
Thu Aug  6 17:52:13 2020 daemon.notice netifd: WAN (5317): Configuring modem
Thu Aug  6 17:52:13 2020 daemon.notice netifd: WAN (5317): Starting network WAN
Thu Aug  6 17:52:13 2020 daemon.notice netifd: WAN (5317): Connecting modem
Thu Aug  6 17:52:13 2020 daemon.notice netifd: WAN (5317): sending -> AT^NDISDUP=1,1,"3internet"
Thu Aug  6 17:52:14 2020 daemon.notice netifd: WAN (5317): Setting up wwan0
Thu Aug  6 17:52:14 2020 daemon.notice netifd: Interface 'WAN' is now up
Thu Aug  6 17:52:14 2020 daemon.notice netifd: Network device 'wwan0' link is up
Thu Aug  6 17:52:14 2020 daemon.notice netifd: Network alias 'wwan0' link is up
Thu Aug  6 17:52:14 2020 daemon.notice netifd: Interface 'WAN_4' is enabled
Thu Aug  6 17:52:14 2020 daemon.notice netifd: Interface 'WAN_4' has link connectivity
Thu Aug  6 17:52:14 2020 daemon.notice netifd: Interface 'WAN_4' is setting up now
Thu Aug  6 17:52:14 2020 daemon.notice netifd: WAN_4 (5409): udhcpc: started, v1.30.1
Thu Aug  6 17:52:14 2020 user.notice firewall: Reloading firewall due to ifup of WAN (wwan0)
Thu Aug  6 17:52:14 2020 daemon.notice netifd: WAN_4 (5409): udhcpc: sending discover
Thu Aug  6 17:52:17 2020 daemon.notice netifd: WAN_4 (5409): udhcpc: sending discover
Thu Aug  6 17:52:17 2020 daemon.notice netifd: WAN_4 (5409): udhcpc: sending select for 92.40.27.70
Thu Aug  6 17:52:17 2020 daemon.notice netifd: WAN_4 (5409): udhcpc: lease of 92.40.27.70 obtained, lease time 518400
Thu Aug  6 17:52:17 2020 daemon.notice netifd: Interface 'WAN_4' is now up
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: reading /tmp/resolv.conf.auto
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using local addresses only for domain test
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using local addresses only for domain onion
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using local addresses only for domain localhost
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using local addresses only for domain local
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using local addresses only for domain invalid
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using local addresses only for domain bind
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using nameserver 8.8.4.4#53
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using nameserver 8.8.8.8#53
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using nameserver 1.1.1.1#53
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using local addresses only for domain lan
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using nameserver 217.171.132.0#53
Thu Aug  6 17:52:17 2020 daemon.info dnsmasq[2745]: using nameserver 217.171.135.0#53
Thu Aug  6 17:52:17 2020 user.notice firewall: Reloading firewall due to ifup of WAN_4 (wwan0)

ansher · April 2, 2021, 4:46am

Put this in the startup section of Openwrt:

sleep 10
echo AT^SETPORT"FF;12,16" > /dev/cdc-wdm0

Please note: "/dev/cdc-wdm0" depends on your port and configuration.