AVM FRITZ!Box 4040

AVM FRITZ!Box 4040 is a versatile home router, supporting 802.11ac Wi-Fi and Gigabit Ethernet but has no integrated modem. Instead, the WAN port can be connected to several types of upstream modems like fiber, cable or DSL.

Sourcecode: http://osp.avm.de/fritzbox/fritzbox-4040/

Installation can be done via fritzflash.py (part of fritz-tools).

→ https://fritz-tools.readthedocs.io/en/latest/

When this device is powered on, a first-time browser based connection (192.168.178.1) is needed to set language, country and first-time login, otherwise the EVA bootloader won't accept a FTP connection! Then on next power-on (or reboot) the EVA bootloader allows FTP connection attempts during a certain time frame.

The FTP server can be reached at IP 192.168.178.1, so give your workstation the IP 192.168.178.2
Turn off your router, ensure your PC is connected via Ethernet, and turn the router back on.
Wait for the interface to become active and the Power LED on the Fritzbox is still flashing and connect to the FTP:
```
ftp -n -v -q 150 -p 192.168.178.1
```
Follow the commands one by one and make sure to set a FTP connection timeout of at least 2 minutes (e.g. “ftp -q 150 ...”). The data transfer can start until around 2 minutes after the put command is executed (“150 Opening BINARY data connection” will be shown) and no error is shown.

quote USER adam2
quote PASS adam2
quote MEDIA FLSH
binary
put <your eva openwrt image> mtd1
quote check mtd1

For openwrt-19.07.2-ipq40xx-generic-avm_fritzbox-4040-squashfs-eva.bin the flash check sum to expect is 0xF1A7E84F. (differs on other bin files)

Note: With a TTL-serial connection you can <break> into EVA bootloader and the power LED stays flashing and ftp connection can be made.

An example output:

$ ftp -n -v -q 150 -p 192.168.178.1
Connected to 192.168.178.1 (192.168.178.1).
220 ADAM2 FTP Server ready
ftp> quote USER adam2
331 Password required for adam2
ftp> quote PASS adam2
230 User adam2 successfully logged in
ftp> quote MEDIA FLSH
200 Media set to MEDIA_FLASH
ftp> binary
200 Type set to BINARY
ftp> put openwrt-18.06.4-ipq40xx-avm_fritzbox-4040-squashfs-eva.bin mtd1
local: openwrt-18.06.4-ipq40xx-avm_fritzbox-4040-squashfs-eva.bin remote: mtd1
227 Entering Passive Mode (192,168,178,1,12,0)
150 Opening BINARY data connection
226 Transfer complete
5505028 bytes sent in 23.97 secs (224.3076 kB/s)
ftp> quote check mtd1
150 Flash check 0x095CB3AB

After it displays something like “150 Flash check 0x...” the procedure is done. It will not terminate automatically, so power-cycle the device.

If you get an error “502 command not implemented” use the command passive after binary to switch between active and passive mode.

Note: If you like to ensure there are no configuration leftovers from the previously installed firmware, reset it to factory defaults before flashing.

Small automatic flash script:

#!/bin/sh

set -e

image="openwrt-ipq40xx-generic-avm_fritzbox-4040-squashfs-eva.bin"
dev="eth0"

ip link set down "$dev"
ip addr flush dev "$dev"
ip link set up "$dev"
ip addr add 192.168.178.2/24 dev "$dev"

while ! ping -c 1 -w 1 192.168.178.1 > /dev/null 2>&1; do
	ip link set up "$dev"
	echo "wait 1 second"
	sleep 1
done

sleep 1

ftp -n -v -q 150 -p "192.168.178.1" << EOF
quote USER adam2
quote PASS adam2
binary
quote MEDIA FLSH
put $image mtd1
EOF

(tested with tnftp, -p activates passive mode by default and -q sets a timeout) (tested also with RPi3b and normal ftp, works fine. DG)

If you want to go back to stock firmware, download the .image file from AVM's website linked above in the box. Rename the file extension to .tar, extract the archive and find the file var/tmp/kernel.image. Use the FTP flashing procedure above and give the put command the kernel.image file.

Alternatively, AVM offers a recovery tool for Windows: https://download.avm.de/fritzbox/fritzbox-4040/other/recover/

Find out flash layout, then add the flash layout table here (copy, paste, modify the example).

Please check out the article Flash layout. It contains examples and explanations that describe how to document the flash layout.

Sysupgrade from 22.03 to 24.10 is not officially supported due to changes in switch management!

sysupgrade

Login as root via SSH on 192.168.1.1, then enter the following commands:

cd /tmp
wget https://downloads.openwrt.org/releases/XX.XX.XX/targets/ipq40xx/generic/openwrt-XX.XX.XX-ipq40xx-generic-avm_fritzbox-4040-squashfs-sysupgrade.bin
sysupgrade /tmp/openwrt-XX.XX.XX-ipq40xx-generic-avm_fritzbox-4040-squashfs-sysupgrade.bin

→ generic.debrick

→ failsafe_and_factory_reset

→ Basic configuration After flashing, proceed with this.
Set up your Internet connection, configure wireless, configure USB port, etc.

The default network configuration is:

Interface Name	Description	Default configuration
br-lan	LAN & WiFi	192.168.1.1/24
eth0	LAN ports (1 to 4)	-
wan (eth1)	WAN port	-
radio0	WiFi (2.4 GHz)	Disabled
radio1	WiFi (5 GHz)	Disabled

It has been mentioned in the forums that VLAN IDs 1 and 2 are treated specially by the hardware driver (https://forum.openwrt.org/t/vlans-on-ipq40xx-alternative-config-for-fritzbox-4040/41832/6). Although using these on eth0 did work for a quick test, it is probably best to avoid VLAN ID 1 and 2 if possible. This has been tested with OpenWrt 18.06 to 19.07 and may change with updated hardware drivers.

This can be confirmed by running swconfig dev switch0 show, listing the default configuration right after flashing OpenWrt:

VLAN 1:
	vid: 1
	ports: 0 1 2 3 4 
VLAN 2:
	vid: 2
	ports: 0t 5

Switch ports 1-4 conform to the four LAN ports on eth0, switch port 5 is the WAN interface eth1. Unfortunately, switch port 5 is not shown in the LuCI web interface. Hence, if VLAN tagging on the WAN port is desired, this needs to be configured manually (see further below for an example).

Port	Switch port
CPU (eth0)	0
LAN 1	1
LAN 2	2
LAN 3	3
LAN 4	4
WAN (eth1)	5

Example 1: VLAN on LAN

A working VLAN setup is shown in the image below. This uses LAN 1 as a trunk port that receives tagged VLAN traffic from the network. LAN 2 and LAN 3 are members of VLAN 42 (used for the “Internal” network), LAN 4 is connected to VLAN 99 (used for a “Guest” network).

When configuring the VLANs it is very important to bind the LUCI network interfaces to the correct physical interfaces before applying the settings. Otherwise you will be locked out of the device and need to reflash it! (Or use a TTL-serial connection.)

Go to LUCI→Network→Interfaces and check the “Physical Settings” for each one. Following the example above “Internal” would be connected to eth0.42 and “Guest” to eth0.99. When everything works as expected, corresponding WIFI networks can be added as a bridge here later on.

Example 2: VLAN on WAN

As has already been mentioned, the WAN interface eth1 is on switch port 5, which does not show up in LuCI. It is required to configure the VLAN setup manually. Beware of changing the switch config afterwards in LuCI again, as this would override the custom setup.

The following example shows a portion of /etc/config/network with the goal of tagging packets on the WAN port with VLAN 7:

config interface 'wan'
	option device 'eth1.7'
	option proto 'pppoe'
	# further pppoe and wan settings here

config switch
	option name 'switch0'
	option reset '1'
	option enable_vlan '1'

config switch_vlan
	option device 'switch0'
	option vlan '1'
	option vid '1'
	option ports '0 1 2 3 4'

config switch_vlan
	option device 'switch0'
	option vlan '7'
	option vid '7'
	option ports '0t 5t'

This leaves VLAN 1 as is in the default configuration. VLAN 2 is also preserved in the default configuration, despite not explicitly configured. VLAN 7 is the manually added VLAN to achieve the desired VLAN tagging on the WAN port.

(An alternative way is to completely forget about the WAN port and treat the device as if it had only 4 LAN ports. It is even possible to remove the WAN interface and WAN firewall settings for this port. One of the LAN ports would then be isolated in a new VLAN and can be used for WAN traffic instead. This makes it possible to configure VLAN tagging for WAN traffic in LuCI.)

If your experiencing slow performance issue such as been max at about 650Mbps, irqbalance will fix it and can be install via opkg

opkg update
opkg install irqbalance nano
nano /etc/config/irqbalance
Change option enabled '0' to option enabled '1'
ctrl + X
Y (to save the file)
/etc/init.d/irqbalance restart

→ hardware.button on howto use and configure the hardware button(s). Here, we merely name the buttons, so we can use them in the above Howto.

The AVM FRITZ!Box 4040 has the following buttons:

BUTTON	Event
WPS	unknown
WLAN	unknown

Front:
Insert photo of front of the casing

Back:

TTL-Serial is hand-made.

Backside label:
Insert photo of backside label

Note: This will void your warranty!

Disconnect all the cables and flip over the case; there are two visible screws near the back and two hidden under the rubber feet near the front of the unit
Gently remove the front rubber feet: they're pressure-fit and reusable
Remove the four screws: you'll need a Torx T7 screwdriver (standard, not the “security” variant)
The two halves of the case now open quite a bit near the back but the front is still held together by three plastic hooks: the two lateral ones are no more than small bumps and unhook easily, while the central one requires a fair amount of wiggling or a plastic insert to pry open

Main PCB (front):

Main PCB (back):

→ port.serial general information about the serial port, serial port cable, etc.

How to connect to the Serial Port of this specific device:
GND / TX / RX / VCC (Square Pad is VCC)

Serial connection parameters for AVM FRITZ!Box 4040	115200, 8N1

Since soldering of GND serial pin might result in cold junction (GND PCB layer sucks the heat of your soldering iron) MX25L25635 flash memory GND pin is utilized via wire wrap for a good ground connection.

→ port.jtag general information about the JTAG port, JTAG cable, etc.

How to connect to the JTAG Port of this specific device:
Insert photo of PCB with markings for JTAG port

→ bootloader

None so far.

[22:37:28:389] Log Type: B - Since Boot(Power On Reset), D - Delta, S - Statistic [22:37:28:389] S - QC_IMAGE_VERSION_STRING=AVM_BOOT.BF.3.1.1 [22:37:28:389] S - IMAGE_VARIANT_STRING=DAABANAZA [22:37:28:389] S - OEM_IMAGE_VERSION_STRING=1204 [22:37:28:389] S - Boot Config, 0x00000021 [22:37:28:389] S - Reset status Config, 0x00000000 [22:37:28:389] S - Core 0 Frequency, 0 MHz [22:37:28:397] B - 262 - PBL, Start [22:37:28:397] B - 1342 - bootable_media_detect_entry, Start [22:37:28:405] B - 1686 - bootable_media_detect_success, Start [22:37:28:405] B - 1700 - elf_loader_entry, Start [22:37:28:412] B - 5419 - auth_hash_seg_entry, Start [22:37:28:412] B - 7377 - auth_hash_seg_exit, Start [22:37:28:422] B - 863037 - elf_segs_hash_verify_entry, Start [22:37:28:422] B - 1036275 - PBL, End [22:37:28:422] B - 0 - SBL1, Start [22:37:28:432] B - 1131916 - pm_device_init, Start [22:37:28:436] D - 7 - pm_device_init, Delta [22:37:28:443] B - 1133037 - boot_flash_init, Start [22:37:28:491] D - 54590 - boot_flash_init, Delta [22:37:28:496] B - 1191966 - boot_config_data_table_init, Start [22:37:28:509] D - 3612 - boot_config_data_table_init, Delta - (419 Bytes) [22:37:28:509] B - 1199580 - clock_init, Start [22:37:28:509] D - 7452 - clock_init, Delta [22:37:28:527] B - 1210539 - CDT version:2,Platform ID:8,Major [22:37:28:527] B - 1211842 - sbl1_ddr_set_params, Start [22:37:28:527] B - 1216550 - cpr_init, Start [22:37:28:527] D - 4 - cpr_init, Delta [22:37:28:527] B - 1221624 - Pre_DDR_clock_init, Start [22:37:28:544] D - 5 - Pre_DDR_clock_init, Delta [22:37:28:544] D - 13015 - sbl1_ddr_set_params, Delta [22:37:28:544] B - 1235436 - pm_driver_init, Start [22:37:28:544] D - 3 - pm_driver_init, Delta [22:37:28:569] B - 1270387 - sbl1_wait_for_ddr_training, Start [22:37:28:572] D - 31 - sbl1_wait_for_ddr_training, Delta [22:37:28:590] B - 1286516 - Image Load, Start [22:37:28:731] D - 145179 - QSEE Image Loaded, Delta - (292888 Bytes) [22:37:28:737] B - 1431997 - Image Load, Start [22:37:28:737] D - 1375 - SEC Image Loaded, Delta - (2048 Bytes) [22:37:28:752] B - 1446397 - Image Load, Start [22:37:28:794] D - 48335 - APPSBL Image Loaded, Delta - (90484 Bytes) [22:37:28:799] B - 1495043 - QSEE Execution, Start [22:37:28:816] D - 60 - QSEE Execution, Delta [22:37:28:816] B - 1501225 - SBL1, End [22:37:28:816] D - 1503249 - SBL1, Delta [22:37:28:816] S - Flash Throughput, 1999 KB/s (385839 Bytes, 192923 us) [22:37:28:816] S - DDR Frequency, 533 MHz [22:37:28:941] [22:37:28:941] (AVM) EVA Revision: 1.3243 [22:37:28:958] (C) Copyright 2005 AVM Date: Dec 13 2016 Time: 16:13:32 (0) 3 0x0-0x240D [22:37:28:958] [22:37:28:958] [FLASH:] MACRONIX Uniform-Flash 32MB 256 Bytes WriteBuffer [22:37:28:958] [FLASH:](Eraseregion [0] 512 sectors a 64kB) [22:37:28:958] [SYSTEM:] CortexA9 [22:37:32:943] [22:37:32:943] Eva_AVM >....................................................Device Tree Blob for SubRevision 4 not found. [22:37:57:864] Using Device Tree Blob of SubRevision 3 [22:37:58:218] [ 0.000000] Booting Linux on physical CPU 0x0 [22:37:58:223] [ 0.000000] Linux version 4.4.60 (jpluschke@guxVersionx70330xMESH18NL3x4040xbuildx71531) (gcc version 5.4.0 (Buildroot 2016.05-gc3ca4dd) ) #1 SMP PREEMPT Mon Sep 16 17:07:21 CEST 2019 [22:37:58:238] [ 0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d [22:37:58:250] [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [22:37:58:262] [ 0.000000] Machine model: AVM FRITZ!Box 4040 [22:37:58:262] [ 0.000000] [init_avm_kernel_config] AVM Kernel Config (ptr c0a2e000) [22:37:58:262] [ 0.000000] [init_avm_kernel_config] AVM Kernel Config: module memory entry [22:37:58:275] [ 0.000000] [init_avm_kernel_config] AVM Kernel Config: version info entry [22:37:58:275] [ 0.000000] [init_avm_kernel_config] AVM Kernel Config: device-tree for subrev 0 found [22:37:58:288] [ 0.000000] [init_avm_kernel_config] AVM Kernel Config: undef entry [22:37:58:300] [ 0.000000] [init_avm_kernel_config] AVM Kernel Config: undef entry [22:37:58:300] [ 0.000000] [init_avm_kernel_config] AVM Kernel Config: undef entry [22:37:58:313] [ 0.000000] [module-alloc] (start 0x80bb2000 end 0x80d61fff) virt=0xc0bb2000 [22:37:58:313] [ 0.000000] [module-alloc] use 0x1b0000 bytes at 0xc0bb2000 [22:37:58:326] [ 0.000000] Memory policy: Data cache writealloc [22:37:58:326] [ 0.000000] PERCPU: Embedded 12 pages/cpu @cebd8000 s19136 r8192 d21824 u49152 [22:37:58:326] [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 56356 [22:37:58:340] [ 0.000000] Kernel command line: mtdram1=0x8EE00000,0x90000000 clk_ignore_unused [22:37:58:351] [ 0.000000] [prepare_ram_resource] entered [22:37:58:351] [ 0.000000] [prepare_ram_resource] mtdram1 0x8ee00000-0x8fffffff [22:37:58:365] [ 0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes) [22:37:58:365] [ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes) [22:37:58:365] [ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes) [22:37:58:378] [ 0.000000] Memory: 213416K/227328K available (5600K kernel code, 728K rwdata, 2032K rodata, 236K init, 1286K bss, 13912K reserved, 0K cma-reserved, 0K highmem) [22:37:58:389] [ 0.000000] Virtual kernel memory layout: [22:37:58:389] [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [22:37:58:401] [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) [22:37:58:401] [ 0.000000] vmalloc : 0xcf000000 - 0xff800000 ( 776 MB) [22:37:58:414] [ 0.000000] lowmem : 0xc0000000 - 0xcee00000 ( 238 MB) [22:37:58:414] [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [22:37:58:426] [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB) [22:37:58:426] [ 0.000000] .text : 0xc0208000 - 0xc097c520 (7634 kB) [22:37:58:438] [ 0.000000] .init : 0xc097d000 - 0xc09b8000 ( 236 kB) [22:37:58:438] [ 0.000000] .data : 0xc09b8000 - 0xc0a6e000 ( 728 kB) [22:37:58:450] [ 0.000000] .bss : 0xc0a70000 - 0xc0bb19f4 (1287 kB) [22:37:58:450] [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [22:37:58:463] [ 0.000000] Preemptible hierarchical RCU implementation. [22:37:58:463] [ 0.000000] Build-time adjustment of leaf fanout to 32. [22:37:58:475] [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [22:37:58:475] [ 0.000000] Architected cp15 timer(s) running at 48.00MHz (virt). [22:37:58:475] [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xb11fd3bfb, max_idle_ns: 440795203732 ns [22:37:58:488] [ 0.000000] sched_clock: 56 bits at 48MHz, resolution 20ns, wraps every 4398046511096ns [22:37:58:500] [ 0.000000] Switching to timer-based delay loop, resolution 20ns [22:37:58:500] [ 0.000297] Calibrating delay loop (skipped), value calculated using timer frequency.. 96.00 BogoMIPS (lpj=192000) [22:37:58:514] [ 0.000326] pid_max: default: 32768 minimum: 301 [22:37:58:514] [ 0.000456] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [22:37:58:527] [ 0.000477] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [22:37:58:537] [ 0.001112] CPU: Testing write buffer coherency: ok [22:37:58:537] [ 0.001471] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [22:37:58:537] [ 0.001564] Setting up static identity map for 0x80208280 - 0x802082d8 [22:37:58:553] [ 0.040584] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [22:37:58:553] [ 0.052608] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 [22:37:58:565] [ 0.064670] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 [22:37:58:565] [ 0.064770] Brought up 4 CPUs [22:37:58:565] [ 0.064814] SMP: Total of 4 processors activated (384.00 BogoMIPS). [22:37:58:578] [ 0.064829] CPU: All CPU(s) started in SVC mode. [22:37:58:578] [ 0.065414] devtmpfs: initialized [22:37:58:578] [ 0.072472] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 [22:37:58:591] [ 0.072921] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [22:37:58:602] [ 0.072965] futex hash table entries: 1024 (order: 4, 65536 bytes) [22:37:58:616] [ 0.073471] pinctrl core: initialized pinctrl subsystem [22:37:58:616] [ 0.075043] NET: Registered protocol family 16 [22:37:58:616] [ 0.076696] DMA: preallocated 2048 KiB pool for atomic coherent allocations [22:37:58:629] [ 0.088483] cpuidle: using governor ladder [22:37:58:629] [ 0.100503] cpuidle: using governor menu [22:37:58:629] [ 0.100538] Creating Config Table [22:37:58:642] [ 0.107200] [qcom:watchdog] wdt_ipq40xx_probe ... [22:37:58:642] [ 0.113739] hw-breakpoint: debug architecture 0x5 unsupported. [22:37:58:642] [ 0.115745] <CORE> glink_init: IPC Logging Disabled [22:37:58:655] [ 0.116401] avm_alloc_page_extension node_extension_table[0] entries=60928 (size=243712) alloced [22:37:58:655] [ 0.116433] [fdt_get_reserved_mem_resource] mem_count = 4; search name = "avm_reboot_string" [22:37:58:666] [ 0.116455] Reboot Status is: Power-On [22:37:58:677] [ 0.134931] SCSI subsystem initialized [22:37:58:677] [ 0.135473] usbcore: registered new interface driver usbfs [22:37:58:677] [ 0.135582] usbcore: registered new interface driver hub [22:37:58:691] [ 0.135725] usbcore: registered new device driver usb [22:37:58:691] [ 0.137368] Advanced Linux Sound Architecture Driver Initialized. [22:37:58:703] [ 0.138091] AVM PA for Linux version 4.4.60 (jpluschke@guxVersionx70330xMESH18NL3x4040xbuildx71531) (gcc version 5.4.0 (Buildroot 2016.05-gc3ca4dd) ) #1 SMP PREEMPT Mon Sep 16 17:07:21 CEST 2019 [22:37:58:715] [ 0.138091] (early init) [22:37:58:715] [ 0.138233] NET: Registered protocol family 8 [22:37:58:728] [ 0.138250] NET: Registered protocol family 20 [22:37:58:728] [ 0.138452] TZ-pin nr 15 reprioritized to 60 [22:37:58:728] [ 0.138478] TZ-pin nr 36 reprioritized to 60 [22:37:58:742] [ 0.138568] TZ-pin nr 206 reprioritized to 60 [22:37:58:742] [ 0.138587] TZ-pin nr 213 reprioritized to 60 [22:37:58:742] [ 0.138607] TZ-pin nr 225 reprioritized to 60 [22:37:58:742] [ 0.138623] TZ-pin nr 226 reprioritized to 60 [22:37:58:755] [ 0.138639] TZ-pin nr 227 reprioritized to 60 [22:37:58:755] [ 0.138654] TZ-pin nr 228 reprioritized to 60 [22:37:58:766] [ 0.138670] TZ-pin nr 229 reprioritized to 60 [22:37:58:766] [ 0.138686] TZ-pin nr 230 reprioritized to 60 [22:37:58:766] [ 0.138701] TZ-pin nr 231 reprioritized to 60 [22:37:58:788] [ 0.138717] TZ-pin nr 232 reprioritized to 60 [22:37:58:788] [ 0.138732] TZ-pin nr 233 reprioritized to 60 [22:37:58:788] [ 0.138764] TZ-pin nr 269 reprioritized to 60 [22:37:58:788] [ 0.139160] [AVM_FIQ] FIQ disabled on CPU2 to install new vector! [22:37:58:788] [ 0.139166] [AVM_FIQ] FIQ disabled on CPU1 to install new vector! [22:37:58:810] [ 0.139193] [AVM_FIQ] FIQ disabled on CPU3 to install new vector! [22:37:58:810] [ 0.139211] [AVM_FIQ] FIQ disabled on CPU0 to install new vector! [22:37:58:810] [ 0.139226] [AVM_FIQ] Vector installed! [22:37:58:810] [ 0.139227] Set FIQ handler address [22:37:58:831] [ 0.139255] [AVM_FIQ] FIQ enabled again on CPU3! [22:37:58:831] [ 0.139263] [AVM_FIQ] FIQ enabled again on CPU1! [22:37:58:831] [ 0.139291] [AVM_FIQ] FIQ enabled again on CPU2! [22:37:58:831] [ 0.139324] [AVM_FIQ] FIQ enabled again on CPU0! [22:37:58:831] [ 0.139326] [AVM_FIQ] (Version 1.0)(TZ 10218) [22:37:58:854] [ 0.139350] TZLOG: Buffer allocated [22:37:58:854] [ 0.139467] clocksource: Switched to clocksource arch_sys_counter [22:37:58:854] [ 0.141946] NET: Registered protocol family 2 [22:37:58:854] [ 0.142982] TCP established hash table entries: 2048 (order: 1, 8192 bytes) [22:37:58:876] [ 0.143039] TCP bind hash table entries: 2048 (order: 2, 16384 bytes) [22:37:58:876] [ 0.143094] TCP: Hash tables configured (established 2048 bind 2048) [22:37:58:876] [ 0.143168] UDP hash table entries: 256 (order: 1, 8192 bytes) [22:37:58:876] [ 0.143211] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) [22:37:58:897] [ 0.143451] avm_pa: try to activate hw accelaration for pid 1 (ipv4) called from avm_pa_dev_pidhandle_register_with_ingress [22:37:58:897] [ 0.143578] NET: Registered protocol family 1 [22:37:58:916] [ 0.144017] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available [22:37:58:916] [ 0.159612] squashfs: version 4.0 (2009/01/31) Phillip Lougher [22:37:58:916] [ 0.160126] ANTFS Module: Version 2.42 [22:37:58:916] [ 0.160361] jffs2: version 2.2 (NAND) (SUMMARY) (ZLIB) (LZO) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc. [22:37:58:932] [ 0.161194] fuse init (API version 7.23) [22:37:58:932] [ 0.166861] Key type asymmetric registered [22:37:58:946] [ 0.166897] Asymmetric key parser 'x509' registered [22:37:58:946] [ 0.166962] io scheduler noop registered [22:37:58:946] [ 0.166986] io scheduler deadline registered (default) [22:37:58:960] [ 0.172971] glink_loopback_server_init: IPC Logging disabled [22:37:58:960] [ 0.174023] NET: Registered protocol family 27 [22:37:58:960] [ 0.174048] IPC_RTR: ipc_router_create_log_ctx: IPC Logging disabled [22:37:58:974] [ 0.174080] qmi_log_init: IPC Logging disabled [22:37:58:974] [ 0.174095] qmi_log_init: IPC Logging disabled [22:37:58:974] [ 0.174375] tcsr 194b000.tcsr: setting usb hs phy mode select = e700e7 [22:37:58:987] [ 0.174447] tcsr 1953000.ess_tcsr: setting ess interface select = 0 [22:37:58:987] [ 0.174538] tcsr 1949000.tcsr: setting wifi_glb_cfg = 41000000 [22:37:58:999] [ 0.174617] tcsr 1957000.tcsr: setting wifi_noc_memtype_m0_m2 = 2222222 [22:37:58:999] [ 0.175848] msm_serial 78af000.serial: msm_serial: detected port #0 [22:37:59:012] [ 0.175873] msm_serial_probe: set dectuart_port to ttyMSM0 [22:37:59:012] [ 0.175927] msm_serial 78af000.serial: uartclk = 1843200 [22:37:59:030] [ 0.175987] 78af000.serial: ttyMSM0 at MMIO 0x78af000 (irq = 25, base_baud = 115200) is a MSM [22:37:59:030] [ 0.176026] msm_serial: console setup on port #0 [22:37:59:030] [ 0.982253] console [ttyMSM0] enabled [22:37:59:051] [ 0.987350] msm_serial: driver initialized [22:37:59:051] [ 0.990716] msm_serial_hsl_init: driver initialized [22:37:59:051] [ 0.994921] [loadcontrol] set auto - scale=1 [22:37:59:051] [ 0.999171] [avm] configured: watchdog event debug [22:37:59:051] [ 1.008247] AVM_WATCHDOG: Watchdog Driver for AR7 Hardware (Version 1.0) [22:37:59:070] [ 1.008563] [qcom:watchdog] ar7wdt_hw_init... [22:37:59:070] [ 1.015303] [avm_request_fiq_on] Virtual IRQ numbers are not supported. Use HWIRQ (35) for requested IRQ (26) [22:37:59:070] [ 1.019504] [register_wdt_bark_irq] watchdog as fastirq(35) on all cpus registered with its default prio [22:37:59:086] [ 1.029359] [avm_request_fiq_on] Forcing HW-IRQ 36 instead [22:37:59:086] [ 1.038930] [register_wdt_bark_irq] watchdog as fastirq(36) on all cpus registered with highest prio [22:37:59:098] [ 1.044201] [avm_request_fiq_on] Forcing HW-IRQ 213 instead [22:37:59:113] [ 1.053513] [register_secure_wdt_bark_irq] secure watchdog as fastirq(213) on all cpus registered with highest prio [22:37:59:113] [ 1.058876] [qcom:ar7wdt_hw_init] Wdt bark time: 320000, bite time: 640000 [22:37:59:128] [ 1.069226] [qcom:ar7wdt_hw_init] Secure watchdog: bark: 20s, bite time: 40s [22:37:59:128] [ 1.076371] Register push button event to receive the set_factory_kernel event [22:37:59:140] [ 1.084203] AVM Simple Profiling enabled Version 3.0 [22:37:59:140] [ 1.090411] [simple-profiling]:4 performance counters implemented [22:37:59:166] [ 1.095517] [simple-profiling]all cpus:performance-mode: round-robin [22:37:59:166] [ 1.101519] [simple-profiling]all cpus:performance-mode: round-robin [22:37:59:166] [ 1.107935] [simple-profiling]all cpus:performance-mode: round-robin [22:37:59:166] [ 1.114268] [simple-profiling]all cpus:performance-mode: round-robin [22:37:59:189] [ 1.121608] avm_net_trace: Up and running. [22:37:59:189] [ 1.128318] zram: Added device: zram0 [22:37:59:189] [ 1.131436] spi_qup 78b5000.spi: IN:block:16, fifo:64, OUT:block:16, fifo:64 [22:37:59:189] [ 1.136862] 2 avmpart_of_nor_spi partitions found on MTD device ram-filesystem [22:37:59:189] [ 1.141720] Creating 2 MTD partitions on "ram-filesystem": [22:37:59:215] [ 1.148747] 0x000000000000-0x000001200000 : "rootfs_ram" [22:37:59:215] [ 1.155753] [mtd_add_handler] rootfs_ram (/dev/mtdblock0) will be used as root device [22:37:59:215] [ 1.159673] 0x000001200001-0x000001200000 : "kernel_ram" [22:37:59:215] [ 1.167372] mtd: partition "kernel_ram" is out of reach -- disabled [22:37:59:239] [ 1.174335] mtd-ram mtd-ram: registered mtd device [22:37:59:239] [ 1.179094] m25p80 spi0.0: mx25l25635e (32768 Kbytes) [22:37:59:239] [ 1.183623] [TFFS3_Register_Panic_CB] registering panic callback for mtd spi0.0 [22:37:59:239] [ 1.189221] [mtd_parser_of_nor_spi] fond_rootfs [22:37:59:265] [ 1.209208] 5 avmpart_of_nor_spi partitions found on MTD device spi0.0 [22:37:59:265] [ 1.209239] Creating 5 MTD partitions on "spi0.0": [22:37:59:265] [ 1.214669] 0x000000000000-0x000000120000 : "urlader" [22:37:59:265] [ 1.220977] 0x000000120000-0x0000001a0000 : "tffs (1)" [22:37:59:288] [ 1.225993] 0x0000001a0000-0x000000220000 : "tffs (2)" [22:37:59:288] [ 1.230967] 0x000000220000-0x000002000000 : "rootfs_kernel_spi" [22:37:59:288] [ 1.236150] 0x000001f00000-0x000002000000 : "jffs2" [22:37:59:288] [ 1.242777] libphy: Fixed MDIO Bus: probed [22:37:59:311] [ 1.245906] qca-mdio 90000.mdio: Could not find phy-reset-gpio, idx 0 [22:37:59:311] [ 1.249597] [qca_mdio_probe] Failed to get plc out of reset: -1 [22:37:59:311] [ 1.256210] libphy: qca_mdio: probed [22:37:59:311] [ 1.264015] qca-mdio 90000.mdio: qca-mdio driver was registered [22:37:59:311] [ 1.265687] tun: Universal TUN/TAP device driver, 1.6 [22:37:59:336] [ 1.271251] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com> [22:37:59:823] [ 1.781856] i2c /dev entries driver [22:37:59:829] [ 1.783761] sdhci: Secure Digital Host Controller Interface driver [22:37:59:845] [ 1.784159] sdhci: Copyright(c) Pierre Ossman [22:37:59:845] [ 1.790453] sdhci-pltfm: SDHCI platform and OF driver helper [22:37:59:845] [ 1.795409] [22:37:59:845] [ 1.795409] Version Rollback Feature Disabled [22:37:59:869] [ 1.802563] AVM PA for Linux Linux version 4.4.60 (jpluschke@guxVersionx70330xMESH18NL3x4040xbuildx71531) (gcc version 5.4.0 (Buildroot 2016.05-gc3ca4dd) ) #1 SMP PREEMPT Mon Sep 16 17:07:21 CEST 2019 [22:37:59:869] [ 1.802563] (late init) [22:37:59:869] [ 1.806902] gre: GRE over IPv4 demultiplexor driver [22:37:59:890] [ 1.828141] NET: Registered protocol family 10 [22:37:59:890] [ 1.832369] avm_pa: try to activate hw accelaration for pid 2 (ipv6) called from avm_pa_dev_pidhandle_register_with_ingress [22:37:59:890] [ 1.835974] sit: IPv6 over IPv4 tunneling driver [22:37:59:912] [ 1.847770] NET: Registered protocol family 17 [22:37:59:912] [ 1.851810] Bridge broadcast ratelimiter registered [22:37:59:912] [ 1.855973] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this. [22:37:59:932] [ 1.860797] Bridge firewalling registered [22:37:59:932] [ 1.873618] KOAM is loaded successfully. [22:37:59:932] [ 1.877549] l2tp_core: L2TP core driver, V2.0 [22:37:59:932] [ 1.881502] l2tp_ip: L2TP IP encapsulation support (L2TPv3) [22:37:59:932] [ 1.885786] l2tp_netlink: L2TP netlink interface [22:37:59:948] [ 1.891179] l2tp_eth: L2TP ethernet pseudowire support (L2TPv3) [22:37:59:948] [ 1.896015] l2tp_ip6: L2TP IP encapsulation support for IPv6 (L2TPv3) [22:37:59:948] [ 1.901662] 8021q: 802.1Q VLAN Support v1.8 [22:37:59:967] [ 1.908347] Registering SWP/SWPB emulation handler [22:37:59:967] [ 1.912264] [fdt_get_reserved_mem_resource] mem_count = 4; search name = "avm_sbl_boot_string" [22:37:59:967] [ 1.917090] [get_sbl_boot_status] No reserved memory for entry for avm_sbl_boot_string [22:37:59:995] [ 1.925696] [avm_request_fiq_on] Using virtual IRQ (250) [22:37:59:995] [ 1.933609] [avm_request_fiq_on] Using virtual IRQ (251) [22:37:59:995] [ 1.939071] [avm_request_fiq_on] Using virtual IRQ (252) [22:37:59:995] [ 1.944367] [avm_request_fiq_on] Using virtual IRQ (253) [22:38:00:019] [ 1.949918] fastirq_to_linux_ipi_init: successfully installed irq=254 [22:38:00:019] [ 1.955062] fastirq_to_linux_ipi_init: successfully installed irq=255 [22:38:00:019] [ 1.961332] fastirq_to_linux_ipi_init: successfully installed irq=256 [22:38:00:019] [ 1.967806] fastirq_to_linux_ipi_init: successfully installed irq=257 [22:38:00:044] [ 1.983502] Could not find Env 'kernel_args' in the device tree [22:38:00:044] [ 1.983600] tffs: Waiting for backend to be available [22:38:00:044] [ 1.988292] [TFFS3_Init] Called. [22:38:00:044] [ 1.989068] hctosys: unable to open rtc device (rtc0) [22:38:00:044] [ 1.993231] clk: Not disabling unused clocks [22:38:00:066] [ 1.993249] ALSA device list: [22:38:00:066] [ 1.993256] No soundcards found. [22:38:00:066] [ 2.008954] [TFFS3_Init] No storage module registered, trying legacy fallback [22:38:00:066] [ 2.012239] [TFFS3_CACHE_Configure] Setting up caching for backend legacy [22:38:00:066] [ 2.019429] [TFFS3-CACHE] Caching module for TFFS 3.x [22:38:00:091] [ 2.021339] VFS: Mounted root (squashfs filesystem) readonly on device 31:0. [22:38:00:091] [ 2.024225] devtmpfs: mounted [22:38:00:091] [ 2.025026] Freeing unused kernel memory: 236K (c097d000 - c09b8000) [22:38:00:091] [ 2.041244] [TFFS3_LGCY_Setup] using mtd3(tffs (1)), mtd4(tffs (2)) [22:38:00:116] [ 2.047799] [TFFS3_LGCY_Setup] mtd "tffs (1)": segment value 20 [22:38:00:116] [ 2.053792] [TFFS3_LGCY_Setup] mtd "tffs (2)": segment value 21 [22:38:00:116] [ 2.059543] [TFFS3_LGCY_Setup] Using segment 21 (avail: 20 + 21) [22:38:00:116] [ 2.065450] [TFFS3_LGCY_Setup] mtd4 size=0x80000 [22:38:00:141] [ 2.071929] TFFS: tiny flash file system driver. GPL (c) AVM Berlin (Version 3.0) [22:38:00:141] [ 2.076339] Adam2 environment variables API installed. [22:38:00:350] starting pid 94, tty '/dev/ttyMSM0': '/etc/init.d/rc.S' [22:38:00:449] nop - do not mount /dev [22:38:00:474] source files in group 0 ... [22:38:00:487] /etc/init.d/S00-signal /etc/init.d/S05-watchdog [22:38:00:487] /etc/init.d/S01-head /etc/init.d/S08-tffs [22:38:00:501] /etc/init.d/S02-led /etc/init.d/S09-0config_rekey [22:38:00:501] /etc/init.d/S02-sysctl-mss /etc/init.d/S09-config [22:38:00:501] /etc/init.d/S02-udev /etc/init.d/S09-rtc [22:38:00:518] source /etc/init.d/S00-signal [22:38:00:518] source /etc/init.d/S01-head [22:38:00:531] [ 2.484674] [TFFS_Cache] Allocate segement buffer cache (size=32784) [22:38:00:534] [ 2.485626] TFFS Name Table M [22:38:01:240] Jan 1 00:00:03 ar7cfgctl[153]: load_config(ar7): open problem - factory default loaded [22:38:01:354] Jan 1 00:00:03 ar7cfgctl[161]: load_config(ar7): open problem - factory default loaded [22:38:01:448] source /etc/init.d/S02-led [22:38:01:494] [ 3.452245] led_modul_Fritz_Box_HW227: module license ' [22:38:01:498] [ 3.452245] (C) Copyright 2012 by AVM [22:38:01:511] [ 3.452245] ' taints kernel. [22:38:01:511] [ 3.452310] Disabling lock debugging due to kernel taint [22:38:01:511] [ 3.465673] [LED] use GPIO 205 for 'gpio_avm_led_power' [22:38:01:525] [ 3.468498] [LED] use GPIO 202 for 'gpio_avm_led_wan' [22:38:01:525] [ 3.473348] [LED] use GPIO 200 for 'gpio_avm_led_wlan' [22:38:01:525] [ 3.478548] [LED] use GPIO 206 for 'gpio_avm_led_lan_all' [22:38:01:538] [ 3.483586] [LED] use GPIO 207 for 'gpio_avm_led_info' [22:38:01:538] [ 3.489051] [LED] use GPIO 201 for 'gpio_avm_led_info_red' [22:38:01:551] [ 3.494105] [BUTTON] use GPIO 58 for 'gpio_avm_button_wlan' [22:38:01:551] [ 3.499640] [BUTTON] use GPIO 63 for 'gpio_avm_button_wlan_wps' [22:38:01:565] [ 3.505089] [button_init] name button_both, virtual multipush button [22:38:01:565] [ 3.511059] [avm_connect][state_machine_init] starting event worker thread [22:38:01:579] source /etc/init.d/S02-sysctl-mss [22:38:01:579] source /etc/init.d/S02-udev [22:38:01:579] source /etc/init.d/S05-watchdog [22:38:01:596] [ 3.554836] AVM_WATCHDOG: System Init Ueberwachung 240 Sekunden [22:38:01:604] source /etc/init.d/S08-tffs [22:38:01:622] [ 3.580174] udevd[191]: starting version 175 [22:38:01:771] P-Defaults: do nothing [22:38:01:771] source /etc/init.d/S09-0config_rekey [22:38:02:213] firmwarecfg config_rekey: nop [22:38:02:213] source /etc/init.d/S09-config [22:38:02:557] source /etc/init.d/S09-rtc [22:38:02:557] execute files in group 0 ... [22:38:02:575] /etc/init.d/E02-sbl_ack /etc/init.d/E05-basemodules [22:38:02:575] /etc/init.d/E02-tz_update [22:38:02:575] execute /etc/init.d/E02-sbl_ack [22:38:02:599] No SBL ACK, Interface not present [22:38:02:599] execute /etc/init.d/E02-tz_update [22:38:02:599] [TZ-Update] [22:38:02:599] mount: mounting proc on /proc failed: Device or resource busy [22:38:02:700] Found mibib @ page 262144 [22:38:02:700] 1 TZs stored, updating index 0 first [22:38:02:762] [/usr/sbin/tz_update] TZ0 already up to date Version 10218 [22:38:02:766] ... tz not updated [22:38:02:778] execute /etc/init.d/E05-basemodules [22:38:02:778] group 0 done ... [22:38:02:778] source files in group 1 ... [22:38:02:778] /etc/init.d/S10-html /etc/init.d/S12-default [22:38:02:791] /etc/init.d/S10-vm /etc/init.d/S15-filesys [22:38:02:791] /etc/init.d/S11-config_def /etc/init.d/S17-tam [22:38:02:791] /etc/init.d/S11-piglet [22:38:02:791] source /etc/init.d/S10-html [22:38:02:804] source /etc/init.d/S10-vm [22:38:02:804] source /etc/init.d/S11-config_def [22:38:02:856] source /etc/init.d/S11-piglet [22:38:02:856] [ 4.815802] [22:38:02:870] [ 4.815802] [avm_debug] redirecting kernel-messages (/dev/debug) [22:38:02:870] source /etc/init.d/S12-default [22:38:02:894] source /etc/init.d/S15-filesys [22:38:02:966] [ 4.924344][2]jffs2: notice: (309) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found. [22:38:02:984] [jffs2] Mount /data success (mtd6 size 1048576) [22:38:02:995] [jffs2] write env variable jffs2_size to 16 [22:38:02:995] source /etc/init.d/S17-tam [22:38:02:995] TAM: create JFFS2 directory /data/tam [22:38:03:015] execute files in group 1 ... [22:38:03:015] /etc/init.d/E15-device [22:38:03:015] execute /etc/init.d/E15-device [22:38:03:036] mknod led c 245 [22:38:03:063] mknod tffs c 246 [22:38:03:089] mknod avm_power c 251 [22:38:03:116] mknod debug c 248 [22:38:03:143] mknod avm_event c 249 [22:38:03:170] mknod capi_oslib c 68 [22:38:03:184] group 1 done ... [22:38:03:184] source files in group 2 ... [22:38:03:184] execute files in group 2 ... [22:38:03:205] group 2 done ... [22:38:03:205] source files in group 3 ... [22:38:03:205] /etc/init.d/S30-ledcfg [22:38:03:205] source /etc/init.d/S30-ledcfg [22:38:03:205] execute files in group 3 ... [22:38:03:219] group 3 done ... [22:38:03:219] source files in group 4 ... [22:38:03:219] /etc/init.d/S42-ptest /etc/init.d/S45-configd /etc/init.d/S46-usb [22:38:03:219] /etc/init.d/S44-hostname /etc/init.d/S45-openwrt [22:38:03:232] source /etc/init.d/S42-ptest [22:38:03:244] source /etc/init.d/S44-hostname [22:38:03:364] source /etc/init.d/S45-configd [22:38:03:380] source /etc/init.d/S45-openwrt [22:38:03:451] Jan 1 01:00:05 configd[411]: configd_call_converters-start [22:38:03:509] Jan 1 01:00:05 configd[411]: configd_call_converters(/usr/share/configd/C20_mobiled.so) [22:38:03:674] [ 5.632099] ssdk_alloc_priv[4137]:INFO:ess-switch dts node number: 1 [22:38:03:680] [ 5.632186] ssdk_dt_get_switch_node[3046]:INFO:ess-switch DT exist! [22:38:03:694] [ 5.632218] ssdk_dt_parse_access_mode[2948]:INFO:switch_access_mode: local bus [22:38:03:694] [ 5.632246] ssdk_dt_parse_access_mode[2960]:INFO:switchreg_base_addr: 0xc000000 [22:38:03:709] [ 5.632275] ssdk_dt_parse_access_mode[2961]:INFO:switchreg_size: 0x80000 [22:38:03:709] [ 5.632301] ssdk_dt_parse_mac_mode[2533]:INFO:mac mode = 0x0 [22:38:03:723] [ 5.632326] ssdk_dt_parse_mac_mode[2539]:INFO:mac mode1 doesn't exit! [22:38:03:723] [ 5.632351] ssdk_dt_parse_mac_mode[2548]:INFO:mac mode2 doesn't exit! [22:38:03:736] [ 5.632376] ssdk_dt_parse_phy_info[2800]:INFO:qcom,port_phyinfo DT doesn't exist! [22:38:03:736] [ 5.632408] ssdk_dt_parse_mdio[2841]:INFO:mdio DT exist! [22:38:03:752] [ 5.632447] ssdk_dt_parse_led[3020]:INFO:current dts led_source_num is 0 [22:38:03:752] [ 5.632479] ssdk_dt_parse_psgmii[2918]:INFO:ess-psgmii DT exist! [22:38:03:752] [ 5.632518][2]ssdk_plat_init start [22:38:03:767] [ 5.632687] ssdk_plat_init[1237]:INFO:enable ess clk [22:38:03:767] [ 5.634381][2]DESS hsl reg init [22:38:03:767] [ 5.694550] malibu_phy_api_ops_init[2577]:INFO:qca probe malibu phy driver succeeded! [22:38:03:780] [ 5.698085] regi_init[4276]:INFO:Initializing DESS!! [22:38:05:427] [ 7.384197] ssdk_switch_register[1817]:INFO:Chip version 0x1401 [22:38:05:431] [ 7.384279] qca_link_polling_select[1480]:INFO:link-polling-required node does not exist [22:38:05:446] [ 7.384313] ssdk_switch_register[1842]:INFO:polling is selected [22:38:05:446] [ 7.385347] regi_init[4285]:INFO:Initializing DESS Done!! [22:38:05:468] [ 7.386457][2][regi_init] AVM_PA init [22:38:05:468] [ 7.409622] regi_init[4318]:INFO:qca-ssdk module init succeeded! [22:38:05:487] [ 7.433831][0][module-alloc-by-name] give 0xf000 bytes at 0xc0bb2000 to module 'essedma' (0x1a1000 bytes left) [22:38:05:487] [ 7.435917][0]Using 5 netdevs [22:38:05:504] [ 7.447113][0]EDMA Dev eth0 (0) --> phy mdio 0 [22:38:05:504] [ 7.447189][0]EDMA Dev eth1 (1) --> phy mdio 1 [22:38:05:504] [ 7.447227][0]EDMA Dev eth2 (2) --> phy mdio 2 [22:38:05:504] [ 7.447264][0]EDMA Dev eth3 (3) --> phy mdio 3 [22:38:05:524] [ 7.447298][0]EDMA Dev wan (4) --> phy mdio 4 [22:38:05:524] [ 7.447705][0][avm_request_fiq_on] Virtual IRQ numbers are not supported. Use HWIRQ (272) for requested IRQ (77) [22:38:05:542] source /etc/init.d/S46-usb [22:38:05:562] mount: mounting usbfs on /proc/bus/usb failed: No such file or directory [22:38:05:908] starting XHCI driver [22:38:05:960] XHCI USB 3.0 Host with port_config:0 started [22:38:05:980] [ 7.936707][2]xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [22:38:05:988] [ 7.936791][2]xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 [22:38:06:002] [ 7.937091][2]xhci-hcd xhci-hcd.0.auto: hcc params 0x0228f665 hci version 0x100 quirks 0x00010010 [22:38:06:002] [ 7.937197][2]xhci-hcd xhci-hcd.0.auto: irq 203, io mem 0x08a00000 [22:38:06:021] [ 7.937501][2]usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [22:38:06:021] [ 7.937542][2]usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [22:38:06:021] [ 7.937567][2]usb usb1: Product: xHCI Host Controller [22:38:06:038] [ 7.937592][2]usb usb1: Manufacturer: Linux 4.4.60 xhci-hcd [22:38:06:038] [ 7.937615][2]usb usb1: SerialNumber: xhci-hcd.0.auto [22:38:06:038] [ 7.938609][2]hub 1-0:1.0: USB hub found [22:38:06:059] [ 7.939753][2]hub 1-0:1.0: 1 port detected [22:38:06:059] [ 7.940427][2]avm_net_trace: New net trace device 'usb1' registered with minor 161. [22:38:06:059] [ 7.940785][2]xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [22:38:06:059] [ 7.940834][2]xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 [22:38:06:079] [ 7.940897][2]xHCI: delay VBUS POWER on for 50 ms [22:38:06:079] [ 7.941039][2]usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [22:38:06:079] [ 7.941246][2]usb usb2: New USB device found, idVendor=1d6b, idProduct=0003 [22:38:06:097] [ 7.941282][2]usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [22:38:06:097] [ 7.941306][2]usb usb2: Product: xHCI Host Controller [22:38:06:114] [ 7.941335][2]usb usb2: Manufacturer: Linux 4.4.60 xhci-hcd [22:38:06:114] [ 7.941358][2]usb usb2: SerialNumber: xhci-hcd.0.auto [22:38:06:114] [ 7.942504][2]hub 2-0:1.0: USB hub found [22:38:06:114] [ 7.942621][2]hub 2-0:1.0: 1 port detected [22:38:06:134] [ 7.942924][2]AVM: disable USB3 bus#2 port#0 config=0 [22:38:06:134] [ 7.943394][2]avm_net_trace: New net trace device 'usb2' registered with minor 162. [22:38:06:134] [ 7.943964][2]xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [22:38:06:134] [ 7.944034][2]xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 3 [22:38:06:151] [ 7.944393][2]xhci-hcd xhci-hcd.1.auto: hcc params 0x0220f665 hci version 0x100 quirks 0x00010010 [22:38:06:168] [ 7.944497][2]xhci-hcd xhci-hcd.1.auto: irq 204, io mem 0x06000000 [22:38:06:168] [ 7.944791][2]usb usb3: New USB device found, idVendor=1d6b, idProduct=0002 [22:38:06:168] [ 7.944823][2]usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [22:38:06:187] [ 7.944847][2]usb usb3: Product: xHCI Host Controller [22:38:06:187] [ 7.944870][2]usb usb3: Manufacturer: Linux 4.4.60 xhci-hcd [22:38:06:187] [ 7.944893][2]usb usb3: SerialNumber: xhci-hcd.1.auto [22:38:06:209] [ 7.946056][2]hub 3-0:1.0: USB hub found [22:38:06:209] [ 7.946166][2]hub 3-0:1.0: 1 port detected [22:38:06:209] [ 7.946862][2]avm_net_trace: New net trace device 'usb3' registered with minor 163. [22:38:06:209] [ 7.947342][2]xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [22:38:06:209] [ 7.947390][2]xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 4 [22:38:06:236] [ 7.947553][2]xHCI: delay VBUS POWER on for 50 ms [22:38:06:236] [ 7.947738][2]usb usb4: We don't know the algorithms for LPM for this host, disabling LPM. [22:38:06:236] [ 7.948039][2]usb usb4: New USB device found, idVendor=1d6b, idProduct=0003 [22:38:06:260] [ 7.948087][2]usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [22:38:06:260] [ 7.948120][2]usb usb4: Product: xHCI Host Controller [22:38:06:260] [ 7.948145][2]usb usb4: Manufacturer: Linux 4.4.60 xhci-hcd [22:38:06:260] [ 7.948168][2]usb usb4: SerialNumber: xhci-hcd.1.auto [22:38:06:260] [ 7.950802][2]hub 4-0:1.0: USB hub found [22:38:06:284] [ 7.950923][2]hub 4-0:1.0: config failed, hub doesn't have any ports! (err -19) [22:38:06:284] [ 7.953393][2]avm_net_trace: New net trace device 'usb4' registered with minor 164. [22:38:06:284] XHCI Platform Driver started [22:38:06:284] [ 7.999565][2]xHCI: delayed VBUS POWER on [22:38:06:284] execute files in group 4 ... [22:38:06:308] /etc/init.d/E40-avmipc /etc/init.d/E45-psetd /etc/init.d/E47-cpunet [22:38:06:308] /etc/init.d/E41-capi /etc/init.d/E46-kpid /etc/init.d/E47-voip [22:38:06:308] /etc/init.d/E45-avmnexusd /etc/init.d/E46-net /etc/init.d/E49-voipkpid [22:38:06:308] execute /etc/init.d/E40-avmipc [22:38:06:374] execute /etc/init.d/E41-capi [22:38:06:399] [ 8.356679][3] [22:38:06:399] [ 8.356679][3][avm_debug] redirecting kernel-messages (/dev/debug) [22:38:06:413] cat: can't open '/dev/debug': Device or resource busy [22:38:06:482] [ 8.439544][1][module-alloc-by-name] give 0x58000 bytes at 0xc0bc1000 to module 'pcmlink' (0x149000 bytes left) [22:38:06:503] [ 8.443968][1]Could not find Env 'kernel_args' in the device tree [22:38:06:664] [ 8.621563][1][module-alloc-by-name] give 0xc3000 bytes at 0xc0c19000 to module 'isdn_fbox_fon5' (0x86000 bytes left) [22:38:06:699] [ 8.656497][1][capi_oslib]avm_stack_attach: cpu1 -> cpu1 [22:38:06:748] [ 8.703807][1][isdn]PCMLINK: Codecslots=0 Slics=0 Pots=0 TE=0 NT=0 DECT=0 no HwCross DSP-EC: 0 [22:38:06:767] [ 8.718175][1][isdn]no hardware found: voip-only [22:38:06:919] [ 8.876328][0][module-alloc-by-name] give 0x55000 bytes at 0xc0cdc000 to module 'capi_codec' (0x31000 bytes left) [22:38:06:938] execute /etc/init.d/E45-avmnexusd [22:38:07:402] Jan 1 01:00:09 avmnexusd[858]: starting ... [22:38:07:489] 1970-01-01 01:00:09 avmnexusd[858]: ready (0sec) [22:38:07:493] execute /etc/init.d/E45-psetd [22:38:07:562] execute /etc/init.d/E46-kpid [22:38:07:576] execute /etc/init.d/E46-net [22:38:07:593] /etc/init.d/E46-net: line 5: /etc/init.d/rc.dsl.sh: not found [22:38:07:615] kpid: Sending section usb failed with error -1: [22:38:07:623] sysfs_usb 1 {"sv":"gen/v1","kpi":{}} [22:38:07:623] kpid: Sending section hwpa failed with error -1: [22:38:07:640] sysfs_hwpa 1 {"sv":"gen/v1","kpi":{}} [22:38:07:640] kpid: Sending section system failed with error -1: [22:38:07:640] sysfs_system 1 {"sv":"gen/v1","kpi":{"temperatures":{}}} [22:38:07:640] Error: Could not set interrupt 'wtf1': IRQ identifier name to ID conversion failed [22:38:07:663] Error: Could not set interrupt 'wtf2': IRQ identifier name to ID conversion failed [22:38:07:663] Error: Could not set interrupt 'wtf3': IRQ identifier name to ID conversion failed [22:38:07:679] Error: Could not set interrupt 'wtf4': IRQ identifier name to ID conversion failed [22:38:07:699] Error: Could not set interrupt 'wtf5': IRQ identifier name to ID conversion failed [22:38:07:699] Error: Could not set interrupt 'wtf6': IRQ identifier name to ID conversion failed [22:38:07:958] Error: Could not set interrupt 'qca-init-rx0': IRQ identifier name to ID conversion failed [22:38:07:977] Error: Could not set interrupt 'qca-init-rx2': IRQ identifier name to ID conversion failed [22:38:08:002] Error: Could not set interrupt 'qca-init-rx4': IRQ identifier name to ID conversion failed [22:38:08:017] Error: Could not set interrupt 'qca-init-rx6': IRQ identifier name to ID conversion failed [22:38:08:097] [ 10.048112][3]kdsldmod: init start (sizeof(struct sk_buff) = 456) [22:38:08:104] [ 10.060409][3]userman: device registerd (userman_url) with major=239 [22:38:08:118] [ 10.060704][3]kdsld: ttychannel: ldisc 8 registered [22:38:08:118] [ 10.062110][3]kdsldmod: init done [22:38:08:208] Neuroptima unavailable... setting (and upon event resetting) default parameters from file '/etc/psetd.cfg'. [22:38:08:815] Jan 1 01:00:10 l2tpv3d[1163]: starting ... [22:38:08:837] Jan 1 01:00:10 l2tpv3d[1165]: started. [22:38:08:837] 1970-01-01 01:00:10 l2tpv3d[1163]: ready (0sec) [22:38:08:837] Jan 1 01:00:10 l2tpv3d[1165]: verbose: DISABLED [22:38:09:034] [ 10.991508][1]avm_power: [avm_power_temperature] No cpu sensor registered [22:38:09:215] [ 11.173405] Could not get KPI temperature.Jan 1 01:00:11 ctlmgr[1170]: pcpc_create_ctx: no pcpserver found [22:38:09:583] Jan 1 01:00:11 ctlmgr[1178]: /var/flash/ewnwstatus.cfg: is empty [22:38:09:588] Jan 1 01:00:11 ctlmgr[1178]: load_config(ewnwstatus): file empty - factory default loaded [22:38:09:602] Jan 1 01:00:11 upnpd[1182]: starting ... [22:38:09:661] 1970-01-01 01:00:11 upnpd[1182]: ready (0sec) [22:38:10:779] [22:38:10:779] [ 12.736401][0][avm_power] eth: port 0 force normal CON t0 [22:38:10:798] [ 12.736828][0][avm_power] eth: port 1 force normal CON t0 [22:38:10:798] [ 12.737120][0][avm_power] eth: port 2 force normal CON t0 [22:38:10:798] [ 12.737393][0][avm_power] eth: port 3 force normal CON t0 [22:38:10:798] [ 12.737661][0][avm_power] eth: port 4 force normal CON t0 [22:38:10:987] Jan 1 01:00:12 multid[1191]: /var/flash/vpn.cfg: is empty [22:38:11:003] Jan 1 01:00:12 multid[1191]: load_config(vpn): file empty - factory default loaded [22:38:11:031] Jan 1 01:00:12 multid[1191]: startup ($Revision$$CompileDate: Jul 16 2019 20:40:13 $) [22:38:11:037] Jan 1 01:00:12 multid[1191]: starting ... [22:38:11:051] Jan 1 01:00:12 multid[1232]: HWRevision=227 [22:38:11:072] Jan 1 01:00:13 multid[1232]: opt_without_tr069discover=0, tr069disc=0, AR7CFG->vinax_hsi_lan_ports=(null), multid.tcom_targetarch=0, is_eth_switch_installed()=0, multid.cpmac_no_ata_possible=0, pppoeiface=wan [22:38:11:091] Jan 1 01:00:13 multid[1232]: bridge_configured: nqos_bridge_active=0, bridge_port_set=0x0, igmp_flt_portset=0x0 [22:38:11:808] Jan 1 01:00:13 ctlmgr[1178]: WLANLIB: wlan_plugin_init:869: prj revision 0x2de935b [22:38:11:836] Jan 1 01:00:13 ctlmgr[1178]: WLANLIB: wlan_libs_nexus_init:231: Get Nexus info failed: try again later. [22:38:12:144] Jan 1 01:00:14 multid[1232]: flood ratelimiting on bridge lan is supported [22:38:12:150] [22:38:12:150] [ 14.102259][2]IPv6: ADDRCONF(NETDEV_UP): lan: link is not ready [22:38:12:162] [ 14.104489][2]IPv6: ADDRCONF(NETDEV_UP): lan: link is not ready [22:38:12:162] Jan 1 01:00:14 multid[1232]: list[0]=lan [22:38:12:177] [ 14.107652][2]device eth0 entered promiscuous mode [22:38:12:177] Jan 1 01:00:14 multid[1232]: list[0]=lan [22:38:12:177] [ 14.111011][2]device eth1 entered promiscuous mode [22:38:12:191] Jan 1 01:00:14 multid[1232]: list[0]=lan [22:38:12:191] [ 14.114759][2]device eth2 entered promiscuous mode [22:38:12:191] Jan 1 01:00:14 multid[1232]: list[0]=lan [22:38:12:191] [ 14.118332][2]device eth3 entered promiscuous mode [22:38:12:858] Jan 1 01:00:14 ctlmgr[1178]: WLANLIB: wcsi_socket_connect:313: Establishing RPC connection failed with TIMEOUT [22:38:12:879] Jan 1 01:00:14 ctlmgr[1178]: /var/flash/stat.cfg: is empty [22:38:12:879] Jan 1 01:00:14 ctlmgr[1178]: /etc/default/avme/stat.cfg: is empty [22:38:12:879] Jan 1 01:00:14 ctlmgr[1178]: load_config(stat): file empty - factory default loaded [22:38:13:210] Jan 1 01:00:15 multid[1232]: flood ratelimiting on bridge guest is supported [22:38:13:219] [22:38:13:219] [ 15.167757][2]IPv6: ADDRCONF(NETDEV_UP): guest: link is not ready [22:38:13:232] [ 15.170625][2]IPv6: ADDRCONF(NETDEV_UP): guest: link is not ready [22:38:13:253] Jan 1 01:00:15 multid[1232]: mrouter: using IGMPv3 for upstream interface dsl [22:38:13:261] Jan 1 01:00:15 multid[1232]: mrouter: using IGMPv3 for other interfaces [22:38:13:273] Jan 1 01:00:15 multid[1232]: resolv.conf with 127.0.0.1 written [22:38:13:292] Jan 1 01:00:15 multid[1232]: DHCPD on lan [22:38:13:292] Jan 1 01:00:15 multid[1232]: DHCPD on lan:0 skipped, is virtual interface [22:38:13:305] Jan 1 01:00:15 multid[1232]: DHCPD on guest [22:38:13:305] Jan 1 01:00:15 multid[1232]: open(/dev/userman) - No such file or directory (2) [22:38:13:328] Jan 1 01:00:15 multid[1232]: open(/dev/userman) - No such file or directory (2) [22:38:13:328] Jan 1 01:00:15 multid[1232]: Warning: dnsd_start: no dns server added [22:38:13:328] Jan 1 01:00:15 multid[1232]: interface lo up. [22:38:13:328] Jan 1 01:00:15 multid[1232]: interface lo carrier up [22:38:13:348] Jan 1 01:00:15 multid[1232]: list[0]=lan [22:38:13:348] Jan 1 01:00:15 multid[1232]: list[1]=guest [22:38:13:348] Jan 1 01:00:15 multid[1232]: br_get_interfaces(guest)=0 [22:38:13:348] Jan 1 01:00:15 multid[1232]: interface eth0 up. [22:38:13:348] Jan 1 01:00:15 multid[1232]: list[0]=lan [22:38:13:348] Jan 1 01:00:15 multid[1232]: list[1]=guest [22:38:13:371] Jan 1 01:00:15 multid[1232]: br_get_interfaces(guest)=0 [22:38:13:371] Jan 1 01:00:15 multid[1232]: interface eth1 up. [22:38:13:371] Jan 1 01:00:15 multid[1232]: list[0]=lan [22:38:13:371] Jan 1 01:00:15 multid[1232]: list[1]=guest [22:38:13:371] Jan 1 01:00:15 multid[1232]: br_get_interfaces(guest)=0 [22:38:13:391] Jan 1 01:00:15 multid[1232]: interface eth2 up. [22:38:13:391] Jan 1 01:00:15 multid[1232]: list[0]=lan [22:38:13:391] Jan 1 01:00:15 multid[1232]: list[1]=guest [22:38:13:391] Jan 1 01:00:15 multid[1232]: br_get_interfaces(guest)=0 [22:38:13:410] Jan 1 01:00:15 multid[1232]: interface eth3 up. [22:38:13:410] Jan 1 01:00:15 multid[1232]: DHCPD on lan [22:38:13:410] Jan 1 01:00:15 multid[1232]: interface lan up. [22:38:13:410] Jan 1 01:00:15 multid[1232]: DHCPD on lan [22:38:13:410] Jan 1 01:00:15 multid[1232]: mrouter: lan: vif 0 if_index 11 [22:38:13:434] Jan 1 01:00:15 multid[1232]: interface guest up. [22:38:13:434] Jan 1 01:00:15 multid[1232]: DHCPD on guest [22:38:13:434] Jan 1 01:00:15 multid[1232]: mrouter: lan: vif 0 if_index 11 [22:38:13:434] Jan 1 01:00:15 multid[1232]: mrouter: guest: vif 1 if_index 12 [22:38:13:456] 1970-01-01 01:00:15 multid[1191]: ready (3sec) [22:38:13:456] Jan 1 01:00:15 multid[1232]: verbose: DISABLED [22:38:13:522] /etc/init.d/E46-net: eval: line 1: dnsqcheckd: not found [22:38:13:542] FATAL: dnsqcheckd not running after started [22:38:13:664] Jan 1 01:00:15 ctlmgr[1178]: /var/flash/vpn.cfg: is empty [22:38:13:667] Jan 1 01:00:15 ctlmgr[1178]: load_config(vpn): file empty - factory default loaded [22:38:13:699] Jan 1 01:00:15 ddnsd[1278]: startup ($Revision$$CompileDate: Jul 16 2019 20:40:17 $) [22:38:13:704] Jan 1 01:00:15 ddnsd[1278]: starting ... [22:38:13:716] Jan 1 01:00:15 ddnsd[1284]: DDNS: no valid accounts [22:38:13:716] Jan 1 01:00:15 ddnsd[1284]: [1284] Start failed [22:38:13:738] [22:38:13:738] 1970-01-01 01:00:15 ddnsd[1278]: [1278]: Daemon start failed (0sec) [22:38:13:738] FATAL: ddnsd not running after started [22:38:13:782] Jan 1 01:00:15 pcpd[1286]: startup (Jul 16 2019 20:40:42) [22:38:13:787] Jan 1 01:00:15 pcpd[1286]: starting ... [22:38:13:803] Jan 1 01:00:15 pcpd[1288]: Local v4 address: 192.168.178.1 [22:38:13:803] Jan 1 01:00:15 pcpd[1288]: verbose: DISABLED [22:38:13:803] 1970-01-01 01:00:15 pcpd[1286]: ready (0sec) [22:38:13:880] rc.wlan: Start WLAN... [22:38:13:900] Jan 1 01:00:15 ctlmgr[1178]: /var/flash/vpn.cfg: is empty [22:38:13:900] Jan 1 01:00:15 ctlmgr[1178]: load_config(vpn): file empty - factory default loaded [22:38:14:102] WLAND:[01308]:01:00.16/[0016.005]:[VER]Registered new module default, DEFAULT, 0 [22:38:14:108] WLAND:[01308]:01:00.16/[0016.005]:[VER]Registered new module global, GLOBAL, 0 [22:38:14:121] WLAND:[01308]:01:00.16/[0016.005]:[ALW]BUILD: Jul 17 2019, 13:52:54 [22:38:14:334] Jan 1 01:00:16 dsld[1311]: set no arch [22:38:14:340] Jan 1 01:00:16 dsld[1311]: set no arch [22:38:14:340] Jan 1 01:00:16 dsld[1311]: startup ($Revision$$CompileDate: Jul 16 2019 20:41:29 $) [22:38:14:459] [ 16.415328][2][wlan_config] Given config is: [22:38:14:463] [ 16.415374][2][wlan_config] hw_interface=0 chip_type=13 (dakota) offload=1 (non) [22:38:14:479] [ 16.415396][2][wlan_config] hw_interface=1 chip_type=13 (dakota) offload=1 (non) [22:38:14:479] [ 16.416268][2]avm_net_trace: New net trace device 'WLAN Management Traffic' registered with minor 128. [22:38:14:498] [ 16.417906][0][wlan_config] HWRevision now 227 [22:38:14:498] [ 16.417972][0][wlan_config] HWSubRevision now 4 [22:38:14:498] [ 16.418032][0][wlan_config] maca now 7c:ff:4d:13:75:34 [22:38:14:518] [ 16.431426][0][wlan_eeprom] EEPROM1: Awaiting calibration data via char dev [22:38:14:518] [ 16.431600][0][wlan_eeprom] New valid EEPROM1 loaded [22:38:14:518] [ 16.431634][0][wlan_eeprom] EEPROM #1, type "QCA99xx/IPQ40xx": [22:38:14:518] [ 16.431698][0][wlan_eeprom] Customer data="FB4040_2G_CAL0_V2" [22:38:14:538] [ 16.431724][0][wlan_eeprom] patching regDmnExt to enable ext chan 144 for fcc [22:38:14:538] [ 16.431884][0][wlan_eeprom] EEPROM2: Awaiting calibration data via char dev [22:38:14:538] [ 16.431993][0][wlan_eeprom] New valid EEPROM2 loaded [22:38:14:558] [ 16.432018][0][wlan_eeprom] EEPROM #2, type "QCA99xx/IPQ40xx": [22:38:14:558] [ 16.432072][0][wlan_eeprom] Customer data="FB4040_5G_CAL1_V5" [22:38:14:558] [ 16.432095][0][wlan_eeprom] patching regDmnExt to enable ext chan 144 for fcc [22:38:14:605] [ 16.557941][1]ath_dfs: Version 2.0.0 [22:38:14:605] [ 16.557941][1]Copyright (c) 2005-2006 Atheros Communications, Inc. All Rights Reserved [22:38:14:625] Jan 1 01:00:16 ctlmgr[1178]: /var/flash/fwupdatetrace.cfg: is empty [22:38:14:625] Jan 1 01:00:16 ctlmgr[1178]: /var/flash/fwupdatetrace.cfg: is empty [22:38:14:625] Jan 1 01:00:16 ctlmgr[1178]: /var/flash/fwupdatetrace.cfg: is empty [22:38:14:649] [ 16.588240][0]ath_spectral: Version 2.0.0 [22:38:14:649] [ 16.588240][0]Copyright (c) 2005-2009 Atheros Communications, Inc. All Rights Reserved [22:38:14:707] [ 16.663789][3]userman: LAN Client Internet-Accounting and Blocking [22:38:14:714] [ 16.663886][3]userman: device registerd with major=237 [22:38:14:726] [ 16.663925][3]userman: init ok [22:38:15:142] [ 17.098035][0]_avm_ath_ext_attach_ieee:77 [22:38:15:438] [ 17.395022] *********** IPQ4019 *************Wifi0 CPU frequency 250000000 [22:38:15:459] [ 17.415399] hif_target_sync_ahb: Got FW signal, retries = 1hif_ahb_enable_bus: X - hif_type = 0xd, target_type = 0xbhif_config_ce: ce_init donehif_config_ce: X, ret = 0hif_set_hia: Ehif_pci_bus_configure: hif_set_hia donehif_configure_irq: Ehif_enable: X OKhif_napi_create: NAPI structures initializedhif_napi_create: NAPI id 6 created for pipe 5qca_napi_create: napi instance 32 created on pipe 4 [22:38:15:508] [ 17.422574] hif_napi_event: received evnt: CONF cmd; v = 1 (state=0x1)hif_napi_event: setting configuration to ON [22:38:15:508] __ol_ath_attach() Allocated scn cbb40540 [22:38:15:508] [ 17.422750][3]__ol_ath_attach: dev name wifi0 [22:38:15:524] [ 17.422775][3]ol_ath_attach interface_id 0 [22:38:15:524] [ 17.423819][3]ol_target_init() BMI inited. [22:38:15:524] [ 17.423950][3]ol_target_init() BMI Get Target Info. [22:38:15:524] [ 17.423980][3]Chip id: 0xb, chip version: 0x1000000 [22:38:15:539] [ 17.423999][3] [22:38:15:539] [ 17.423999][3] CE WAR Disabled [22:38:15:539] [ 17.424164][3]NUM_DEV=1 FWMODE=0x2 FWSUBMODE=0x0 FWBR_BUF 0 [22:38:15:539] [ 17.424375][3]ol_target_init() configure Target . [22:38:15:558] [ 17.424491][3] [22:38:15:558] [ 17.424491][3] Target Version is 1000000 [22:38:15:558] [ 17.424519][3] [22:38:15:558] [ 17.424519][3] Flash Download Address c0000 [22:38:15:558] [ 17.424544][3]ol_transfer_bin_file: flash data file defined [22:38:15:581] [ 17.424668][3]ol_transfer_bin_file 3904: Download AVM WLAN EEPROM len 12064 [22:38:15:581] [ 17.424722][3]qc98xx_verify_checksum: flash checksum passed: 0x479c [22:38:15:581] [ 17.424747][3]ol_transfer_bin_file 4007: Download Flash data len 12064 [22:38:15:581] [ 17.425125][3]Board extended Data download address: 0x0 [22:38:15:603] [ 17.444704][3] [22:38:15:603] [ 17.444704][3] Board data initialized [22:38:15:603] [ 17.444886][3]ol_ath_download_firmware: Download OTP, flash download ADDRESS 0xc0000 [22:38:15:603] [ 17.444913][3] [22:38:15:603] [ 17.444913][3] Selecting OTP binary for CHIP Version -1083735048 [22:38:15:627] [ 17.453392][3]ol_transfer_bin_file 3771: downloading file 0, Download data len 4688 [22:38:15:627] [ 17.469877][3] [22:38:15:627] [ 17.469877][3] First OTP send param 10 [22:38:15:627] [ 17.474586][3]ol_ath_download_firmware :First OTP download and Execute is good address:0x4000 return param 4660 [22:38:15:653] [ 17.474615][3]ol_ath_download_firmware:##Board Id 16 , CHIP Id 0 [22:38:15:653] [ 17.474765][3]ol_ath_download_firmware: Using 0x1234 for the remainder of init [22:38:15:653] [ 17.474794][3] [22:38:15:653] [ 17.474794][3] Selecting OTP binary for CHIP Version -1083735048 [22:38:15:653] [ 17.474961][3]ol_transfer_bin_file 3771: downloading file 0, Download data len 4688 [22:38:15:677] [ 17.491402][3] [22:38:15:677] [ 17.491402][3] [Non-Flash] mode : Module param 0x0 selected [22:38:15:677] [ 17.491437][3] [22:38:15:677] [ 17.491437][3] Second otp download Param 0 [22:38:15:677] [ 17.506313][3]ol_ath_download_firmware : Second OTP download and Execute is good, param=0x0 [22:38:15:700] [ 17.506347][3] [22:38:15:700] [ 17.506347][3] Mission mode: Firmware CHIP Version -1083735048 [22:38:15:700] [ 17.566581][3]ol_swap_seg_alloc: Successfully allocated memory for SWAP size=262144 [22:38:15:700] [ 17.567123][3]ol_swap_wlan_memory_expansion: length:185431 size_left:185451 dma_size_left:262144 fw_temp:d2561004 fw_entry_size:185455 [22:38:15:723] [ 17.567165][3]ol_swap_wlan_memory_expansion: dma_virt_addr :d24b0000 fw_temp: d2561008 length: 185431 [22:38:15:723] [ 17.567441][3]Swap: bytes_left to copy: fw:16; dma_page:76713 [22:38:15:723] [ 17.567571][3]ol_swap_wlan_memory_expansion: length:0 size_left:12 dma_size_left:76713 fw_temp:d258e463 fw_entry_size:185455 [22:38:15:743] [ 17.567599][3]Swap: wrong length read:0 [22:38:15:743] [ 17.567621][3]ol_swap_wlan_memory_expansion: Swap total_bytes copied: 185431 Target address 4180e0 [22:38:15:767] [ 17.567756][3]scn=cbb40540 target_write_addr=4180e0 seg_info=ce4f4810 [22:38:15:767] [ 17.567787][3]ol_transfer_swap_struct:Code swap structure successfully downloaded for bin type =2 [22:38:15:767] [ 17.567809][3]bin_filename=IPQ4019/hw.1/athwlan.bin swap_filename=/lib/firmware/IPQ4019/hw.1/athwlan.codeswap.bin [22:38:15:790] [ 17.567904][3]ol_transfer_bin_file: Downloading firmware file: IPQ4019/hw.1/athwlan.bin [22:38:15:790] [ 17.740079][0]ol_transfer_bin_file 3771: downloading file 1, Download data len 380020 [22:38:15:868] Jan 1 01:00:17 usermand[1362]: /var/flash/userstat.cfg: is empty [22:38:15:885] Jan 1 01:00:17 usermand[1362]: /etc/default/avme/userstat.cfg: is empty [22:38:15:885] Jan 1 01:00:17 usermand[1362]: load_config(userstat): file empty - factory default loaded [22:38:15:907] Jan 1 01:00:17 usermand[1362]: starting ... [22:38:15:945] Jan 1 01:00:17 contfiltd[1367]: starting ... [22:38:17:054] [ 19.009928][3]ol_target_init() Download FW done. [22:38:17:056] [ 19.010271][3] [22:38:17:066] [ 19.010271][3]ol_ath_copy_tgt_iram() iram read paddr: 0x980000 host paddr 0x8a700000 vaddr 0xd26a2000 Radio id 0 [22:38:17:218] [ 19.174087][3]ol_ath_copy_tgt_iram(): hif_diag_read_tgtiram FW IRAM content success [22:38:17:227] [ 19.174359][3]ol_ath_attach() WMI attached. wmi_handle cbaa0000 [22:38:17:239] [ 19.174394][3]wmi_unified_register_event_handler: Event id 62 is unavailable [22:38:17:239] [ 19.174418] +htc_create .. HIF :cc9b8000-htc_create: (0xccfbe800) [22:38:17:252] [ 19.174504][3]htc_wmi_init() HT Create . ccfbe800 [22:38:17:252] [ 19.174609][3]htc_wmi_init 8312 host_enable 0 nss_nwifi_offload 0 [22:38:17:265] [ 19.174642][3]ol_ath_set_default_tgt_config : AC Minfree buffer allocation through module param (umac.ko) [22:38:17:265] [ 19.174661][3] OL_ACBKMinfree : 0 [22:38:17:278] [ 19.174678][3] OL_ACBEMinfree : 0 [22:38:17:278] [ 19.174695][3] OL_ACVIMinfree : 0 [22:38:17:278] [ 19.174716][3] OL_ACVOMinfree : 0 [22:38:17:278] [ 19.174736] hif_enable_fastpath, Enabling fastpath mode [22:38:17:294] [ 19.174750][3]+HWT [22:38:17:294] [ 19.174777] hif_completion_thread_startup: pipe_num:0 pipe_info:0xcc9bce78hif_completion_thread_startup: pipe_num:3 pipe_info:0xcc9bcf50hif_completion_thread_startup: pipe_num:4 pipe_info:0xcc9bcf98 [22:38:17:307] [ 19.184426][3]-HWT [22:38:17:307] [ 19.184553][3]Startup Mode-0 set [22:38:17:307] [ 19.184580][3] [22:38:17:324] [ 19.184580][3]<=== cfg max peer id 1056 ====> [22:38:17:324] [ 19.185300][3]htt_peer_map_timer_init Enter pdev c9c88000 hrtimer c9c8c930 [22:38:17:324] [ 19.185347][3] [22:38:17:324] [ 19.185347][3] htt_alloc_peer_map_mem : Alloc Success : host q vaddr c9c92000 paddr 89c92000 [22:38:17:345] [ 19.185374][3] [22:38:17:345] [ 19.185374][3] htt_alloc_peer_map_mem : Flush Interval Configured to 256 pkts [22:38:17:345] [ 19.185484][3]htt_avm_offload_init:201 registered irq 50 for wifi0-avm_offload_rx_refill on wifi0 [22:38:17:368] [ 19.185565][3]htt_avm_offload_init:224 registered irq 55 for wifi0-avm_offload_tx_free on wifi0 [22:38:17:368] [ 19.188284][3]ol_txrx_pdev_attach: 2500 tx desc's allocated ; range starts from c9fc0000 [22:38:17:368] [ 19.190401][0]Firmware_Build_Number:50 [22:38:17:368] [ 19.190449][0]FW wireless modes: 0x680c [22:38:17:387] [ 19.190474] num_rf_chain:0x00000002 ht_cap_info:0x0000085b vht_cap_info:0x339959b2 vht_supp_mcs:0x0000fffa [22:38:17:387] [ 19.190531][0]btcoex_support 0, wlan_prio_gpio 0, coex_gpio_pin 0 0 0 [22:38:17:387] [ 19.190557][0]wmi_service_coex_gpio 1, wmi_service_4_wire_coex_support 1, coex_version 0 [22:38:17:403] [ 19.190584][0] [22:38:17:403] [ 19.190584][0]Sending Ext resource cfg: HOST PLATFORM as 0 [22:38:17:425] [ 19.190584][0]fw_feature_bitmap as 50 to TGT [22:38:17:425] [ 19.190697][0]ol_ath_service_ready_event: tt_support: 1 [22:38:17:425] [ 19.190725][0]ol_ath_service_ready_event: periodic_chan_stats: 1 [22:38:17:425] [ 19.190745][0]ol_ath_service_ready_event: sw_cal_support_check_flag: 1 [22:38:17:441] [ 19.190768][0]Peer Caching Enabled ; num_peers = 530, num_active_peers = 52 num_tids = 104, num_vdevs = 17 [22:38:17:441] [ 19.190836][0]EXT NSS Supported [22:38:17:441] [ 19.190877][0]idx 1 req 2 num_units 1 num_unit_info 12 unit size 256 actual units 53 [22:38:17:464] [ 19.190949][0]ol_ath_alloc_host_mem_chunk req_id 2 idx 0 num_units 53 unit_len 256, [22:38:17:464] [ 19.190979][0]idx 2 req 3 num_units 1 num_unit_info 12 unit size 1024 actual units 53 [22:38:17:464] [ 19.191059][0]ol_ath_alloc_host_mem_chunk req_id 3 idx 1 num_units 53 unit_len 1024, [22:38:17:488] [ 19.191087][0]idx 3 req 4 num_units 1 num_unit_info 12 unit size 4096 actual units 53 [22:38:17:488] [ 19.191276][0]ol_ath_alloc_host_mem_chunk req_id 4 idx 2 num_units 53 unit_len 4096, [22:38:17:488] [ 19.191305][0]idx 0 req 1 num_units 0 num_unit_info 2 unit size 744 actual units 531 [22:38:17:512] [ 19.191737][0]ol_ath_alloc_host_mem_chunk req_id 1 idx 3 num_units 531 unit_len 744, [22:38:17:512] [ 19.191769][0]idx 4 req 6 num_units 35 num_unit_info 0 unit size 3072 actual units 35 [22:38:17:512] [ 19.191891][0]ol_ath_alloc_host_mem_chunk req_id 6 idx 4 num_units 35 unit_len 3072, [22:38:17:536] [ 19.191920][0]idx 5 req 7 num_units 1 num_unit_info 0 unit size 6144 actual units 1 [22:38:17:536] [ 19.191959][0]ol_ath_alloc_host_mem_chunk req_id 7 idx 5 num_units 1 unit_len 6144, [22:38:17:536] [ 19.191987][0]idx 6 req 5 num_units 0 num_unit_info 2 unit size 2056 actual units 531 [22:38:17:563] [ 19.192974][0]ol_ath_alloc_host_mem_chunk req_id 5 idx 6 num_units 531 unit_len 2056, [22:38:17:563] [ 19.193003][0]Support not added yet for Service 91 [22:38:17:563] [ 19.193021][0]Support not added yet for Service 92 [22:38:17:563] [ 19.193038][0]No EXT_MSG send INIT now [22:38:17:563] [ 19.193066][0]chunk 0 len 13568 requested , ptr 0x89fb8000 [22:38:17:586] [ 19.193092][0]chunk 1 len 54272 requested , ptr 0x89820000 [22:38:17:586] [ 19.193111][0]chunk 2 len 217088 requested , ptr 0x89840000 [22:38:17:586] [ 19.193134][0]chunk 3 len 395064 requested , ptr 0x89880000 [22:38:17:586] [ 19.193153][0]chunk 4 len 107520 requested , ptr 0x89900000 [22:38:17:612] [ 19.193177][0]chunk 5 len 6144 requested , ptr 0x8a5fe000 [22:38:17:612] [ 19.193196][0]chunk 6 len 1091736 requested , ptr 0x89a00000 [22:38:17:612] Jan 1 01:00:19 dsld[1311]: starting ... [22:38:17:612] [ 19.193223][0]chunk 7 len 0 requested , ptr 0x8a700000 [22:38:17:612] [ 19.193414][0]smart_log_init: Smart logging Enabled buf=c9b10000 (size=65536) [22:38:17:632] [ 19.199163][0]wmi_control_rx :event handler is not registered: event id 0x9044 [22:38:17:632] [ 19.206053][0]wmi_control_rx :event handler is not registered: event id 0x9044 [22:38:17:632] [ 19.212729][0]wmi_control_rx :event handler is not registered: event id 0x9044 [22:38:17:661] [ 19.219413][0]wmi_control_rx :event handler is not registered: event id 0x9044 [22:38:17:661] [ 19.224759][0]Version = 16777216 3 status = 0 [22:38:17:661] [ 19.224812][3]ol_ath_connect_htc() WMI is ready [22:38:17:661] [ 19.224872][3]htt_h2t_frag_desc_bank_cfg_msg - HTT_H2T_MSG_TYPE_FRAG_DESC_BANK_CFG sent to FW for radio ID = 0 [22:38:17:684] [ 19.224882][0]target uses HTT version 2.2; host uses 2.2 [22:38:17:684] [ 19.229937][3]ol_ath_attach() connect HTC. [22:38:17:684] [ 19.229960][3]bypasswmi : 0 [22:38:17:684] [ 19.229986][3]ol_regdmn_start: reg-domain param: regdmn=0, countryName=, wModeSelect=FFFFFFFF, netBand=FFFFFFFF, extendedChanMode=0. [22:38:17:701] [ 19.230028][3]ol_regdmn_init_channels: !avail mode 0x680c (0x2) flags 0x2150 [22:38:17:701] [ 19.230050][3]ol_regdmn_init_channels: !avail mode 0x680c (0x1) flags 0x140 [22:38:17:701] [ 19.230106][3]ol_regdmn_init_channels: !avail mode 0x680c (0x20) flags 0xd0 [22:38:17:724] [ 19.230127][3]ol_regdmn_init_channels: !avail mode 0x680c (0x40) flags 0x150 [22:38:17:724] [ 19.230182][3]ol_regdmn_init_channels: !avail mode 0x680c (0x1000) flags 0x10100 [22:38:17:724] [ 19.230204][3]ol_regdmn_init_channels: !avail mode 0x680c (0x8000) flags 0x20100 [22:38:17:745] [ 19.230225][3]ol_regdmn_init_channels: !avail mode 0x680c (0x10000) flags 0x40100 [22:38:17:745] [ 19.230246][3]ol_regdmn_init_channels: !avail mode 0x680c (0x20000) flags 0x100100 [22:38:17:765] [ 19.230267][3]ol_regdmn_init_channels: !avail mode 0x680c (0x40000) flags 0x200100 [22:38:17:765] [ 19.230288][3]ol_regdmn_init_channels: !avail mode 0x680c (0x80000) flags 0x400100 [22:38:17:765] [ 19.230309][3]ol_regdmn_init_channels: !avail mode 0x680c (0x100000) flags 0x800100 [22:38:17:790] [ 19.230330][3]ol_regdmn_init_channels: !avail mode 0x680c (0x200000) flags 0x4000100 [22:38:17:790] [ 19.230352][3]ol_regdmn_init_channels: !avail mode 0x680c (0x400000) flags 0x8000100 [22:38:17:790] [ 19.230531][3]AVM/WLAN: cc=840. Clearing potential non valid maxpwr entries [22:38:17:813] [ 19.230661][3]OL Resmgr Init-ed [22:38:17:813] [ 19.230842][3]ieee80211_bsteering_attach: Band steering initialized [22:38:17:813] [ 19.230906][3]acfg_attach: 3070: Netlink socket created:ccfb6000 [22:38:17:813] [ 19.230968][3]SPECTRAL : get_capability not registered [22:38:17:813] [ 19.230987][3]HAL_CAP_PHYDIAG : Capable [22:38:17:835] [ 19.231006][3]SPECTRAL : Need to fix the capablity check for RADAR (spectral_attach : 237) [22:38:17:835] [ 19.231025][3]SPECTRAL : get_capability not registered [22:38:17:835] [ 19.231043][3]HAL_CAP_RADAR : Capable [22:38:17:835] [ 19.231063][3]SPECTRAL : Need to fix the capablity check for SPECTRAL [22:38:17:857] [ 19.231063][3] (spectral_attach : 242) [22:38:17:857] [ 19.231086][3]SPECTRAL : get_capability not registered [22:38:17:857] [ 19.231103][3]HAL_CAP_SPECTRAL_SCAN : Capable [22:38:17:857] [ 19.231121][3]SPECTRAL : get_tsf64 not registered [22:38:17:857] [ 19.231151][3]spectral_init_netlink 78 NULL SKB [22:38:17:880] [ 19.231179][3]GreenAP on wifi0: Attached [22:38:17:880] [ 19.231197][3]Green-AP : Attached [22:38:17:880] [ 19.231215][3]rate power table override is only supported for AR98XX [22:38:17:880] [ 19.231368][3]ol_if_dfs_attach: called; ptr=c987998c, radar_info=cb8a7bc0 [22:38:17:905] [ 19.231389][3]dfs_attach: event log enabled by default [22:38:17:905] [ 19.232380][3]>>>> CB Set (null) [22:38:17:905] [ 19.232686][3]ol_ath_attach() UMAC attach . [22:38:17:905] [ 19.232707][3] [22:38:17:905] [ 19.232707][3] BURSTING enabled by default [22:38:17:905] [ 19.232779][3]ol_ath_attach: Set global_ic[1] ..ptr:bf570ee0 [22:38:17:929] [ 19.232836][3]ath_lowi_if_netlink_init LOWI Netlink successfully created [22:38:17:929] [ 19.232886][3]osif_wrap_attach:444 osif wrap attached [22:38:17:929] [ 19.232930][3]osif_wrap_devt_init:405 osif wrap dev table init done [22:38:17:929] [ 19.232954][3] Wrap Attached: Wrap_com =c9f9d200 ic->ic_wrap_com=c9f9d200 &wrap_com->wc_devt=c9f9d200 [22:38:17:952] [ 19.232975][3]__ol_ath_attach: needed_headroom reservation 60 [22:38:17:952] [ 19.236250][1]avm_wlan_get_current_temp_cb: Error invalid handle: (null) != c9cfa980 [22:38:17:952] [ 19.236524][3]AVM/WLAN: __ol_ath_thermal_mitigation_attach -> temperature callback registered for wifi0 as Wifi-2.4GHz! [22:38:17:975] [ 19.236561][3]ol_ath_thermal_mitigation_attach: -- [22:38:17:975] [ 19.236610][3]ol_ath_ahb_probe num_radios=0, wifi_radios[0].sc = cbb40540 wifi_radio_type = 2 [22:38:17:975] [ 19.236645][3]ath_sysfs_diag_init: diag_fsattr [22:38:18:000] [ 19.236733][3]Suspending Target scn=cbb40540 [22:38:18:000] [ 19.236768][3]waiting for target paused event from target [22:38:18:000] [ 19.242987][3]ol_ath_thermal_mitigation_detach: ++ [22:38:18:000] [ 19.243073][3]AVM/WLAN: __ol_ath_thermal_mitigation_detach -> temperature callback deregistered for wifi0! [22:38:18:000] [ 19.243125][3]ol_ath_thermal_mitigation_detach: -- [22:38:18:017] [ 19.243186][3]DFS clear list erased [22:38:18:017] [ 19.243215][3]ol_if_dfs_clist_update: called, cmd=1, nollist= (null), nentries=0 [22:38:18:017] [ 19.246363] ce_h2t_tx_ce_cleanup 1089 Fastpath mode ON, Cleaning up HTT Tx CEsmart_log_deinit: Smart logging Disabled [22:38:18:031] [ 19.246657][3]ol_tx_me_exit: Already Disabled !!! [22:38:18:031] [ 19.248949][3]htt_avm_offload_deinit:246 unregistered irq 50 for wifi0-avm_offload_rx_refill on wifi0 [22:38:18:044] [ 19.248998][3]htt_avm_offload_deinit:252 unregistered irq 55 for wifi0-avm_offload_tx_free on wifi0 [22:38:18:060] [ 19.249200][3] [22:38:18:060] [ 19.249200][3]ol_ath_free_tgt_iram() iram free paddr: 0x980000 host paddr 0x8a700000 Radio id 0 [22:38:18:060] [ 19.274580][3]Reset complete for wifi core id : 0 [22:38:18:084] [ 19.274646] hif_disable: Xhif_napi_destroy: NAPI 6 destroyed [22:38:18:084] [ 19.274685] hif_napi_destroy: no NAPI instances. Zapped.ath_sysfs_diag_fini: diag_fsattr [22:38:18:084] [ 19.275159] *********** IPQ4019 *************Wifi1 CPU frequency 250000000 [22:38:18:108] [ 19.295381] hif_target_sync_ahb: Got FW signal, retries = 1hif_ahb_enable_bus: X - hif_type = 0xd, target_type = 0xbhif_config_ce: ce_init donehif_config_ce: X, ret = 0hif_set_hia: Ehif_pci_bus_configure: hif_set_hia donehif_configure_irq: Ehif_enable: X OKhif_napi_create: NAPI structures initializedhif_napi_create: NAPI id 6 created for pipe 5qca_napi_create: napi instance 32 created on pipe 4 [22:38:18:127] [ 19.301853] hif_napi_event: received evnt: CONF cmd; v = 1 (state=0x1)hif_napi_event: setting configuration to ON [22:38:18:145] __ol_ath_attach() Allocated scn cbbc0540 [22:38:18:145] [ 19.302008][3]__ol_ath_attach: dev name wifi1 [22:38:18:145] [ 19.302033][3]ol_ath_attach interface_id 1 [22:38:18:145] [ 19.302341][3]ol_target_init() BMI inited. [22:38:18:163] [ 19.302477][3]ol_target_init() BMI Get Target Info. [22:38:18:163] [ 19.302501][3]Chip id: 0xb, chip version: 0x1000000 [22:38:18:163] [ 19.302519][3] [22:38:18:163] [ 19.302519][3] CE WAR Disabled [22:38:18:163] [ 19.302681][3]NUM_DEV=1 FWMODE=0x2 FWSUBMODE=0x0 FWBR_BUF 0 [22:38:18:185] [ 19.302893][3]ol_target_init() configure Target . [22:38:18:185] [ 19.303011][3] [22:38:18:185] [ 19.303011][3] Target Version is 1000000 [22:38:18:185] [ 19.303036][3] [22:38:18:185] [ 19.303036][3] Flash Download Address c0000 [22:38:18:185] [ 19.303060][3]ol_transfer_bin_file: flash data file defined [22:38:18:206] [ 19.303182][3]ol_transfer_bin_file 3904: Download AVM WLAN EEPROM len 12064 [22:38:18:206] [ 19.303233][3]qc98xx_verify_checksum: flash checksum passed: 0xe644 [22:38:18:206] [ 19.303254][3]ol_transfer_bin_file 4007: Download Flash data len 12064 [22:38:18:222] [ 19.303688][3]Board extended Data download address: 0x0 [22:38:18:222] [ 19.323283][3] [22:38:18:222] [ 19.323283][3] Board data initialized [22:38:18:222] [ 19.323512][3]ol_ath_download_firmware: Download OTP, flash download ADDRESS 0xc0000 [22:38:18:242] [ 19.323538][3] [22:38:18:242] [ 19.323538][3] Selecting OTP binary for CHIP Version -1083735048 [22:38:18:242] [ 19.323725][3]ol_transfer_bin_file 3771: downloading file 0, Download data len 4688 [22:38:18:242] [ 19.340208][3] [22:38:18:268] [ 19.340208][3] First OTP send param 10 [22:38:18:268] [ 19.344941][3]ol_ath_download_firmware :First OTP download and Execute is good address:0x4400 return param 4660 [22:38:18:268] [ 19.344970][3]ol_ath_download_firmware:##Board Id 17 , CHIP Id 0 [22:38:18:268] [ 19.345129][3]ol_ath_download_firmware: Using 0x1234 for the remainder of init [22:38:18:289] [ 19.345155][3] [22:38:18:289] [ 19.345155][3] Selecting OTP binary for CHIP Version -1083735048 [22:38:18:289] [ 19.345342][3]ol_transfer_bin_file 3771: downloading file 0, Download data len 4688 [22:38:18:289] [ 19.361791][3] [22:38:18:289] [ 19.361791][3] [Non-Flash] mode : Module param 0x0 selected [22:38:18:314] [ 19.361828][3] [22:38:18:314] [ 19.361828][3] Second otp download Param 0 [22:38:18:314] [ 19.376688][3]ol_ath_download_firmware : Second OTP download and Execute is good, param=0x0 [22:38:18:314] [ 19.376722][3] [22:38:18:314] [ 19.376722][3] Mission mode: Firmware CHIP Version -1083735048 [22:38:18:338] [ 19.377556][3]ol_swap_seg_alloc: Successfully allocated memory for SWAP size=262144 [22:38:18:338] [ 19.377995][3]ol_swap_wlan_memory_expansion: length:185431 size_left:185451 dma_size_left:262144 fw_temp:d2c81004 fw_entry_size:185455 [22:38:18:338] [ 19.378028][3]ol_swap_wlan_memory_expansion: dma_virt_addr :d2a30000 fw_temp: d2c81008 length: 185431 [22:38:18:356] [ 19.378314][3]Swap: bytes_left to copy: fw:16; dma_page:76713 [22:38:18:356] [ 19.378342][3]ol_swap_wlan_memory_expansion: length:0 size_left:12 dma_size_left:76713 fw_temp:d2cae463 fw_entry_size:185455 [22:38:18:375] [ 19.378362][3]Swap: wrong length read:0 [22:38:18:375] [ 19.378383][3]ol_swap_wlan_memory_expansion: Swap total_bytes copied: 185431 Target address 4180e0 [22:38:18:375] [ 19.378528][3]scn=cbbc0540 target_write_addr=4180e0 seg_info=cc958610 [22:38:18:403] [ 19.378559][3]ol_transfer_swap_struct:Code swap structure successfully downloaded for bin type =2 [22:38:18:403] [ 19.378581][3]bin_filename=IPQ4019/hw.1/athwlan.bin swap_filename=/lib/firmware/IPQ4019/hw.1/athwlan.codeswap.bin [22:38:18:403] [ 19.378635][3]ol_transfer_bin_file: Downloading firmware file: IPQ4019/hw.1/athwlan.bin [22:38:18:427] [ 19.379683][3]ol_transfer_bin_file 3771: downloading file 1, Download data len 380020 [22:38:18:427] Jan 1 01:00:19 dsld[1392]: DSL Mac 7c:ff:4d:13:75:38 [22:38:18:427] Jan 1 01:00:20 dsld[1392]: VOIP Mac 7c:ff:4d:13:75:39 [22:38:18:427] Jan 1 01:00:20 dsld[1392]: VCC2 Mac 7c:ff:4d:13:75:3a [22:38:18:450] Jan 1 01:00:20 dsld[1392]: VCC3 Mac 7c:ff:4d:13:75:35 [22:38:18:450] [ 20.195652][2]kdsld: kdsld_vcc_preunregister: * [22:38:18:450] [ 20.196836][2]kdsld: nr_ipv4 0 nr_ipv6 0 nr_vpn 0 [22:38:18:450] Jan 1 01:00:20 dsld[1392]: sync_ata is igd_device [22:38:18:450] [ 20.197862][2]kdsld: flushing internet sessions [22:38:18:478] Jan 1 01:00:20 dsld[1392]: /var/flash/vpn.cfg: is empty [22:38:18:478] Jan 1 01:00:20 dsld[1392]: load_config(vpn): file empty - factory default loaded [22:38:18:478] Jan 1 01:00:20 dsld[1392]: interface lo carrier_up. [22:38:18:478] Jan 1 01:00:20 dsld[1392]: /var/flash/stat.cfg: is empty [22:38:18:478] Jan 1 01:00:20 dsld[1392]: /etc/default/avme/stat.cfg: is empty [22:38:18:501] Jan 1 01:00:20 dsld[1392]: load_config(stat): file empty - factory default loaded [22:38:18:501] [ 20.231678][2]device wan entered promiscuous mode [22:38:18:501] Jan 1 01:00:20 dsld[1392]: egress shaping with sfq [22:38:18:501] Jan 1 01:00:20 dsld[1392]: ingress shaping with fq_codel [22:38:18:501] [ 20.416032][2]kdsld: kdsld_vcc_preregister: preata1 [22:38:18:521] [ 20.416084][2]kdsld: kdsld_vcc_preregister: vcc->ifaces= (null) [22:38:18:521] [ 20.416526][2]kdsld: IPv6: internet: link local fe80::7eff:4dff:fe13:7538 remote :: [22:38:18:521] [ 20.416560][2]kdsld: internet: set_rcv_ipaddr: 192.168.178.1 [22:38:18:540] Jan 1 01:00:20 dsld[1392]: no sync [22:38:18:540] Jan 1 01:00:20 dsld[1392]: _sync_lost(0): sync_ata (0) [22:38:18:540] [ 20.420554][2]kdsld: kdsld_dsld_ioctl got unknown cmd=0x400441a0 [22:38:18:557] Jan 1 01:00:20 dsld[1392]: verbose: DISABLED [22:38:18:557] 1970-01-01 01:00:20 dsld[1311]: ready (1sec) [22:38:18:624] Jan 1 01:00:20 cloudmsgd[1410]: starting ... [22:38:18:687] [ 20.643616][2]ol_target_init() Download FW done. [22:38:18:694] [ 20.643966][2] [22:38:18:694] [ 20.643966][2]ol_ath_copy_tgt_iram() iram read paddr: 0x980000 host paddr 0x89c00000 vaddr 0xd2e1d000 Radio id 1 [22:38:18:717] cloudmsgd not running after started [22:38:18:780] Jan 1 01:00:20 deviceinfod[1417]: starting ... [22:38:18:799] 1970-01-01 01:00:20 deviceinfod[1417]: ready (0sec) [22:38:18:815] execute /etc/init.d/E47-cpunet [22:38:18:830] execute /etc/init.d/E47-voip [22:38:18:853] [ 20.808777][2]ol_ath_copy_tgt_iram(): hif_diag_read_tgtiram FW IRAM content success [22:38:18:858] [ 20.809067][2]ol_ath_attach() WMI attached. wmi_handle cdb4c000 [22:38:18:869] [ 20.809105][2]wmi_unified_register_event_handler: Event id 62 is unavailable [22:38:18:882] [ 20.809129] +htc_create .. HIF :cc9b8000-htc_create: (0xca760000) [22:38:18:882] [ 20.809223][2]htc_wmi_init() HT Create . ca760000 [22:38:18:895] [ 20.809368][2]htc_wmi_init 8312 host_enable 0 nss_nwifi_offload 0 [22:38:18:895] [ 20.809428][2]ol_ath_set_default_tgt_config : AC Minfree buffer allocation through module param (umac.ko) [22:38:18:908] [ 20.809467][2] OL_ACBKMinfree : 0 [22:38:18:908] [ 20.809486][2] OL_ACBEMinfree : 0 [22:38:18:908] [ 20.809504][2] OL_ACVIMinfree : 0 [22:38:18:908] [ 20.809520][2] OL_ACVOMinfree : 0 [22:38:18:929] [ 20.809551] hif_enable_fastpath, Enabling fastpath mode [22:38:18:929] [ 20.809566][2]+HWT [22:38:18:929] [ 20.809592] hif_completion_thread_startup: pipe_num:0 pipe_info:0xcc9bce78hif_completion_thread_startup: pipe_num:3 pipe_info:0xcc9bcf50hif_completion_thread_startup: pipe_num:4 pipe_info:0xcc9bcf98 [22:38:18:947] [ 20.819181][2]-HWT [22:38:18:947] [ 20.819302][2]Startup Mode-0 set [22:38:18:947] [ 20.819329][2] [22:38:18:947] [ 20.819329][2]<=== cfg max peer id 1056 ====> [22:38:18:947] [ 20.820156][2]htt_peer_map_timer_init Enter pdev c9f10000 hrtimer c9f14930 [22:38:18:967] [ 20.820206][2] [22:38:18:967] [ 20.820206][2] htt_alloc_peer_map_mem : Alloc Success : host q vaddr ca75e000 paddr 8a75e000 [22:38:18:967] [ 20.820234][2] [22:38:18:967] [ 20.820234][2] htt_alloc_peer_map_mem : Flush Interval Configured to 256 pkts [22:38:18:985] [ 20.820341][2]htt_avm_offload_init:201 registered irq 51 for wifi1-avm_offload_rx_refill on wifi1 [22:38:18:985] [ 20.820423][2]htt_avm_offload_init:224 registered irq 56 for wifi1-avm_offload_tx_free on wifi1 [22:38:18:998] [ 20.823248][2]ol_txrx_pdev_attach: 2500 tx desc's allocated ; range starts from c94e0000 [22:38:19:012] [ 20.825562][0]Firmware_Build_Number:50 [22:38:19:012] [ 20.825608][0]FW wireless modes: 0x1f9001 [22:38:19:012] [ 20.825633] num_rf_chain:0x00000002 ht_cap_info:0x0000085b vht_cap_info:0x339959b2 vht_supp_mcs:0x0000fffa [22:38:19:027] [ 20.826693][0]wmi_service_coex_gpio 0, wmi_service_4_wire_coex_support 0, coex_version 0 [22:38:19:027] [ 20.826726][0] [22:38:19:040] [ 20.826726][0]Sending Ext resource cfg: HOST PLATFORM as 0 [22:38:19:040] [ 20.826726][0]fw_feature_bitmap as 50 to TGT [22:38:19:040] [ 20.826825][0]ol_ath_service_ready_event: tt_support: 1 [22:38:19:058] [ 20.826856][0]ol_ath_service_ready_event: periodic_chan_stats: 1 [22:38:19:058] [ 20.826876][0]ol_ath_service_ready_event: sw_cal_support_check_flag: 1 [22:38:19:058] [ 20.826899][0]Peer Caching Enabled ; num_peers = 530, num_active_peers = 52 num_tids = 104, num_vdevs = 17 [22:38:19:077] [ 20.826976][0]EXT NSS Supported [22:38:19:077] [ 20.827020][0]idx 1 req 2 num_units 1 num_unit_info 12 unit size 256 actual units 53 [22:38:19:077] [ 20.827092][0]ol_ath_alloc_host_mem_chunk req_id 2 idx 0 num_units 53 unit_len 256, [22:38:19:096] [ 20.827122][0]idx 2 req 3 num_units 1 num_unit_info 12 unit size 1024 actual units 53 [22:38:19:096] [ 20.827202][0]ol_ath_alloc_host_mem_chunk req_id 3 idx 1 num_units 53 unit_len 1024, [22:38:19:113] [ 20.827233][0]idx 3 req 4 num_units 1 num_unit_info 12 unit size 4096 actual units 53 [22:38:19:113] [ 20.827441][0]ol_ath_alloc_host_mem_chunk req_id 4 idx 2 num_units 53 unit_len 4096, [22:38:19:126] [ 20.827528][0]idx 0 req 1 num_units 0 num_unit_info 2 unit size 744 actual units 531 [22:38:19:126] [ 20.827907][0]ol_ath_alloc_host_mem_chunk req_id 1 idx 3 num_units 531 unit_len 744, [22:38:19:140] [ 20.827940][0]idx 4 req 6 num_units 35 num_unit_info 0 unit size 3072 actual units 35 [22:38:19:140] [ 20.828056][0]ol_ath_alloc_host_mem_chunk req_id 6 idx 4 num_units 35 unit_len 3072, [22:38:19:152] [ 20.828080][0]idx 5 req 7 num_units 1 num_unit_info 0 unit size 6144 actual units 1 [22:38:19:164] [ 20.828236][0]ol_ath_alloc_host_mem_chunk req_id 7 idx 5 num_units 1 unit_len 6144, [22:38:19:164] [ 20.828262][0]idx 6 req 5 num_units 0 num_unit_info 2 unit size 2056 actual units 531 [22:38:19:178] [ 20.829256][0]ol_ath_alloc_host_mem_chunk req_id 5 idx 6 num_units 531 unit_len 2056, [22:38:19:178] [ 20.829288][0]Support not added yet for Service 91 [22:38:19:191] [ 20.829307][0]Support not added yet for Service 92 [22:38:19:191] [ 20.829324][0]No EXT_MSG send INIT now [22:38:19:191] [ 20.829353][0]chunk 0 len 13568 requested , ptr 0x894c8000 [22:38:19:204] [ 20.829374][0]chunk 1 len 54272 requested , ptr 0x894d0000 [22:38:19:204] [ 20.829393][0]chunk 2 len 217088 requested , ptr 0x89540000 [22:38:19:216] [ 20.829412][0]chunk 3 len 395064 requested , ptr 0x89580000 [22:38:19:216] [ 20.829432][0]chunk 4 len 107520 requested , ptr 0x89600000 [22:38:19:216] [ 20.829451][0]chunk 5 len 6144 requested , ptr 0x89bf4000 [22:38:19:231] [ 20.829470][0]chunk 6 len 1091736 requested , ptr 0x89000000 [22:38:19:231] [ 20.829490][0]chunk 7 len 0 requested , ptr 0x89c00000 [22:38:19:246] [ 20.829688][0]smart_log_init: Smart logging Enabled buf=c9110000 (size=65536) [22:38:19:246] [ 20.835659][0]wmi_control_rx :event handler is not registered: event id 0x9044 [22:38:19:263] [ 20.842298][0]wmi_control_rx :event handler is not registered: event id 0x9044 [22:38:19:263] [ 20.848789][0]wmi_control_rx :event handler is not registered: event id 0x9044 [22:38:19:277] [ 20.855289][0]wmi_control_rx :event handler is not registered: event id 0x9044 [22:38:19:277] [ 20.860713][0]Version = 16777216 3 status = 0 [22:38:19:277] [ 20.860788][2]ol_ath_connect_htc() WMI is ready [22:38:19:292] [ 20.860862][2]htt_h2t_frag_desc_bank_cfg_msg - HTT_H2T_MSG_TYPE_FRAG_DESC_BANK_CFG sent to FW for radio ID = 1 [22:38:19:292] [ 20.860880][0]target uses HTT version 2.2; host uses 2.2 [22:38:19:307] [ 20.865928][2]ol_ath_attach() connect HTC. [22:38:19:307] [ 20.865957][2]bypasswmi : 0 [22:38:19:307] [ 20.865984][2]ol_regdmn_start: reg-domain param: regdmn=0, countryName=, wModeSelect=FFFFFFFF, netBand=FFFFFFFF, extendedChanMode=0. [22:38:19:323] [ 20.866025][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x2) flags 0x2150 [22:38:19:323] [ 20.866087][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x4) flags 0xa0 [22:38:19:338] [ 20.866110][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x8) flags 0xc0 [22:38:19:338] [ 20.866131][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x20) flags 0xd0 [22:38:19:361] [ 20.866152][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x40) flags 0x150 [22:38:19:361] [ 20.866173][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x800) flags 0x10080 [22:38:19:361] [ 20.866194][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x2000) flags 0x20080 [22:38:19:380] [ 20.866230][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x4000) flags 0x40080 [22:38:19:380] [ 20.866357][2]Add VHT80 channel: 5210 [22:38:19:380] [ 20.866377][2]Add VHT80 channel: 5290 [22:38:19:380] [ 20.866395][2]Add VHT80 channel: 5530 [22:38:19:405] [ 20.866413][2]Add VHT80 channel: 5610 [22:38:19:405] [ 20.866430][2]Add VHT80 channel: 5690 [22:38:19:405] [ 20.866449][2]Add VHT80 channel: 5775 [22:38:19:405] [ 20.866493][2]Skipping VHT80 channel 5825 [22:38:19:405] [ 20.866516][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x200000) flags 0x4000100 [22:38:19:422] [ 20.866538][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x400000) flags 0x8000100 [22:38:19:422] [ 20.867676][2]freq=58 [22:38:19:422] [ 20.867697][2]freq=106 [22:38:19:422] [ 20.867714][2]freq=122 [22:38:19:422] [ 20.867731][2]freq=138 [22:38:19:437] [ 20.867808][2]OL Resmgr Init-ed [22:38:19:437] [ 20.867995][2]ieee80211_bsteering_attach: Band steering initialized [22:38:19:437] [ 20.868024][2]acfg_attach: Offload using existing sock ccfb6000 [22:38:19:452] [ 20.868086][2]SPECTRAL : get_capability not registered [22:38:19:452] [ 20.868105][2]HAL_CAP_PHYDIAG : Capable [22:38:19:452] [ 20.868125][2]SPECTRAL : Need to fix the capablity check for RADAR (spectral_attach : 237) [22:38:19:468] [ 20.868143][2]SPECTRAL : get_capability not registered [22:38:19:468] [ 20.868161][2]HAL_CAP_RADAR : Capable [22:38:19:468] [ 20.868180][2]SPECTRAL : Need to fix the capablity check for SPECTRAL [22:38:19:482] [ 20.868180][2] (spectral_attach : 242) [22:38:19:482] [ 20.868219][2]SPECTRAL : get_capability not registered [22:38:19:482] [ 20.868238][2]HAL_CAP_SPECTRAL_SCAN : Capable [22:38:19:498] [ 20.868256][2]SPECTRAL : get_tsf64 not registered [22:38:19:498] [ 20.868277][2]spectral_init_netlink 78 NULL SKB [22:38:19:498] [ 20.868302][2]GreenAP on wifi1: Attached [22:38:19:498] [ 20.868321][2]Green-AP : Attached [22:38:19:515] [ 20.868340][2]rate power table override is only supported for AR98XX [22:38:19:515] [ 20.868486][2]ol_if_dfs_attach: called; ptr=c95e998c, radar_info=cb8a7bc0 [22:38:19:515] [ 20.868507][2]dfs_attach: event log enabled by default [22:38:19:541] [ 20.869576][2]>>>> CB Set (null) [22:38:19:541] [ 20.870367][2]ol_ath_attach() UMAC attach . [22:38:19:541] [ 20.870393][2] [22:38:19:541] [ 20.870393][2] BURSTING enabled by default [22:38:19:541] [ 20.870465][2]ol_ath_attach: Set global_ic[2] ..ptr:bf570ee0 [22:38:19:541] [ 20.870497][2]ath_lowi_if_netlink_init Incremented LOWI netlink ref count: 2 [22:38:19:559] [ 20.870529][2]osif_wrap_attach:444 osif wrap attached [22:38:19:559] [ 20.870575][2]osif_wrap_devt_init:405 osif wrap dev table init done [22:38:19:559] [ 20.870598][2] Wrap Attached: Wrap_com =c94a7c00 ic->ic_wrap_com=c94a7c00 &wrap_com->wc_devt=c94a7c00 [22:38:19:578] [ 20.870619][2]__ol_ath_attach: needed_headroom reservation 60 [22:38:19:578] [ 20.875918][2]avm_wlan_get_current_temp_cb: Error invalid handle: (null) != c9cfc780 [22:38:19:594] [ 20.875981][1]AVM/WLAN: __ol_ath_thermal_mitigation_attach -> temperature callback registered for wifi1 as Wifi-5GHz! [22:38:19:594] [ 20.876008][1]ol_ath_thermal_mitigation_attach: -- [22:38:19:608] [ 20.876059][1]ol_ath_ahb_probe num_radios=1, wifi_radios[1].sc = cbbc0540 wifi_radio_type = 2 [22:38:19:608] [ 20.876095][1]ath_sysfs_diag_init: diag_fsattr [22:38:19:608] [ 20.876173][1]Suspending Target scn=cbbc0540 [22:38:19:625] [ 20.876211][1]waiting for target paused event from target [22:38:19:625] [ 20.882397][1]ol_ath_thermal_mitigation_detach: ++ [22:38:19:625] [ 20.882482][1]AVM/WLAN: __ol_ath_thermal_mitigation_detach -> temperature callback deregistered for wifi1! [22:38:19:640] [ 20.882537][1]ol_ath_thermal_mitigation_detach: -- [22:38:19:640] [ 20.882600][1]freq=58 [22:38:19:640] [ 20.882618][1]freq=106 [22:38:19:655] [ 20.882635][1]freq=122 [22:38:19:655] [ 20.882651][1]freq=138 [22:38:19:655] [ 20.882677][1]DFS clear list erased [22:38:19:655] [ 20.882703][1]ol_if_dfs_clist_update: called, cmd=1, nollist= (null), nentries=0 [22:38:19:669] [ 20.885895] ce_h2t_tx_ce_cleanup 1089 Fastpath mode ON, Cleaning up HTT Tx CEsmart_log_deinit: Smart logging Disabled [22:38:19:669] [ 20.886193][1]ol_tx_me_exit: Already Disabled !!! [22:38:19:681] [ 20.888749][1]htt_avm_offload_deinit:246 unregistered irq 51 for wifi1-avm_offload_rx_refill on wifi1 [22:38:19:681] [ 20.888800][1]htt_avm_offload_deinit:252 unregistered irq 56 for wifi1-avm_offload_tx_free on wifi1 [22:38:19:694] [ 20.889017][1] [22:38:19:694] [ 20.889017][1]ol_ath_free_tgt_iram() iram free paddr: 0x980000 host paddr 0x89c00000 Radio id 1 [22:38:19:707] [ 20.914436][1]Reset complete for wifi core id : 1 [22:38:19:707] [ 20.914509] hif_disable: Xhif_napi_destroy: NAPI 6 destroyed [22:38:19:720] [ 20.914550] hif_napi_destroy: no NAPI instances. Zapped.ath_sysfs_diag_fini: diag_fsattr [22:38:19:740] [ 20.914898][1]ath_ol_pci: (Atheros/multi-bss) [22:38:19:740] [ 21.206317][2]Starting the target before sending the command [22:38:19:740] [ 21.206412] *********** IPQ4019 *************Wifi0 CPU frequency 250000000 [22:38:19:740] [ 21.226683] hif_target_sync_ahb: Got FW signal, retries = 1hif_ahb_enable_bus: X - hif_type = 0xd, target_type = 0xbhif_config_ce: ce_init donehif_config_ce: X, ret = 0hif_set_hia: Ehif_pci_bus_configure: hif_set_hia donehif_configure_irq: Ehif_enable: X OKhif_napi_create: NAPI structures initializedhif_napi_create: NAPI id 6 created for pipe 5qca_napi_create: napi instance 32 created on pipe 4 [22:38:19:784] [ 21.232829] hif_napi_event: received evnt: CONF cmd; v = 1 (state=0x1)hif_napi_event: setting configuration to ON [22:38:19:784] [ 21.237180][2]ol_target_init() BMI inited. [22:38:19:805] [ 21.237327][2]ol_target_init() BMI Get Target Info. [22:38:19:805] [ 21.237353][2]Chip id: 0xb, chip version: 0x1000000 [22:38:19:805] [ 21.237372][2] [22:38:19:805] [ 21.237372][2] CE WAR Disabled [22:38:19:805] [ 21.237536][2]NUM_DEV=1 FWMODE=0x2 FWSUBMODE=0x0 FWBR_BUF 0 [22:38:19:828] [ 21.237744][2]ol_target_init() configure Target . [22:38:19:828] [ 21.237857][2] [22:38:19:828] [ 21.237857][2] Target Version is 1000000 [22:38:19:828] [ 21.237882][2] [22:38:19:828] [ 21.237882][2] Flash Download Address c0000 [22:38:19:828] [ 21.237905][2]ol_transfer_bin_file: flash data file defined [22:38:19:828] [ 21.238035][2]ol_transfer_bin_file 3904: Download AVM WLAN EEPROM len 12064 [22:38:19:850] [ 21.238085][2]qc98xx_verify_checksum: flash checksum passed: 0x479c [22:38:19:850] [ 21.238107][2]ol_transfer_bin_file 4007: Download Flash data len 12064 [22:38:19:850] [ 21.238514][2]Board extended Data download address: 0x0 [22:38:19:865] [ 21.258117][2] [22:38:19:865] [ 21.258117][2] Board data initialized [22:38:19:865] [ 21.258286][2]ol_ath_download_firmware: Download OTP, flash download ADDRESS 0xc0000 [22:38:19:865] [ 21.258312][2] [22:38:19:883] [ 21.258312][2] Selecting OTP binary for CHIP Version -1083735048 [22:38:19:883] [ 21.258513][2]ol_transfer_bin_file 3771: downloading file 0, Download data len 4688 [22:38:19:883] [ 21.274994][2] [22:38:19:883] [ 21.274994][2] First OTP send param 10 [22:38:19:903] [ 21.279729][2]ol_ath_download_firmware :First OTP download and Execute is good address:0x4000 return param 4660 [22:38:19:903] [ 21.279762][2]ol_ath_download_firmware:##Board Id 16 , CHIP Id 0 [22:38:19:917] [ 21.279926][2]ol_ath_download_firmware: Using 0x1234 for the remainder of init [22:38:19:917] [ 21.279952][2] [22:38:19:917] [ 21.279952][2] Selecting OTP binary for CHIP Version -1083735048 [22:38:19:917] [ 21.280140][2]ol_transfer_bin_file 3771: downloading file 0, Download data len 4688 [22:38:19:932] [ 21.296610][2] [22:38:19:932] [ 21.296610][2] [Non-Flash] mode : Module param 0x0 selected [22:38:19:932] [ 21.296651][2] [22:38:19:955] [ 21.296651][2] Second otp download Param 0 [22:38:19:955] [ 21.315540][2]ol_ath_download_firmware : Second OTP download and Execute is good, param=0x0 [22:38:19:955] [ 21.315578][2] [22:38:19:955] [ 21.315578][2] Mission mode: Firmware CHIP Version -1083735048 [22:38:19:955] [ 21.316150][2]ol_swap_wlan_memory_expansion: length:185431 size_left:185451 dma_size_left:262144 fw_temp:d3381004 fw_entry_size:185455 [22:38:19:976] [ 21.316184][2]ol_swap_wlan_memory_expansion: dma_virt_addr :d24b0000 fw_temp: d3381008 length: 185431 [22:38:19:976] [ 21.316481][2]Swap: bytes_left to copy: fw:16; dma_page:76713 [22:38:19:996] [ 21.316507][2]ol_swap_wlan_memory_expansion: length:0 size_left:12 dma_size_left:76713 fw_temp:d33ae463 fw_entry_size:185455 [22:38:19:996] [ 21.316527][2]Swap: wrong length read:0 [22:38:19:996] [ 21.316550][2]ol_swap_wlan_memory_expansion: Swap total_bytes copied: 185431 Target address 4180e0 [22:38:20:016] [ 21.316716][2]scn=cbb40540 target_write_addr=4180e0 seg_info=ce4f4810 [22:38:20:016] [ 21.316744][2]ol_transfer_swap_struct:Code swap structure successfully downloaded for bin type =2 [22:38:20:033] [ 21.316766][2]bin_filename=IPQ4019/hw.1/athwlan.bin swap_filename=/lib/firmware/IPQ4019/hw.1/athwlan.codeswap.bin [22:38:20:033] [ 21.316823][2]ol_transfer_bin_file: Downloading firmware file: IPQ4019/hw.1/athwlan.bin [22:38:20:046] [ 21.317704][2]ol_transfer_bin_file 3771: downloading file 1, Download data len 380020 [22:38:20:626] [ 22.582091][3]ol_target_init() Download FW done. [22:38:20:632] [ 22.582140][3] [22:38:20:645] [ 22.582140][3]ol_ath_copy_tgt_iram() iram read paddr: 0x980000 host paddr 0x8a700000 vaddr 0xd26a2000 Radio id 0 [22:38:20:790] [ 22.745896][3]ol_ath_copy_tgt_iram(): hif_diag_read_tgtiram FW IRAM content success [22:38:20:797] [ 22.745934] +htc_create .. HIF :c91c0000-htc_create: (0xcdb57000) [22:38:20:808] [ 22.746008][3]htc_wmi_init() HT Create . cdb57000 [22:38:20:808] [ 22.746104][3]htc_wmi_init 8312 host_enable 0 nss_nwifi_offload 0 [22:38:20:827] [ 22.746135][3]ol_ath_set_default_tgt_config : AC Minfree buffer allocation through module param (umac.ko) [22:38:20:827] [ 22.746155][3] OL_ACBKMinfree : 0 [22:38:20:827] [ 22.746173][3] OL_ACBEMinfree : 0 [22:38:20:854] [ 22.746193][3] OL_ACVIMinfree : 0 [22:38:20:854] [ 22.746210][3] OL_ACVOMinfree : 0 [22:38:20:854] [ 22.746234] hif_enable_fastpath, Enabling fastpath mode [22:38:20:854] [ 22.746248][3]+HWT [22:38:20:854] [ 22.746274] hif_completion_thread_startup: pipe_num:0 pipe_info:0xc91c4e78hif_completion_thread_startup: pipe_num:3 pipe_info:0xc91c4f50hif_completion_thread_startup: pipe_num:4 pipe_info:0xc91c4f98 [22:38:20:877] [ 22.755974][3]-HWT [22:38:20:877] [ 22.756028][3]wmi_unified_register_event_handler : event handler already registered 0x8002 [22:38:20:877] [ 22.756115][3]Startup Mode-0 set [22:38:20:877] [ 22.756144][3] [22:38:20:877] [ 22.756144][3]<=== cfg max peer id 1056 ====> [22:38:20:903] [ 22.756638][3]htt_peer_map_timer_init Enter pdev c9d78000 hrtimer c9d7c930 [22:38:20:903] [ 22.756683][3] [22:38:20:903] [ 22.756683][3] htt_alloc_peer_map_mem : Alloc Success : host q vaddr c9c92000 paddr 89c92000 [22:38:20:903] [ 22.756709][3] [22:38:20:903] [ 22.756709][3] htt_alloc_peer_map_mem : Flush Interval Configured to 256 pkts [22:38:20:927] [ 22.756786][3]htt_avm_offload_init:201 registered irq 50 for wifi0-avm_offload_rx_refill on wifi0 [22:38:20:927] [ 22.756839][3]htt_avm_offload_init:224 registered irq 55 for wifi0-avm_offload_tx_free on wifi0 [22:38:20:927] [ 22.759525][3]ol_txrx_pdev_attach: 2500 tx desc's allocated ; range starts from c9fc0000 [22:38:20:946] [ 22.761625][0]Firmware_Build_Number:50 [22:38:20:946] [ 22.761671][0]FW wireless modes: 0x680c [22:38:20:946] [ 22.761694] num_rf_chain:0x00000002 ht_cap_info:0x0000085b vht_cap_info:0x339959b2 vht_supp_mcs:0x0000fffa [22:38:20:968] [ 22.761749][0]btcoex_support 0, wlan_prio_gpio 0, coex_gpio_pin 0 0 0 [22:38:20:968] [ 22.761771][0]wmi_service_coex_gpio 1, wmi_service_4_wire_coex_support 1, coex_version 0 [22:38:20:968] [ 22.761796][0] [22:38:20:968] [ 22.761796][0]Sending Ext resource cfg: HOST PLATFORM as 0 [22:38:20:992] [ 22.761796][0]fw_feature_bitmap as 50 to TGT [22:38:20:992] [ 22.761884][0]ol_ath_service_ready_event: tt_support: 1 [22:38:20:992] [ 22.761905][0]ol_ath_service_ready_event: periodic_chan_stats: 1 [22:38:20:992] [ 22.761924][0]ol_ath_service_ready_event: sw_cal_support_check_flag: 1 [22:38:21:014] [ 22.761946][0]Peer Caching Enabled ; num_peers = 530, num_active_peers = 52 num_tids = 104, num_vdevs = 17 [22:38:21:014] [ 22.762009][0]EXT NSS Supported [22:38:21:014] [ 22.762047][0]idx 1 req 2 num_units 1 num_unit_info 12 unit size 256 actual units 53 [22:38:21:014] [ 22.762075][0]ol_ath_alloc_host_mem_chunk req_id 2 idx 0 num_units 53 unit_len 256, [22:38:21:034] [ 22.762098][0]idx 2 req 3 num_units 1 num_unit_info 12 unit size 1024 actual units 53 [22:38:21:034] [ 22.762120][0]ol_ath_alloc_host_mem_chunk req_id 3 idx 1 num_units 53 unit_len 1024, [22:38:21:056] [ 22.762142][0]idx 3 req 4 num_units 1 num_unit_info 12 unit size 4096 actual units 53 [22:38:21:056] [ 22.762164][0]ol_ath_alloc_host_mem_chunk req_id 4 idx 2 num_units 53 unit_len 4096, [22:38:21:056] [ 22.762186][0]idx 0 req 1 num_units 0 num_unit_info 2 unit size 744 actual units 531 [22:38:21:077] [ 22.762208][0]ol_ath_alloc_host_mem_chunk req_id 1 idx 3 num_units 531 unit_len 744, [22:38:21:077] [ 22.762230][0]idx 4 req 6 num_units 35 num_unit_info 0 unit size 3072 actual units 35 [22:38:21:077] [ 22.762252][0]ol_ath_alloc_host_mem_chunk req_id 6 idx 4 num_units 35 unit_len 3072, [22:38:21:102] [ 22.762274][0]idx 5 req 7 num_units 1 num_unit_info 0 unit size 6144 actual units 1 [22:38:21:102] [ 22.762296][0]ol_ath_alloc_host_mem_chunk req_id 7 idx 5 num_units 1 unit_len 6144, [22:38:21:102] [ 22.762318][0]idx 6 req 5 num_units 0 num_unit_info 2 unit size 2056 actual units 531 [22:38:21:125] [ 22.762340][0]ol_ath_alloc_host_mem_chunk req_id 5 idx 6 num_units 531 unit_len 2056, [22:38:21:125] [ 22.762360][0]Support not added yet for Service 91 [22:38:21:125] [ 22.762377][0]Support not added yet for Service 92 [22:38:21:125] [ 22.762394][0]No EXT_MSG send INIT now [22:38:21:149] [ 22.762420][0]chunk 0 len 13568 requested , ptr 0x89fb8000 [22:38:21:149] [ 22.762441][0]chunk 1 len 54272 requested , ptr 0x89820000 [22:38:21:149] [ 22.762460][0]chunk 2 len 217088 requested , ptr 0x89840000 [22:38:21:149] [ 22.762480][0]chunk 3 len 395064 requested , ptr 0x89880000 [22:38:21:169] [ 22.762499][0]chunk 4 len 107520 requested , ptr 0x89900000 [22:38:21:169] [ 22.762519][0]chunk 5 len 6144 requested , ptr 0x8a5fe000 [22:38:21:169] [ 22.762538][0]chunk 6 len 1091736 requested , ptr 0x89a00000 [22:38:21:169] [ 22.762557][0]chunk 7 len 0 requested , ptr 0x8a700000 [22:38:21:185] Jan 1 01:00:22 telefon[1441]: use clock_gettime(CLOCK_MONOTONIC)! [22:38:21:185] [ 22.762762][0]smart_log_init: Smart logging Enabled buf=c96e0000 (size=65536) [22:38:21:185] [ 22.793787][0]Version = 16777216 3 status = 0 [22:38:21:202] [ 22.793850][3]ol_ath_connect_htc() WMI is ready [22:38:21:202] [ 22.793915][3]htt_h2t_frag_desc_bank_cfg_msg - HTT_H2T_MSG_TYPE_FRAG_DESC_BANK_CFG sent to FW for radio ID = 0 [22:38:21:222] [ 22.793933][0]target uses HTT version 2.2; host uses 2.2 [22:38:21:222] [ 22.798977][3]wmi_unified_register_event_handler : event handler already registered 0x900b [22:38:21:222] [ 22.799001][3]wmi_unified_register_event_handler : event handler already registered 0x9042 [22:38:21:238] [ 22.799023][3]>>>> CB Set (null) [22:38:21:238] [ 22.799350][2]avm_wlan_get_current_temp_cb: Error invalid handle: (null) != cc9cee80 [22:38:21:238] [ 22.799401][3]AVM/WLAN: __ol_ath_thermal_mitigation_attach -> temperature callback registered for wifi0 as Wifi-2.4GHz! [22:38:21:258] [ 22.799427][3]ol_ath_thermal_mitigation_attach: -- [22:38:21:258] [ 22.799529][3]ath_sysfs_diag_init: diag_fsattr [22:38:21:258] [ 22.799556][3]isCountryCodeValid: EEPROM regdomain 0x0 [22:38:21:274] [ 22.799597][3]ol_regdmn_init_channels: !avail mode 0x680c (0x2) flags 0x2150 [22:38:21:274] [ 22.799619][3]ol_regdmn_init_channels: !avail mode 0x680c (0x1) flags 0x140 [22:38:21:288] [ 22.799679][3]ol_regdmn_init_channels: !avail mode 0x680c (0x20) flags 0xd0 [22:38:21:288] [ 22.799700][3]ol_regdmn_init_channels: !avail mode 0x680c (0x40) flags 0x150 [22:38:21:307] [ 22.799759][3]ol_regdmn_init_channels: !avail mode 0x680c (0x1000) flags 0x10100 [22:38:21:307] [ 22.799781][3]ol_regdmn_init_channels: !avail mode 0x680c (0x8000) flags 0x20100 [22:38:21:307] [ 22.799802][3]ol_regdmn_init_channels: !avail mode 0x680c (0x10000) flags 0x40100 [22:38:21:326] [ 22.799823][3]ol_regdmn_init_channels: !avail mode 0x680c (0x20000) flags 0x100100 [22:38:21:326] [ 22.799844][3]ol_regdmn_init_channels: !avail mode 0x680c (0x40000) flags 0x200100 [22:38:21:341] [ 22.799865][3]ol_regdmn_init_channels: !avail mode 0x680c (0x80000) flags 0x400100 [22:38:21:341] [ 22.799886][3]ol_regdmn_init_channels: !avail mode 0x680c (0x100000) flags 0x800100 [22:38:21:355] [ 22.799908][3]ol_regdmn_init_channels: !avail mode 0x680c (0x200000) flags 0x4000100 [22:38:21:355] [ 22.799929][3]ol_regdmn_init_channels: !avail mode 0x680c (0x400000) flags 0x8000100 [22:38:21:373] [ 22.800107][3]AVM/WLAN: cc=276. Clearing potential non valid maxpwr entries [22:38:21:373] [ 22.803407][3]ol_ath_ucfg_setparam: mac req feature 1 [22:38:21:373] [ 22.803633] DCS: ol_ath_set_config_param dcs enable value 1 return value 0isCountryCodeValid: EEPROM regdomain 0x0 [22:38:21:390] [ 22.803925][3]ol_regdmn_init_channels: !avail mode 0x680c (0x2) flags 0x2150 [22:38:21:390] [ 22.803951][3]ol_regdmn_init_channels: !avail mode 0x680c (0x1) flags 0x140 [22:38:21:407] [ 22.804008][3]ol_regdmn_init_channels: !avail mode 0x680c (0x20) flags 0xd0 [22:38:21:407] [ 22.804030][3]ol_regdmn_init_channels: !avail mode 0x680c (0x40) flags 0x150 [22:38:21:421] [ 22.804088][3]ol_regdmn_init_channels: !avail mode 0x680c (0x1000) flags 0x10100 [22:38:21:421] [ 22.804110][3]ol_regdmn_init_channels: !avail mode 0x680c (0x8000) flags 0x20100 [22:38:21:438] [ 22.804131][3]ol_regdmn_init_channels: !avail mode 0x680c (0x10000) flags 0x40100 [22:38:21:438] [ 22.804152][3]ol_regdmn_init_channels: !avail mode 0x680c (0x20000) flags 0x100100 [22:38:21:457] [ 22.804173][3]ol_regdmn_init_channels: !avail mode 0x680c (0x40000) flags 0x200100 [22:38:21:457] [ 22.804194][3]ol_regdmn_init_channels: !avail mode 0x680c (0x80000) flags 0x400100 [22:38:21:457] [ 22.804215][3]ol_regdmn_init_channels: !avail mode 0x680c (0x100000) flags 0x800100 [22:38:21:475] [ 22.804236][3]ol_regdmn_init_channels: !avail mode 0x680c (0x200000) flags 0x4000100 [22:38:21:475] [ 22.804257][3]ol_regdmn_init_channels: !avail mode 0x680c (0x400000) flags 0x8000100 [22:38:21:501] [ 22.804845][3]ol_ath_ucfg_setparam: mac req feature 1 [22:38:21:501] [ 22.821042][3]Requested VPA id 0 and MAC AN:ON:YM:IZ:ED:!! [22:38:21:501] [ 22.821087][3]wlan_vap_create : enter. devhandle=0xcbb40540, opmode=IEEE80211_M_HOSTAP, flags=0x0 [22:38:21:501] [ 22.821173][3]send_vdev_create_cmd_non_tlv: ID = 0 Type = 1, Subtype = 0 VAP Addr = 7c:ff:4d:13:75:36: [22:38:21:520] [ 22.821309][3]ieee80211_mbo_vattach:MBO Initialized [22:38:21:520] [ 22.821337][3]ieee80211_oce_vattach: OCE Initialized [22:38:21:520] [ 22.821531][3]wlan_vap_create : exit. devhandle=0xcbb40540, vap=0xc9e00000, opmode=IEEE80211_M_HOSTAP, flags=0x0. [22:38:21:537] [ 22.821569][3]Enabling TX checksum bit for the vap ath0 features 4000 [22:38:21:537] [ 22.821589][3]Enabling SG bit for the vap ath0 features 4012 [22:38:21:559] [ acfg_hostapd_modify_bss 22.821609][3]Enabling TSO bit for the vap ath0 features 4013 [22:38:21:559] [ 22.821632][3]Register vap net trace device skipped (ath0). [22:38:21:559] [ 22.821694][3]avm_net_trace: New net trace device 'AP (2.4 + 5 GHz, ath0)' registered with minor 133. [22:38:21:583] [ 22.821914][3]avm_wlan_vap_wnts at 1 [22:38:21:583] [ 22.821941][3]Register vap net trace device succeeded with minor 133 (ath0). [22:38:21:583] [ 22.822220][3]ath0: registered PID at AVM_PA [22:38:21:583] [ 22.822871][3]avm_pa: try to activate hw accelaration for pid 9 (ath0) called from osif_create_vap_complete [umac] [22:38:21:601] [ 22.823826][1]ath0: registered VPID at AVM_PA [22:38:21:601] [ 22.825517][1]VAP device ath0 created osifp: (cdb56540) os_if: (c9e00000) [22:38:21:601] [ 22.825932][1]the cmd id is 23 [22:38:21:601] [ 22.825972][1][DEBUG] vap-0(ath0):set SIOC80211NWID, 17 characters [22:38:21:623] [ 22.825996][1] [22:38:21:623] [ 22.825996][1] DES SSID SET=FRITZ!Box 4040 xx [<= ANONYMIZED last 2 chars] [22:38:21:623] [ 22.826094][1]the cmd id is 53 [22:38:21:623] [ 22.826259][1]the cmd id is 53 [22:38:21:623] [ 22.826356][1]the cmd id is 59 [22:38:21:623] [ 22.826458][1]the cmd id is 25 [22:38:21:644] [ 22.826488][1]Set freq vap 0 stop send + c9e00000 [22:38:21:644] [ 22.826533][1][SM] vap-0(ath0):ieee80211_state_event: VAP state event 0, cur_state=0, vap_deleted_is_set=0 [22:38:21:644] [ 22.826756][1][SM] vap-0(ath0):ieee80211_state_event: VAP state event 11, cur_state=0, vap_deleted_is_set=0 [22:38:21:657] [ 22.826789][1][SM] vap-0(ath0):ieee80211_state_event: VAP EVENT_NODE_FREED node count 1 [22:38:21:657] [ 22.826815][1]Set freq vap 0 stop send -c9e00000 [22:38:21:670] [ 22.851482][1]Set wait done --c9e00000 [22:38:21:670] [ 22.863615][3]the cmd id is 53 [22:38:21:670] [ 22.863784][3]the cmd id is 53 [22:38:21:684] [ 22.863870][3]the cmd id is 53 [22:38:21:684] [ 22.863946][3]the cmd id is 53 [22:38:21:684] [ 22.864018][3]the cmd id is 53 [22:38:21:684] [ 22.864043][3]ieee80211_ucfg_setparam : Rate to be configured for beacon is: 6000 (Kbps) [22:38:21:704] [ 22.864123][3]the cmd id is 53 [22:38:21:704] [ 22.864150][3]su bfee 1 mu bfee 0 su bfer 1 mu bfer 1 impl bf 1 sounding dim 1 [22:38:21:704] [ 22.864245][3]the cmd id is 53 [22:38:21:704] [ 22.864324][3]the cmd id is 53 [22:38:21:727] [ 22.864398][3]the cmd id is 53 [22:38:21:727] [ 22.864469][3]the cmd id is 53 [22:38:21:727] [ 22.864540][3]the cmd id is 53 [22:38:21:727] [ 22.864634][3]the cmd id is 53 [22:38:21:727] [ 22.864722][3]the cmd id is 53 [22:38:21:727] [ 22.865028][3]the cmd id is 61 [22:38:21:727] [ 22.865118][3]the cmd id is 53 [22:38:21:727] [ 22.865227][3]the cmd id is 53 [22:38:21:758] [ 23.393061][0]random: nonblocking pool is initialized [22:38:21:758] : Hostapd delete error [22:38:21:758] Jan 1 01:00:23 telefon[1441]: No S0NT! [22:38:21:758] Jan 1 01:00:23 telefon[1441]: NoOldMSNNames [22:38:21:758] [ 23.443263][3]the cmd id is 47 [22:38:21:758] [ 23.443391][3]the cmd id is 53 [22:38:21:758] telefon: SIGCHLD PID 1470 received! [22:38:21:758] [ 23.491607][3] ieee80211_ioctl_siwmode: imr.ifm_active=131712, new mode=3, valid=1 [22:38:21:782] [ 23.511741][3] DEVICE IS DOWN ifname=ath0 [22:38:21:782] [ 23.511835][3] DEVICE IS DOWN ifname=ath0 [22:38:21:782] [ 23.670910][3][SM] vap-0(ath0):ieee80211_state_event: VAP state event 0, cur_state=0, vap_deleted_is_set=0 [22:38:21:782] [ 23.671173][3][SM] vap-0(ath0):ieee80211_state_event: VAP state event 11, cur_state=0, vap_deleted_is_set=0 [22:38:21:799] [ 23.671209][3][SM] vap-0(ath0):ieee80211_state_event: VAP EVENT_NODE_FREED node count 1 [22:38:21:799] [ 23.700120][3]device ath0 entered promiscuous mode [22:38:21:814] [ 23.700790][3]lan: port 5(ath0) entered forwarding state [22:38:21:814] [ 23.700962][3]lan: port 5(ath0) entered forwarding state [22:38:21:814] [ 23.701933][3]IPv6: ADDRCONF(NETDEV_CHANGE): lan: link becomes ready [22:38:21:830] [ 23.719354][3]Starting the target before sending the command [22:38:21:830] [ 23.719969] *********** IPQ4019 *************Wifi1 CPU frequency 250000000 [22:38:21:843] [ 23.741038] hif_target_sync_ahb: Got FW signal, retries = 1hif_ahb_enable_bus: X - hif_type = 0xd, target_type = 0xbhif_config_ce: ce_init donehif_config_ce: X, ret = 0hif_set_hia: Ehif_pci_bus_configure: hif_set_hia donehif_configure_irq: Ehif_enable: X OKhif_napi_create: NAPI structures initializedhif_napi_create: NAPI id 6 created for pipe 5qca_napi_create: napi instance 32 created on pipe 4 [22:38:21:869] [ 23.748234] hif_napi_event: received evnt: CONF cmd; v = 1 (state=0x1)hif_napi_event: setting configuration to ONError: Could not set interrupt 'wtf2': IRQ identifier name to ID conversion failed [22:38:21:882] [22:38:21:898] [ 23.754869][3]ol_target_init() BMI inited. [22:38:21:898] [ 23.755131][3]ol_target_init() BMI Get Target Info. [22:38:21:898] [ 23.755224][3]Chip id: 0xb, chip version: 0x1000000 [22:38:21:898] [ 23.755293][3] [22:38:21:917] [ 23.755293][3] CE WAR Disabled [22:38:21:917] [ 23.755322] [wifi0] FWLOG: [1595] WAL_DBGID_RST_STATS ( 0x1, 0x1, 0x96c, 0x0 ) [22:38:21:917] [ 23.755424] [wifi0] FWLOG: [1623] WAL_DBGID_TX_AC_BUFFER_SET ( 0x3, 0x1e, 0x94c, 0x94c, 0x0 ) [22:38:21:937] [ 23.755625] [wifi0] FWLOG: [1623] WAL_DBGID_TX_AC_BUFFER_SET ( 0x12, 0x1e, 0x94c, 0x94c, 0x0 ) [22:38:21:937] [ 23.755730] [wifi0] FWLOG: [1623] WAL_DBGID_TX_AC_BUFFER_SET ( 0x45, 0x1e, 0x94c, 0x94c, 0x0 ) [22:38:21:937] [ 23.755835] [wifi0] FWLOG: [1623] WAL_DBGID_TX_AC_BUFFER_SET ( 0x67, 0x1e, 0x94c, 0x94c, 0x0 ) [22:38:21:962] [ 23.755939] [wifi0] FWLOG: [1633] WAL_DBGID_PDEV_INFO_PRINT ( 0x37, 0x199, 0x130, 0x0, 0x32 ) [22:38:21:962] [ 23.756044] [wifi0] FWLOG: [1637] WAL_DBGID_PDEV_INFO_PRINT ( 0x37, 0x199, 0x130, 0x0, 0x32 ) [22:38:21:962] [ 23.756149] [wifi0] FWLOG: [2524] WAL_DBGID_SECURITY_ENCR_EN ( ) [22:38:21:982] [ 23.756183] [wifi0] FWLOG: [2524] WAL_DBGID_SECURITY_MCAST_KEY_SET ( 0x1 ) [22:38:21:982] [ 23.756644][3]NUM_DEV=1 FWMODE=0x2 FWSUBMODE=0x0 FWBR_BUF 0 [22:38:21:982] [ 23.756941][3]ol_target_init() configure Target . [22:38:21:995] [ 23.757121][3] [22:38:21:995] [ 23.757121][3] Target Version is 1000000 [22:38:21:995] [ 23.757209][3] [22:38:21:995] [ 23.757209][3] Flash Download Address c0000 [22:38:22:009] [ 23.757300][3]ol_transfer_bin_file: flash data file defined [22:38:22:009] [ 23.757504][3]ol_transfer_bin_file 3904: Download AVM WLAN EEPROM len 12064 [22:38:22:009] [ 23.757646][3]qc98xx_verify_checksum: flash checksum passed: 0xe644 [22:38:22:024] [ 23.757727][3]ol_transfer_bin_file 4007: Download Flash data len 12064 [22:38:22:024] [ 23.758256][3]Board extended Data download address: 0x0 [22:38:22:037] [ 23.778525][3] [22:38:22:037] [ 23.778525][3] Board data initialized [22:38:22:037] [ 23.778761][3]ol_ath_download_firmware: Download OTP, flash download ADDRESS 0xc0000 [22:38:22:037] [ 23.778812][3] [22:38:22:055] [ 23.778812][3] Selecting OTP binary for CHIP Version -1083735048 [22:38:22:055] [ 23.779054][3]ol_transfer_bin_file 3771: downloading file 0, Download data len 4688 [22:38:22:055] [ 23.795620][3] [22:38:22:070] [ 23.795620][3] First OTP send param 10 [22:38:22:070] [ 23.800414][3]ol_ath_download_firmware :First OTP download and Execute is good address:0x4400 return param 4660 [22:38:22:070] [ 23.800463][3]ol_ath_download_firmware:##Board Id 17 , CHIP Id 0 [22:38:22:082] [ 23.800653][3]ol_ath_download_firmware: Using 0x1234 for the remainder of init [22:38:22:082] [ 23.800710][3] [22:38:22:100] [ 23.800710][3] Selecting OTP binary for CHIP Version -1083735048 [22:38:22:100] [ 23.800992][3]ol_transfer_bin_file 3771: downloading file 0, Download data len 4688 [22:38:22:100] Error: Could not set interrupt 'wtf5': IRQ identifier name to ID conversion failed [22:38:22:123] [ 23.817662][3] [22:38:22:123] [ 23.817662][3] [Non-Flash] mode : Module param 0x0 selected [22:38:22:123] [ 23.817719][3] [22:38:22:123] [ 23.817719][3] Second otp download Param 0 [22:38:22:123] Error: Could not set interrupt 'wtf6': IRQ identifier name to ID conversion failed [22:38:22:140] [ 23.832624][3]ol_ath_download_firmware : Second OTP download and Execute is good, param=0x0 [22:38:22:140] [ 23.832674][3] [22:38:22:140] [ 23.832674][3] Mission mode: Firmware CHIP Version -1083735048 [22:38:22:154] [ 23.833242][3]ol_swap_wlan_memory_expansion: length:185431 size_left:185451 dma_size_left:262144 fw_temp:d35c7004 fw_entry_size:185455 [22:38:22:154] [ 23.833277][3]ol_swap_wlan_memory_expansion: dma_virt_addr :d2a30000 fw_temp: d35c7008 length: 185431 [22:38:22:169] [ 23.833593][3]Swap: bytes_left to copy: fw:16; dma_page:76713 [22:38:22:169] [ 23.833622][3]ol_swap_wlan_memory_expansion: length:0 size_left:12 dma_size_left:76713 fw_temp:d35f4463 fw_entry_size:185455 [22:38:22:184] [ 23.833642][3]Swap: wrong length read:0 [22:38:22:199] [ 23.833663][3]ol_swap_wlan_memory_expansion: Swap total_bytes copied: 185431 Target address 4180e0 [22:38:22:199] [ 23.833927][3]scn=cbbc0540 target_write_addr=4180e0 seg_info=cc958610 [22:38:22:199] [ 23.833969][3]ol_transfer_swap_struct:Code swap structure successfully downloaded for bin type =2 [22:38:22:218] [ 23.833992][3]bin_filename=IPQ4019/hw.1/athwlan.bin swap_filename=/lib/firmware/IPQ4019/hw.1/athwlan.codeswap.bin [22:38:22:218] [ 23.834050][3]ol_transfer_bin_file: Downloading firmware file: IPQ4019/hw.1/athwlan.bin [22:38:22:232] [ 23.834965][3]ol_transfer_bin_file 3771: downloading file 1, Download data len 380020 [22:38:22:245] Error: Could not set interrupt 'qca-init-rx0': IRQ identifier name to ID conversion failed [22:38:22:245] Error: Could not set interrupt 'qca-init-rx2': IRQ identifier name to ID conversion failed [22:38:22:259] Error: Could not set interrupt 'qca-init-rx4': IRQ identifier name to ID conversion failed [22:38:22:259] Error: Could not set interrupt 'qca-init-rx6': IRQ identifier name to ID conversion failed [22:38:22:859] Jan 1 01:00:24 telefon[1473]: NoOldMSNNames [22:38:23:150] [ 25.104891][2]ol_target_init() Download FW done. [22:38:23:153] [ 25.104941][2] [22:38:23:168] [ 25.104941][2]ol_ath_copy_tgt_iram() iram read paddr: 0x980000 host paddr 0x89c00000 vaddr 0xd2e1d000 Radio id 1 [22:38:23:315] [ 25.268726][2]ol_ath_copy_tgt_iram(): hif_diag_read_tgtiram FW IRAM content success [22:38:23:322] [ 25.268770] +htc_create .. HIF :c9e38000-htc_create: (0xca765800) [22:38:23:334] [ 25.268839][2]htc_wmi_init() HT Create . ca765800 [22:38:23:334] [ 25.268936][2]htc_wmi_init 8312 host_enable 0 nss_nwifi_offload 0 [22:38:23:347] [ 25.268963][2]ol_ath_set_default_tgt_config : AC Minfree buffer allocation through module param (umac.ko) [22:38:23:361] [ 25.268983][2] OL_ACBKMinfree : 0 [22:38:23:361] [ 25.268999][2] OL_ACBEMinfree : 0 [22:38:23:361] [ 25.269017][2] OL_ACVIMinfree : 0 [22:38:23:361] [ 25.269033][2] OL_ACVOMinfree : 0 [22:38:23:378] [ 25.269052] hif_enable_fastpath, Enabling fastpath mode [22:38:23:378] [ 25.269066][2]+HWT [22:38:23:378] [ 25.269092] hif_completion_thread_startup: pipe_num:0 pipe_info:0xc9e3ce78hif_completion_thread_startup: pipe_num:3 pipe_info:0xc9e3cf50hif_completion_thread_startup: pipe_num:4 pipe_info:0xc9e3cf98 [22:38:23:395] [ 25.278761][2]-HWT [22:38:23:395] [ 25.278815][2]wmi_unified_register_event_handler : event handler already registered 0x8002 [22:38:23:408] [ 25.278904][2]Startup Mode-0 set [22:38:23:408] [ 25.278933][2] [22:38:23:408] [ 25.278933][2]<=== cfg max peer id 1056 ====> [22:38:23:408] [ 25.279431][2]htt_peer_map_timer_init Enter pdev c9288000 hrtimer c928c930 [22:38:23:422] [ 25.279516][2] [22:38:23:422] [ 25.279516][2] htt_alloc_peer_map_mem : Alloc Success : host q vaddr c9d06000 paddr 89d06000 [22:38:23:436] [ 25.279543][2] [22:38:23:436] [ 25.279543][2] htt_alloc_peer_map_mem : Flush Interval Configured to 256 pkts [22:38:23:436] [ 25.279619][2]htt_avm_offload_init:201 registered irq 51 for wifi1-avm_offload_rx_refill on wifi1 [22:38:23:450] [ 25.279670][2]htt_avm_offload_init:224 registered irq 56 for wifi1-avm_offload_tx_free on wifi1 [22:38:23:450] [ 25.282310][2]ol_txrx_pdev_attach: 2500 tx desc's allocated ; range starts from c94e0000 [22:38:23:465] [ 25.284477][0]Firmware_Build_Number:50 [22:38:23:465] [ 25.284521][0]FW wireless modes: 0x1f9001 [22:38:23:465] [ 25.284544] num_rf_chain:0x00000002 ht_cap_info:0x0000085b vht_cap_info:0x339959b2 vht_supp_mcs:0x0000fffa [22:38:23:478] [ 25.284595][0]wmi_service_coex_gpio 0, wmi_service_4_wire_coex_support 0, coex_version 0 [22:38:23:490] [ 25.284628][0] [22:38:23:490] [ 25.284628][0]Sending Ext resource cfg: HOST PLATFORM as 0 [22:38:23:490] [ 25.284628][0]fw_feature_bitmap as 50 to TGT [22:38:23:503] [ 25.284718][0]ol_ath_service_ready_event: tt_support: 1 [22:38:23:503] [ 25.284745][0]ol_ath_service_ready_event: periodic_chan_stats: 1 [22:38:23:516] [ 25.284765][0]ol_ath_service_ready_event: sw_cal_support_check_flag: 1 [22:38:23:516] [ 25.284787][0]Peer Caching Enabled ; num_peers = 530, num_active_peers = 52 num_tids = 104, num_vdevs = 17 [22:38:23:529] [ 25.284857][0]EXT NSS Supported [22:38:23:529] [ 25.284899][0]idx 1 req 2 num_units 1 num_unit_info 12 unit size 256 actual units 53 [22:38:23:542] [ 25.284929][0]ol_ath_alloc_host_mem_chunk req_id 2 idx 0 num_units 53 unit_len 256, [22:38:23:542] [ 25.284952][0]idx 2 req 3 num_units 1 num_unit_info 12 unit size 1024 actual units 53 [22:38:23:555] [ 25.284979][0]ol_ath_alloc_host_mem_chunk req_id 3 idx 1 num_units 53 unit_len 1024, [22:38:23:568] [ 25.285001][0]idx 3 req 4 num_units 1 num_unit_info 12 unit size 4096 actual units 53 [22:38:23:568] [ 25.285023][0]ol_ath_alloc_host_mem_chunk req_id 4 idx 2 num_units 53 unit_len 4096, [22:38:23:581] [ 25.285046][0]idx 0 req 1 num_units 0 num_unit_info 2 unit size 744 actual units 531 [22:38:23:581] [ 25.285067][0]ol_ath_alloc_host_mem_chunk req_id 1 idx 3 num_units 531 unit_len 744, [22:38:23:596] [ 25.285090][0]idx 4 req 6 num_units 35 num_unit_info 0 unit size 3072 actual units 35 [22:38:23:596] [ 25.285111][0]ol_ath_alloc_host_mem_chunk req_id 6 idx 4 num_units 35 unit_len 3072, [22:38:23:611] [ 25.285133][0]idx 5 req 7 num_units 1 num_unit_info 0 unit size 6144 actual units 1 [22:38:23:611] [ 25.285155][0]ol_ath_alloc_host_mem_chunk req_id 7 idx 5 num_units 1 unit_len 6144, [22:38:23:626] [ 25.285177][0]idx 6 req 5 num_units 0 num_unit_info 2 unit size 2056 actual units 531 [22:38:23:626] [ 25.285199][0]ol_ath_alloc_host_mem_chunk req_id 5 idx 6 num_units 531 unit_len 2056, [22:38:23:639] [ 25.285218][0]Support not added yet for Service 91 [22:38:23:639] [ 25.285236][0]Support not added yet for Service 92 [22:38:23:652] [ 25.285252][0]No EXT_MSG send INIT now [22:38:23:652] [ 25.285277][0]chunk 0 len 13568 requested , ptr 0x894c8000 [22:38:23:665] [ 25.285323][0]chunk 1 len 54272 requested , ptr 0x894d0000 [22:38:23:665] [ 25.285344][0]chunk 2 len 217088 requested , ptr 0x89540000 [22:38:23:665] [ 25.285363][0]chunk 3 len 395064 requested , ptr 0x89580000 [22:38:23:682] [ 25.285383][0]chunk 4 len 107520 requested , ptr 0x89600000 [22:38:23:682] [ 25.285402][0]chunk 5 len 6144 requested , ptr 0x89bf4000 [22:38:23:682] [ 25.285421][0]chunk 6 len 1091736 requested , ptr 0x89000000 [22:38:23:697] [ 25.285441][0]chunk 7 len 0 requested , ptr 0x89c00000 [22:38:23:697] [ 25.285626][0]smart_log_init: Smart logging Enabled buf=c8570000 (size=65536) [22:38:23:712] Jan 1 01:00:25 voipd[1475]: starting ... [22:38:23:712] [ 25.316619][0]Version = 16777216 3 status = 0 [22:38:23:712] [ 25.316678][2]ol_ath_connect_htc() WMI is ready [22:38:23:712] [ 25.316738][2]htt_h2t_frag_desc_bank_cfg_msg - HTT_H2T_MSG_TYPE_FRAG_DESC_BANK_CFG sent to FW for radio ID = 1 [22:38:23:730] [ 25.316756][0]target uses HTT version 2.2; host uses 2.2 [22:38:23:730] [ 25.321802][2]wmi_unified_register_event_handler : event handler already registered 0x900b [22:38:23:745] [ 25.321828][2]wmi_unified_register_event_handler : event handler already registered 0x9042 [22:38:23:745] [ 25.321849][2]>>>> CB Set (null) [22:38:23:745] [ 25.322190][0]avm_wlan_get_current_temp_cb: Error invalid handle: (null) != c9c62e80 [22:38:23:761] [ 25.322247][2]AVM/WLAN: __ol_ath_thermal_mitigation_attach -> temperature callback registered for wifi1 as Wifi-5GHz! [22:38:23:776] [ 25.322272][2]ol_ath_thermal_mitigation_attach: -- [22:38:23:776] [ 25.322322][2]ath_sysfs_diag_init: diag_fsattr [22:38:23:776] [ 25.322347][2]isCountryCodeValid: EEPROM regdomain 0x0 [22:38:23:791] [ 25.322387][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x2) flags 0x2150 [22:38:23:791] [ 25.322441][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x4) flags 0xa0 [22:38:23:804] [ 25.322462][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x8) flags 0xc0 [22:38:23:804] [ 25.322483][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x20) flags 0xd0 [22:38:23:820] [ 25.322504][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x40) flags 0x150 [22:38:23:820] [ 25.322525][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x800) flags 0x10080 [22:38:23:837] [ 25.322546][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x2000) flags 0x20080 [22:38:23:837] [ 25.322567][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x4000) flags 0x40080 [22:38:23:859] [ 25.322674][2]Add VHT80 channel: 5210 [22:38:23:859] [ 25.322693][2]Add VHT80 channel: 5290 [22:38:23:859] [ 25.322712][2]Add VHT80 channel: 5530 [22:38:23:859] [ 25.322729][2]Add VHT80 channel: 5610 [22:38:23:859] [ 25.322766][2]Skipping VHT80 channel 5660 [22:38:23:859] [ 25.322784][2]Skipping VHT80 channel 5680 [22:38:23:885] [ 25.322802][2]Skipping VHT80 channel 5700 [22:38:23:885] [ 25.322825][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x200000) flags 0x4000100 [22:38:23:885] [ 25.322847][2]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x400000) flags 0x8000100 [22:38:23:885] [ 25.324130][2]freq=58 [22:38:23:885] [ 25.324153][2]freq=106 [22:38:23:885] [ 25.324170][2]freq=122 [22:38:23:912] [ 25.328456][1]avm_pa: priomap 0 setprio 6: setprio not found [22:38:23:912] [ 25.328697][1]avm_pa: priomap 1 setprio 6: setprio not found [22:38:23:912] [ 25.328938][1]avm_pa: priomap 0 enable 1: enable not found [22:38:23:912] [ 25.329153][1]avm_pa: priomap 1 enable 1: enable not found [22:38:23:912] [ 25.332022][0]ol_ath_ucfg_setparam: mac req feature 1 [22:38:23:937] [ 25.332310] DCS: ol_ath_set_config_param dcs enable value 1 return value 0isCountryCodeValid: EEPROM regdomain 0x0 [22:38:23:937] [ 25.332770][0]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x2) flags 0x2150 [22:38:23:937] [ 25.332832][0]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x4) flags 0xa0 [22:38:23:956] [ 25.332856][0]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x8) flags 0xc0 [22:38:23:956] [ 25.332877][0]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x20) flags 0xd0 [22:38:23:956] [ 25.332898][0]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x40) flags 0x150 [22:38:23:980] [ 25.332921][0]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x800) flags 0x10080 [22:38:23:980] [ 25.332942][0]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x2000) flags 0x20080 [22:38:23:980] [ 25.332963][0]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x4000) flags 0x40080 [22:38:24:003] [ 25.333093][0]Add VHT80 channel: 5210 [22:38:24:003] [ 25.333124][0]Add VHT80 channel: 5290 [22:38:24:003] [ 25.333143][0]Add VHT80 channel: 5530 [22:38:24:003] [ 25.333160][0]Add VHT80 channel: 5610 [22:38:24:003] [ 25.333199][0]Skipping VHT80 channel 5660 [22:38:24:003] [ 25.333218][0]Skipping VHT80 channel 5680 [22:38:24:031] [ 25.333236][0]Skipping VHT80 channel 5700 [22:38:24:031] [ 25.333270][0]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x200000) flags 0x4000100 [22:38:24:031] [ 25.333303][0]ol_regdmn_init_channels: !avail mode 0x1f9001 (0x400000) flags 0x8000100 [22:38:24:031] [ 25.334746][0]freq=58 [22:38:24:031] [ 25.334779][0]freq=106 [22:38:24:031] [ 25.334797][0]freq=122 [22:38:24:058] [ 25.334961][0]ol_ath_ucfg_setparam: mac req feature 1 [22:38:24:058] [ 25.357218][0]Requested VPA id 0 and MAC AN:ON:YM:IZ:ED:!! [22:38:24:058] [ 25.357266][0]wlan_vap_create : enter. devhandle=0xcbbc0540, opmode=IEEE80211_M_HOSTAP, flags=0x0 [22:38:24:058] [ 25.357361][0]send_vdev_create_cmd_non_tlv: ID = 0 Type = 1, Subtype = 0 VAP Addr = 7c:ff:4d:13:75:37: [22:38:24:080] [ 25.357625][0]ieee802acfg_hostapd_modify_bss11_mbo_vattach:MBO Initialized [22:38:24:080] [ 25.357658][0]ieee80211_oce_vattach: OCE Initialized [22:38:24:080] [ 25.357924][0]wlan_vap_create : exit. devhandle=0xcbbc0540, vap=0xc85c8000, opmode=IEEE80211_M_HOSTAP, flags=0x0. [22:38:24:104] [ 25.358029][0]Enabling TX checksum bit for the vap ath1 features 4000 [22:38:24:104] [ 25.358077][0]Enabling SG bit for the vap ath1 features 4012 [22:38:24:104] [ 25.358118][0]Enabling TSO bit for the vap ath1 features 4013 [22:38:24:104] [ 25.358152][0]Register vap net trace device skipped (ath1). [22:38:24:129] [ 25.358238][0]avm_net_trace: New net trace device 'AP2 (2.4 + 5 GHz, ath1)' registered with minor 139. [22:38:24:129] [ 25.358321][0]avm_wlan_vap_wnts at 7 [22:38:24:129] [ 25.358367][0]Register vap net trace device succeeded with minor 139 (ath1). [22:38:24:129] [ 25.358506][0]ath1: registered PID at AVM_PA [22:38:24:152] [ 25.359176][0]avm_pa: try to activate hw accelaration for pid 10 (ath1) called from osif_create_vap_complete [umac] [22:38:24:152] [ 25.359221][0]ath1: registered VPID at AVM_PA [22:38:24:152] [ 25.360643][0]VAP device ath1 created osifp: (cdb19d40) os_if: (c85c8000) [22:38:24:171] [ 25.361232][0]the cmd id is 23 [22:38:24:171] [ 25.361277][0][DEBUG] vap-0(ath1):set SIOC80211NWID, 17 characters [22:38:24:171] [ 25.361301][0] [22:38:24:171] [ 25.361301][0] DES SSID SET=FRITZ!Box 4040 xx [<= ANONYMIZED last 2 chars] [22:38:24:171] [ 25.361430][0]the cmd id is 53 [22:38:24:171] [ 25.361665][0]the cmd id is 53 [22:38:24:200] [ 25.361772][0]the cmd id is 59 [22:38:24:200] [ 25.361881][0]the cmd id is 25 [22:38:24:200] [ 25.361920][0]Set freq vap 0 stop send + c85c8000 [22:38:24:200] [ 25.361971][0][SM] vap-0(ath1):ieee80211_state_event: VAP state event 0, cur_state=0, vap_deleted_is_set=0 [22:38:24:200] [ 25.362435][0][SM] vap-0(ath1):ieee80211_state_event: VAP state event 11, cur_state=0, vap_deleted_is_set=0 [22:38:24:227] [ 25.362482][0][SM] vap-0(ath1):ieee80211_state_event: VAP EVENT_NODE_FREED node count 1 [22:38:24:227] [ 25.362512][0]Set freq vap 0 stop send -c85c8000 [22:38:24:227] [ 25.387490][0]Set wait done --c85c8000 [22:38:24:227] [ 25.399611][0]the cmd id is 53 [22:38:24:227] [ 25.399847][0]the cmd id is 53 [22:38:24:227] [ 25.399948][0]the cmd id is 53 [22:38:24:249] [ 25.400035][0]the cmd id is 53 [22:38:24:249] [ 25.400113][0]the cmd id is 53 [22:38:24:249] [ 25.400138][0]ieee80211_ucfg_setparam : Rate to be configured for beacon is: 6000 (Kbps) [22:38:24:249] [ 25.400298][0]the cmd id is 53 [22:38:24:249] [ 25.400329][0]su bfee 1 mu bfee 0 su bfer 1 mu bfer 1 impl bf 1 sounding dim 1 [22:38:24:274] [ 25.400477][0]the cmd id is 53 [22:38:24:274] [ 25.400566][0]the cmd id is 53 [22:38:24:274] [ 25.400647][0]the cmd id is 53 [22:38:24:274] [ 25.401357][0]the cmd id is 53 [22:38:24:274] [ 25.401625][0]the cmd id is 53 [22:38:24:274] [ 25.401725][0]the cmd id is 53 [22:38:24:274] [ 25.402092][0]the cmd id is 61 [22:38:24:294] [ 25.402190][0]the cmd id is 53 [22:38:24:294] [ 25.402378][0]the cmd id is 53 [22:38:24:294] [ 25.784172][2][module-alloc-by-name] give 0x31000 bytes at 0xc0d31000 to module 'krtp' (0x0 bytes left) [22:38:24:294] [ 25.788642][0]Switching to Tx Mode-1 Threshold 280 [22:38:24:311] : Hostapd delete error [22:38:24:311] Error: Could not set interrupt 'qca-init-rx0': IRQ identifier name to ID conversion failed [22:38:24:311] Jan 1 01:00:25 voipd[1725]: internet: dns_context: internet (ref 1) [22:38:24:333] Jan 1 01:00:25 voipd[1725]: internet: dns_context: ipv4 only, dns, 0, 2/5 300, 1 [22:38:24:333] Jan 1 01:00:25 voipd[1725]: internet: dns_context: 1 name server: [22:38:24:333] Jan 1 01:00:25 voipd[1725]: internet: dns_context: 127.0.0.1 0 [22:38:24:333] Jan 1 01:00:25 voipd[1725]: internet: dns_context: from 192.168.178.1 [22:38:24:353] Jan 1 01:00:25 voipd[1725]: internet: if dsl (16) nr 0 connection 0 [22:38:24:353] Jan 1 01:00:25 voipd[1725]: internet: dnscctx internet stay online 1 natstate no NAT [22:38:24:353] Jan 1 01:00:25 voipd[1725]: internet: ipv4 no sync [22:38:24:371] Jan 1 01:00:25 voipd[1725]: internet: stackmode_ipv4 [22:38:24:371] Jan 1 01:00:25 voipd[1725]: internet: dns_context: internet (ref 1) [22:38:24:371] Jan 1 01:00:26 voipd[1725]: internet: dns_context: ipv4 only, dns, 0, 2/5 300, 1 [22:38:24:393] Jan 1 01:00:26 voipd[1725]: internet: dns_context: 1 name server: [22:38:24:393] Jan 1 01:00:26 voipd[1725]: internet: dns_context: 127.0.0.1 0 [22:38:24:393] Jan 1 01:00:26 voipd[1725]: internet: dns_context: from 192.168.178.1 [22:38:24:393] [ 25.964420][0]the cmd id is 47 [22:38:24:413] [ 25.964623][0]the cmd id is 53 [22:38:24:413] [ 25.999545][3] ieee80211_ioctl_siwmode: imr.ifm_active=66176, new mode=3, valid=1 [22:38:24:413] [ 26.015767][3] DEVICE IS DOWN ifname=ath1 [22:38:24:413] [ 26.015840][3] DEVICE IS DOWN ifname=ath1 [22:38:24:432] [ 26.174145][3][SM] vap-0(ath1):ieee80211_state_event: VAP state event 0, cur_state=0, vap_deleted_is_set=0 [22:38:24:432] [ 26.174498][3][SM] vap-0(ath1):ieee80211_state_event: VAP state event 11, cur_state=0, vap_deleted_is_set=0 [22:38:24:452] [ 26.174551][3][SM] vap-0(ath1):ieee80211_state_event: VAP EVENT_NODE_FREED node count 1 [22:38:24:452] [ 26.202709][3]device ath1 entered promiscuous mode [22:38:24:452] [ 26.202885][3]lan: port 6(ath1) entered forwarding state [22:38:24:452] [ 26.202993][3]lan: port 6(ath1) entered forwarding state [22:38:24:470] [ 26.278157] [wifi1] FWLOG: [1604] WAL_DBGID_RST_STATS ( 0x1, 0x1, 0x1464, 0x0 ) [22:38:24:470] [ 26.278269] [wifi1] FWLOG: [1632] WAL_DBGID_TX_AC_BUFFER_SET ( 0x3, 0x1e, 0x94c, 0x94c, 0x0 ) [22:38:24:490] [ 26.278374] [wifi1] FWLOG: [1632] WAL_DBGID_TX_AC_BUFFER_SET ( 0x12, 0x1e, 0x94c, 0x94c, 0x0 ) [22:38:24:490] [ 26.278479] [wifi1] FWLOG: [1632] WAL_DBGID_TX_AC_BUFFER_SET ( 0x45, 0x1e, 0x94c, 0x94c, 0x0 ) [22:38:24:490] [ 26.278583] [wifi1] FWLOG: [1632] WAL_DBGID_TX_AC_BUFFER_SET ( 0x67, 0x1e, 0x94c, 0x94c, 0x0 ) [22:38:24:510] [ 26.278686] [wifi1] FWLOG: [1643] WAL_DBGID_PDEV_INFO_PRINT ( 0x37, 0x199, 0x130, 0x30, 0x0 ) [22:38:24:510] [ 26.278790] [wifi1] FWLOG: [1643] WAL_DBGID_PDEV_INFO_PRINT ( 0x37, 0x30, 0x0, 0x30, 0x0 ) [22:38:24:527] [ 26.278893] [wifi1] FWLOG: [1654] WAL_DBGID_PDEV_INFO_PRINT ( 0x37, 0x199, 0x130, 0x30, 0x0 ) [22:38:24:527] [ 26.278997] [wifi1] FWLOG: [1654] WAL_DBGID_PDEV_INFO_PRINT ( 0x37, 0x30, 0x0, 0x30, 0x0 ) [22:38:24:540] [ 26.279100] [wifi1] FWLOG: [2514] WAL_DBGID_SECURITY_ENCR_EN ( ) [22:38:24:540] [ 26.279135] [wifi1] FWLOG: [2514] WAL_DBGID_SECURITY_MCAST_KEY_SET ( 0x1 ) [22:38:24:553] Jan 1 01:00:26 voipd[1725]: voip: dns_context: voip (ref 1) [22:38:24:553] Error: Could not set interrupt 'qca-init-rx4': IRQ identifier name to ID conversion failed [22:38:24:566] Jan 1 01:00:26 voipd[1725]: voip: dns_context: ipv4 only, sipdns, 0, 2/5 300, 1 [22:38:24:566] Jan 1 01:00:26 voipd[1725]: voip: dns_context: 1 name server: [22:38:24:578] Jan 1 01:00:26 voipd[1725]: voip: dns_context: 127.0.0.1 0 [22:38:24:578] Jan 1 01:00:26 voipd[1725]: voip: dns_context: from 192.168.178.1 [22:38:24:591] Jan 1 01:00:26 voipd[1725]: voip: if dsl (16) nr -1 connection 0 [22:38:24:591] Jan 1 01:00:26 voipd[1725]: voip: dnscctx voip stay online 1 natstate no NAT [22:38:24:604] Jan 1 01:00:26 voipd[1725]: voip: ipv4 no sync [22:38:24:604] Jan 1 01:00:26 voipd[1725]: voip: stackmode_ipv4 [22:38:24:604] Jan 1 01:00:26 voipd[1725]: voip: dns_context: voip (ref 1) [22:38:24:619] Jan 1 01:00:26 voipd[1725]: voip: dns_context: ipv4 only, sipdns, 0, 2/5 300, 1 [22:38:24:619] Jan 1 01:00:26 voipd[1725]: voip: dns_context: 1 name server: [22:38:24:633] Jan 1 01:00:26 voipd[1725]: voip: dns_context: 127.0.0.1 0 [22:38:24:633] Jan 1 01:00:26 voipd[1725]: voip: dns_context: from 192.168.178.1 [22:38:24:647] Jan 1 01:00:26 voipd[1725]: mta: if eth_udma1.3 (0) netmark 2 [22:38:24:647] Error: Could not set interrupt 'qca-init-rx6': IRQ identifier name to ID conversion failed [22:38:24:661] Jan 1 01:00:26 voipd[1725]: mta: dnscctx mta stay online 1 natstate no NAT [22:38:24:661] Jan 1 01:00:26 voipd[1725]: mta: ipv4 no sync [22:38:24:661] Jan 1 01:00:26 voipd[1725]: mta: ipv6 no sync [22:38:24:677] Jan 1 01:00:26 voipd[1725]: mta: stackmode_dual [22:38:24:677] Jan 1 01:00:26 voipd[1725]: mta: dns_context: mta (ref 1) [22:38:24:677] Jan 1 01:00:26 voipd[1725]: mta: dns_context: ipv4 only, none, 0, 2/5 300, 1 [22:38:24:698] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: homenet (ref 1) [22:38:24:698] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: ipv4 only, none, 0, 2/5 300, 1 [22:38:24:698] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: 1 name server: [22:38:24:719] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: 127.0.0.1 0 [22:38:24:719] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: from 127.0.0.1 [22:38:24:719] Jan 1 01:00:26 voipd[1725]: homenet: if lan (11) [22:38:24:719] Jan 1 01:00:26 voipd[1725]: homenet: dnscctx homenet stay online 1 natstate no NAT [22:38:24:737] Jan 1 01:00:26 voipd[1725]: homenet: ipv4 connected [22:38:24:737] Jan 1 01:00:26 voipd[1725]: homenet: stackmode_ipv4 [22:38:24:737] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: homenet (ref 1) [22:38:24:757] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: ipv4 only, none, 0, 2/5 300, 1 [22:38:24:757] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: 1 name server: [22:38:24:757] [ 26.465729][0]the cmd id is 26 [22:38:24:757] [ 26.468808][0][SM] vap-0(ath1):ieee80211_vap_scan_event_handler: orig=c85c8000 vap=c85c8000 scan_id 40961 event 0 reason 0 [22:38:24:779] [ 26.468879][0][SM] vap-0(ath1):ieee80211_state_event: VAP state event 2, cur_state=0, vap_deleted_is_set=0 [22:38:24:779] [ 26.468937][0][SM] vap-0(ath1):ieee80211_state_event: VAP state transition INIT -> SCAN [22:38:24:794] [ 26.470823][0][SM] vap-0(ath0):ieee80211_vap_scan_event_handler: orig=c9e00000 vap=c9e00000 scan_id 40961 event 0 reason 0 [22:38:24:794] [ 26.470868][0][SM] vap-0(ath0):ieee80211_state_event: VAP state event 2, cur_state=0, vap_deleted_is_set=0 [22:38:24:812] [ 26.470897][0][SM] vap-0(ath0):ieee80211_state_event: VAP state transition INIT -> SCAN [22:38:24:812] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: 127.0.0.1 0 [22:38:24:829] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: from 127.0.0.1 [22:38:24:829] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: homenet (ref 1) [22:38:24:829] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: ipv4 only, none, 0, 2/5 300, 1 [22:38:24:845] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: 1 name server: [22:38:24:845] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: 127.0.0.1 0 [22:38:24:865] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: from 127.0.0.1 [22:38:24:865] Jan 1 01:00:26 voipd[1725]: vpn: if dsl (16) nr 0 connection 0 [22:38:24:865] Jan 1 01:00:26 voipd[1725]: vpn: dnscctx homenet stay online 1 natstate no NAT [22:38:24:865] Jan 1 01:00:26 voipd[1725]: vpn: ipv4 no sync [22:38:24:880] Jan 1 01:00:26 voipd[1725]: vpn: stackmode_ipv4 [22:38:24:880] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: homenet (ref 1) [22:38:24:894] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: ipv4 only, none, 0, 2/5 300, 1 [22:38:24:894] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: 1 name server: [22:38:24:894] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: 127.0.0.1 0 [22:38:24:915] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: from 127.0.0.1 [22:38:24:915] Jan 1 01:00:26 voipd[1725]: tunnel: dns_context: tunnel (ref 1) [22:38:24:915] Jan 1 01:00:26 voipd[1725]: tunnel: dns_context: ipv4 only, none, 0, 2/5 300, 1 [22:38:24:935] Jan 1 01:00:26 voipd[1725]: tunnel: dns_context: 1 name server: [22:38:24:935] Jan 1 01:00:26 voipd[1725]: tunnel: dns_context: 127.0.0.1 0 [22:38:24:935] Jan 1 01:00:26 voipd[1725]: tunnel: if tun0 (0) [22:38:24:935] Jan 1 01:00:26 voipd[1725]: tunnel: dnscctx tunnel stay online 1 natstate no NAT [22:38:24:954] Jan 1 01:00:26 voipd[1725]: tunnel: ipv4 no sync [22:38:24:954] Jan 1 01:00:26 voipd[1725]: tunnel: ipv6 no sync [22:38:24:954] Jan 1 01:00:26 voipd[1725]: tunnel: stackmode_dual [22:38:24:978] Jan 1 01:00:26 voipd[1725]: tunnel: dns_context: tunnel (ref 1) [22:38:24:978] Jan 1 01:00:26 voipd[1725]: tunnel: dns_context: ipv4 only, none, 0, 2/5 300, 1 [22:38:24:978] Jan 1 01:00:26 voipd[1725]: tunnel: dns_context: 1 name server: [22:38:24:978] Jan 1 01:00:26 voipd[1725]: tunnel: dns_context: 127.0.0.1 0 [22:38:25:004] Jan 1 01:00:26 voipd[1725]: internet: dns_context: internet (ref 1) [22:38:25:004] Jan 1 01:00:26 voipd[1725]: internet: dns_context: ipv4 only, dns, 0, 2/5 300, 1 [22:38:25:004] Jan 1 01:00:26 voipd[1725]: internet: dns_context: 1 name server: [22:38:25:004] Jan 1 01:00:26 voipd[1725]: internet: dns_context: 127.0.0.1 0 [22:38:25:004] Jan 1 01:00:26 voipd[1725]: internet: dns_context: from 192.168.178.1 [22:38:25:030] Jan 1 01:00:26 voipd[1725]: voip: dns_context: voip (ref 1) [22:38:25:030] Jan 1 01:00:26 voipd[1725]: voip: dns_context: ipv4 only, sipdns, 0, 2/5 300, 1 [22:38:25:030] Jan 1 01:00:26 voipd[1725]: voip: dns_context: 1 name server: [22:38:25:030] Jan 1 01:00:26 voipd[1725]: voip: dns_context: 127.0.0.1 0 [22:38:25:053] Jan 1 01:00:26 voipd[1725]: voip: dns_context: from 192.168.178.1 [22:38:25:053] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: homenet (ref 1) [22:38:25:053] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: ipv4 only, none, 0, 2/5 300, 1 [22:38:25:078] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: 1 name server: [22:38:25:078] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: 127.0.0.1 0 [22:38:25:078] Jan 1 01:00:26 voipd[1725]: homenet: dns_context: from 127.0.0.1 [22:38:25:078] 1970-01-01 01:00:26 voipd[1475]: ready (1sec) [22:38:25:078] execute /etc/init.d/E49-voipkpid [22:38:25:102] group 4 done ... [22:38:25:102] source files in group 5 ... [22:38:25:102] [ 26.754072] [wifi0] FWLOG: [5400] WAL channel change freq=2412, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:102] [ 26.754188] [wifi0] FWLOG: [5401] WAL_DBGID_RST_STATS ( 0x2, 0x80, 0x96c, 0x0 ) [22:38:25:102] [ 26.754284] [wifi0] FWLOG: [5509] WAL channel change freq=2437, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:129] [ 26.754323] [wifi0] FWLOG: [5624] WAL channel change freq=2462, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:129] execute files in group 5 ... [22:38:25:129] /etc/init.d/E50-plcd /etc/init.d/E51-mesh [22:38:25:129] execute /etc/init.d/E50-plcd [22:38:25:129] Error: Could not set interrupt 'qca-init-rx0': IRQ identifier name to ID conversion failed [22:38:25:153] Error: Could not set interrupt 'qca-init-rx2': IRQ identifier name to ID conversion failed [22:38:25:153] Error: Could not set interrupt 'qca-init-rx4': IRQ identifier name to ID conversion failed [22:38:25:153] Error: Could not set interrupt 'qca-init-rx6': IRQ identifier name to ID conversion failed [22:38:25:189] Jan 1 01:00:27 plcd[1960]: starting ... [22:38:25:189] 1970-01-01 01:00:27 plcd[1960]: ready (0sec) [22:38:25:189] execute /etc/init.d/E51-mesh [22:38:25:213] Jan 1 01:00:27 plcd[2068]: event=Service started. str1= str2= str3= [22:38:25:324] [ 27.277651] [wifi1] FWLOG: [2823] WAL channel change freq=5260, mode=0 flags=0 rx_ok=1 tx_ok=1 [22:38:25:344] [ 27.277723] [wifi1] FWLOG: [2823] WAL_DBGID_RST_STATS ( 0x2, 0x80, 0x148c, 0x0 ) [22:38:25:344] [ 27.277816] [wifi1] FWLOG: [3036] WAL channel change freq=5280, mode=0 flags=0 rx_ok=1 tx_ok=1 [22:38:25:368] [ 27.277857] [wifi1] FWLOG: [3248] WAL channel change freq=5300, mode=0 flags=0 rx_ok=1 tx_ok=1 [22:38:25:368] [ 27.277897] [wifi1] FWLOG: [3460] WAL channel change freq=5320, mode=0 flags=0 rx_ok=1 tx_ok=1 [22:38:25:385] Error: Could not set interrupt 'qca-init-rx0': IRQ identifier name to ID conversion failed [22:38:25:398] Error: Could not set interrupt 'qca-init-rx2': IRQ identifier name to ID conversion failed [22:38:25:413] Error: Could not set interrupt 'qca-init-rx4': IRQ identifier name to ID conversion failed [22:38:25:433] Error: Could not set interrupt 'qca-init-rx6': IRQ identifier name to ID conversion failed [22:38:25:799] [ 27.753556] [wifi0] FWLOG: [5738] WAL channel change freq=2442, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:819] [ 27.753633] [wifi0] FWLOG: [5852] WAL channel change freq=2472, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:819] [ 27.754738] [wifi0] FWLOG: [5966] WAL channel change freq=2417, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:838] [ 27.754868] [wifi0] FWLOG: [6081] WAL channel change freq=2422, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:838] [ 27.755991] [wifi0] FWLOG: [6196] WAL channel change freq=2427, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:859] [ 27.756036] [wifi0] FWLOG: [6309] WAL channel change freq=2432, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:859] [ 27.756084] [wifi0] FWLOG: [6424] WAL channel change freq=2447, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:859] [ 27.756123] [wifi0] FWLOG: [6539] WAL channel change freq=2452, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:876] [ 27.756162] [wifi0] FWLOG: [6654] WAL channel change freq=2457, mode=1 flags=0 rx_ok=1 tx_ok=1 [22:38:25:897] Error: Could not set interrupt 'qca-init-rx0': IRQ identifier name to ID conversion failed [22:38:25:915] Error: Could not set interrupt 'qca-init-rx2': IRQ identifier name to ID conversion failed [22:38:25:929] Error: Could not set interrupt 'qca-init-rx4': IRQ identifier name to ID conversion failed [22:38:25:945] Error: Could not set interrupt 'qca-init-rx6': IRQ identifier name to ID conversion failed [22:38:25:968] [ 27.923100][0][SM] vap-0(ath0):ieee80211_vap_scan_event_handler: orig=c9e00000 vap=c9e00000 scan_id 40961 event 1 reason 1 [22:38:25:987] [ 27.923162][0][SM] vap-0(ath0):ieee80211_state_event: VAP state event 3, cur_state=1, vap_deleted_is_set=0 [22:38:26:005] [ 27.923192][0][SM] vap-0(ath0):ieee80211_state_event: VAP state transition SCAN -> INIT [22:38:26:005] [ 27.923262][0]send_vdev_down_cmd_non_tlv for vap 0 [22:38:26:005] [ 27.923865][0][SM] vap-0(ath0):ieee80211_state_event: VAP state event 14, cur_state=0, vap_deleted_is_set=0 [22:38:26:022] [ 27.923921][0][SM] vap-0(ath0):Failed to derive Channel [22:38:26:022] [ 27.923950][0][SM] vap-0(ath0):Error osif_bringup_vap_iter_func : failed start bss with error code 22 [22:38:26:037] [ 27.924163][0][SM] vap-0(ath0):ieee80211_state_event: VAP state event 11, cur_state=0, vap_deleted_is_set=0 [22:38:26:037] [ 27.924199][0][SM] vap-0(ath0):ieee80211_state_event: VAP EVENT_NODE_FREED node count 1 [22:38:26:076] [ 27.928578][0][SM] vap-0(ath0):ieee80211_vap_scan_event_handler: orig=c9e00000 vap=c9e00000 scan_id 40962 event 0 reason 0 [22:38:26:076] [ 27.928632][0][SM] vap-0(ath0):ieee80211_state_event: VAP state event 2, cur_state=0, vap_deleted_is_set=0 [22:38:26:076] [ 27.928662][0][SM] vap-0(ath0):ieee80211_state_event: VAP state transition INIT -> SCAN [22:38:26:076] Jan 1 01:00:28 meshd[2076]: LOG: main: Hello from meshd. [22:38:26:157] Jan 1 01:00:28 meshd[2076]: starting ... [22:38:26:174] Jan 1 01:00:28 meshd[2355]: verbose: DISABLED [22:38:26:188] 1970-01-01 01:00:28 meshd[2076]: ready (0sec) [22:38:26:188] Jan 1 01:00:28 plcd[2068]: event=Client connected. str1= str2= str3= [22:38:26:188] Jan 1 01:00:28 plcd[2068]: event=Opening the HomePlug library. str1= str2= str3= [22:38:26:312] group 5 done ... [22:38:26:312] source files in group 6 ... [22:38:26:328] execute files in group 6 ... [22:38:26:328] [ 28.277197] [wifi1] FWLOG: [3671] WAL channel change freq=5180, mode=0 flags=0 rx_ok=1 tx_ok=1 [22:38:26:345] [ 28.277275] [wifi1] FWLOG: [3876] WAL channel change freq=5200, mode=0 flags=0 rx_ok=1 tx_ok=1 [22:38:26:345] [ 28.277370] [wifi1] FWLOG: [4089] WAL channel change freq=5220, mode=0 flags=0 rx_ok=1 tx_ok=1 [22:38:26:362] [ 28.278440] [wifi1] FWLOG: [4301] WAL channel change freq=5240, mode=0 flags=0 rx_ok=1 tx_ok=1 [22:38:26:362] [ 28.278490] [wifi1] FWLOG: [4513] WAL channel change freq=5500, mode=0 flags=0 rx_ok=1 tx_ok=1 [22:38:26:378] group 6 done ... [22:38:26:378] source files in group 7 ... [22:38:26:378] /etc/init.d/S73-capitcp /etc/init.d/S76-media [22:38:26:378] /etc/init.d/S75-inetd /etc/init.d/S79-boxnotify [22:38:26:378] source /etc/init.d/S73-capitcp [22:38:26:392] [ 28.311562][0]Switching to Tx Mode-1 Threshold 280 [22:38:26:513] source /etc/init.d/S75-inetd [22:38:26:524] source /etc/init.d/S76-media [22:38:26:524] source /etc/init.d/S79-boxnotify [22:38:26:568] execute files in group 7 ... [22:38:26:584] group 7 done ... [22:38:26:584] source files in group 8 ... [22:38:26:584] /etc/init.d/S85-apps [22:38:26:596] source /etc/init.d/S85-apps [22:38:26:596] execute files in group 8 ... [22:38:26:618] group 8 done ... [22:38:26:618] source files in group 9 ... [22:38:26:618] execute files in group 9 ... [22:38:26:618] Error: Could not set interrupt 'qca-init-rx0': IRQ identifier name to ID conversion failed [22:38:26:634] /etc/init.d/E99-tail [22:38:26:634] execute /etc/init.d/E99-tail [22:38:26:649] Error: Could not set interrupt 'qca-init-rx2': IRQ identifier name to ID conversion failed [22:38:26:660] Error: Could not set interrupt 'qca-init-rx4': IRQ identifier name to ID conversion failed [22:38:26:677] Error: Could not set interrupt 'qca-init-rx6': IRQ identifier name to ID conversion failed [22:38:26:706] run_clock demon started [22:38:26:706] [22:38:26:706] [ 28.654003][2]AVM_WATCHDOG: System Init Ueberwachung abgeschlossen (537580 ms noch verfuegbar) [22:38:26:725] [ 28.679892][1][avm_power]pm_ressourceinfo_scriptparse: powerdevice_usb_host: norm_power_rate=500 act_rate=0 mul=55 div=10 offset=0 NormP=2750 mW -> SumNormP=2750 mW [22:38:26:751] [ 28.680044][1][avm_power]pm_ressourceinfo_scriptparse: powerdevice_usb_host2: norm_power_rate=500 act_rate=0 mul=55 div=10 offset=0 NormP=2750 mW -> SumNormP=5500 mW [22:38:26:772] [ 28.680175][1][avm_power]pm_ressourceinfo_scriptparse: powerdevice_wlan: norm_power_rate=100 act_rate=0 mul=130 div=10 offset=100 NormP=1400 mW -> SumNormP=6900 mW [22:38:26:772] [ 28.680279][1][avm_power]pm_ressourceinfo_scriptparse: powerdevice_cpuclock: norm_power_rate=100 act_rate=100 mul=0 div=1 offset=0 NormP=0 mW -> SumNormP=6900 mW [22:38:26:784] [ 28.680375][1][avm_power]pm_ressourceinfo_scriptparse: powerdevice_loadrate: norm_power_rate=100 act_rate=25 mul=29 div=10 offset=-17 NormP=273 mW -> SumNormP=7173 mW [22:38:26:817] [ 28.680456][1][avm_power]pm_ressourceinfo_scriptparse: powerdevice_dspclock: norm_power_rate=100 act_rate=100 mul=0 div=1 offset=1500 NormP=1500 mW -> SumNormP=8673 mW [22:38:26:817] [ 28.680570][1][avm_power]pm_ressourceinfo_scriptparse: powerdevice_systemclock: norm_power_rate=100 act_rate=100 mul=0 div=1 offset=2500 NormP=2500 mW -> SumNormP=11173 mW [22:38:26:833] [ 28.680648][1][avm_power]pm_ressourceinfo_scriptparse: powerdevice_ethernet: norm_power_rate=2817 act_rate=268435456 mul=1 div=1 offset=50 NormP=2867 mW -> SumNormP=14040 mW [22:38:26:849] [ 28.681434] sysrq: SysRq : Changing Loglevel [22:38:26:849] [ 28.681495][3]sysrq: Loglevel set to 4 [22:38:26:859] [ 28.681691][3] [22:38:26:859] [ 28.681691][3][avm_debug] redirecting kernel-messages (/dev/debug) [22:38:26:859] Info: have to stop cat /dev/debug. [22:38:26:872] group 9 done ... [22:38:26:872] rc.S: System init scripts finished; detaching from console. [22:38:26:872] cat: read error: Broken pipe [22:38:27:222] Error: Could not set interrupt 'qca-init-rx0': IRQ identifier name to ID conversion failed [22:38:27:239] Error: Could not set interrupt 'qca-init-rx2': IRQ identifier name to ID conversion failed [22:38:27:258] Error: Could not set interrupt 'qca-init-rx4': IRQ identifier name to ID conversion failed [22:38:27:277] Error: Could not set interrupt 'qca-init-rx6': IRQ identifier name to ID conversion failed [22:38:27:724] Error: Could not set interrupt 'qca-init-rx0': IRQ identifier name to ID conversion failed [22:38:27:744] Error: Could not set interrupt 'qca-init-rx2': IRQ identifier name to ID conversion failed [22:38:27:767] Error: Could not set interrupt 'qca-init-rx4': IRQ identifier name to ID conversion failed [22:38:27:767] Error: Could not set interrupt 'qca-init-rx6': IRQ identifier name to ID conversion failed [22:38:27:822] [22:38:27:822] Please press Enter to activate this console.

Format: Log Type - Time(microsec) - Message - Optional Info Log Type: B - Since Boot(Power On Reset), D - Delta, S - Statistic S - QC_IMAGE_VERSION_STRING=BOOT.BF.3.1.1-00085 S - IMAGE_VARIANT_STRING=DAABANAZA S - OEM_IMAGE_VERSION_STRING=CRM S - Boot Config, 0x00000021 S - Core 0 Frequency, 0 MHz B - 261 - PBL, Start B - 1338 - bootable_media_detect_entry, Start B - 1678 - bootable_media_detect_success, Start B - 1692 - elf_loader_entry, Start B - 5068 - auth_hash_seg_entry, Start B - 7209 - auth_hash_seg_exit, Start B - 570971 - elf_segs_hash_verify_entry, Start B - 685490 - PBL, End B - 685514 - SBL1, Start B - 774001 - pm_device_init, Start D - 6 - pm_device_init, Delta B - 775445 - boot_flash_init, Start D - 54251 - boot_flash_init, Delta B - 833869 - boot_config_data_table_init, Start D - 3810 - boot_config_data_table_init, Delta - (419 Bytes) B - 841071 - clock_init, Start D - 7569 - clock_init, Delta B - 853165 - CDT version:2,Platform ID:8,Major ID:1,Minor ID:0,Subtype:0 B - 856661 - sbl1_ddr_set_params, Start B - 861655 - cpr_init, Start D - 2 - cpr_init, Delta B - 866043 - Pre_DDR_clock_init, Start D - 4 - Pre_DDR_clock_init, Delta D - 13160 - sbl1_ddr_set_params, Delta B - 879884 - pm_driver_init, Start D - 2 - pm_driver_init, Delta B - 950382 - sbl1_wait_for_ddr_training, Start D - 29 - sbl1_wait_for_ddr_training, Delta B - 966167 - Image Load, Start D - 142807 - QSEE Image Loaded, Delta - (286068 Bytes) B - 1109477 - Image Load, Start D - 1444 - SEC Image Loaded, Delta - (2048 Bytes) B - 1119738 - Image Load, Start D - 48080 - APPSBL Image Loaded, Delta - (88264 Bytes) B - 1168237 - QSEE Execution, Start D - 66 - QSEE Execution, Delta B - 1174382 - SBL1, End D - 490870 - SBL1, Delta S - Flash Throughput, 1982 KB/s (376799 Bytes, 190048 us) (AVM) EVA Revision: 1.3550 (C) Copyright 2005 AVM Date: Jul 25 2018 Time: 16:42:30 (0) 3 0x0-0x240D [FLASH:] MACRONIX Uniform-Flash 32MB 256 Bytes WriteBuffer [FLASH:](Eraseregion [0] 512 sectors a 64kB) [SYSTEM:] CortexA9 Eva_AVM >......Device Tree Blob for SubRevision 4 not found. Using Device Tree Blob of SubRevision 2 U-Boot 2012.07 [local,r7808-ef686b7292] (Jul 01 2019 - 04:53:20) smem ram ptable found: ver: 1 len: 3 DRAM: 256 MiB machid : 0x8010000 Maximum malloc length: 512 KBytes mem_malloc_start/brk/end: 0x84140000/84140000/84200000 Relocation offset: 0 NAND: SF: Detected MX25L25635E with page size 4 KiB, total 32 MiB ipq_spi: page_size: 0x100, sector_size: 0x1000, size: 0x2000000 32 MiB *** Warning - bad CRC, using default environment In: serial Out: serial Err: serial name : offset size 0:SBL1 : 00000000 00040000 0:MIBIB : 00040000 00020000 0:QSEE : 00060000 00060000 0:CDT : 000c0000 00010000 0:DDRPARAMS : 000d0000 00010000 0:APPSBLENV : 000e0000 00010000 0:APPSBL : 000f0000 00030000 machid: 8010000 Net: Configure GPIOSRead 1024 bytes from 011dc00 maca: 98:9b:cb:ec:(xx:xx) macb: 98:9b:cb:ec:(xx:xx) MAC0 addr:98:9b:cb:ec:(xx:xx) PHY ID1: 0x4d PHY ID2: 0xd0b1 ipq40xx_ess_sw_init done eth0 Hit any key to stop autoboot: 3 HHH 2 HHH 1 HHH 0 Loading from nand0, offset 0x2a0000 Fit image detected... FIT description: ARM OpenWrt FIT (Flattened Image Tree) Image 0 (kernel@1) Description: ARM OpenWrt Linux-4.14.131 Type: Kernel Image Compression: lzma compressed Data Start: 0x850000e4 Data Size: 2123226 Bytes = 2 MiB Architecture: ARM OS: Linux Load Address: 0x80208000 Entry Point: 0x80208000 Hash algo: crc32 Hash value: 39ddadb9 Hash algo: sha1 Hash value: ceb7b8423082f18fb362a1b60cdf3a10839eb720 Image 1 (fdt@1) Description: ARM OpenWrt avm_fritzbox-4040 device tree blob Type: Flat Device Tree Compression: uncompressed Data Start: 0x852067fc Data Size: 34767 Bytes = 34 KiB Architecture: ARM Hash algo: crc32 Hash value: 6b1c991d Hash algo: sha1 Hash value: 93a596a7cdf40c4865f50f1ab12e51cba4f1b39f Default Configuration: 'config@1' Configuration 0 (config@1) Description: OpenWrt Kernel: kernel@1 FDT: fdt@1 ## Booting kernel from FIT Image at 85000000 ... Using 'config@1' configuration Trying 'kernel@1' kernel subimage Description: ARM OpenWrt Linux-4.14.131 Type: Kernel Image Compression: lzma compressed Data Start: 0x850000e4 Data Size: 2123226 Bytes = 2 MiB Architecture: ARM OS: Linux Load Address: 0x80208000 Entry Point: 0x80208000 Hash algo: crc32 Hash value: 39ddadb9 Hash algo: sha1 Hash value: ceb7b8423082f18fb362a1b60cdf3a10839eb720 Verifying Hash Integrity ... crc32+ sha1+ OK ## Flattened Device Tree from FIT Image at 85000000 Using 'config@1' configuration Trying 'fdt@1' FDT blob subimage Description: ARM OpenWrt avm_fritzbox-4040 device tree blob Type: Flat Device Tree Compression: uncompressed Data Start: 0x852067fc Data Size: 34767 Bytes = 34 KiB Architecture: ARM Hash algo: crc32 Hash value: 6b1c991d Hash algo: sha1 Hash value: 93a596a7cdf40c4865f50f1ab12e51cba4f1b39f Verifying Hash Integrity ... crc32+ sha1+ OK Booting using the fdt blob at 0x852067fc Uncompressing Kernel Image ... OK Loading Device Tree to 84123000, end 8412e7ce ... OK Read 1024 bytes from 011dc00 maca: 98:9b:cb:ec:7b:ab macb: 98:9b:cb:ec:7b:ac Using machid 0x8010000 from environment Starting kernel ... [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.14.131 (buildbot@c2c312384f99) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r7808-ef686b [ 0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d [ 0.000000] CPU: div instructions available: patching division code [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] OF: fdt: Machine model: AVM FRITZ!Box 4040 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] random: get_random_bytes called from 0xc09008d0 with crng_init=0 [ 0.000000] percpu: Embedded 15 pages/cpu s29388 r8192 d23860 u61440 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 64512 [ 0.000000] Kernel command line: [ 0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes) [ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes) [ 0.000000] Memory: 249852K/260096K available (4397K kernel code, 147K rwdata, 700K rodata, 1024K init, 236K [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) [ 0.000000] vmalloc : 0xd0800000 - 0xff800000 ( 752 MB) [ 0.000000] lowmem : 0xc0000000 - 0xd0000000 ( 256 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB) [ 0.000000] .text : 0xc0208000 - 0xc074b448 (5390 kB) [ 0.000000] .init : 0xc0900000 - 0xc0a00000 (1024 kB) [ 0.000000] .data : 0xc0a00000 - 0xc0a24c80 ( 148 kB) [ 0.000000] .bss : 0xc0a26000 - 0xc0a61298 ( 237 kB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] arch_timer: cp15 timer(s) running at 48.00MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xb11fd3bfb, max_idle_ns: 44079 [ 0.000009] sched_clock: 56 bits at 48MHz, resolution 20ns, wraps every 4398046511096ns [ 0.000025] Switching to timer-based delay loop, resolution 20ns [ 0.000493] Calibrating delay loop (skipped), value calculated using timer frequency.. 96.00 BogoMIPS (lpj=48 [ 0.000516] pid_max: default: 32768 minimum: 301 [ 0.000674] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.000693] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.001377] CPU: Testing write buffer coherency: ok [ 0.002126] Setting up static identity map for 0x80300000 - 0x80300060 [ 0.002282] Hierarchical SRCU implementation. [ 0.003002] smp: Bringing up secondary CPUs ... [ 0.005936] smp: Brought up 1 node, 4 CPUs [ 0.005958] SMP: Total of 4 processors activated (384.00 BogoMIPS). [ 0.005968] CPU: All CPU(s) started in SVC mode. [ 0.015586] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 [ 0.015763] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.015792] futex hash table entries: 1024 (order: 4, 65536 bytes) [ 0.016004] pinctrl core: initialized pinctrl subsystem [ 0.016982] NET: Registered protocol family 16 [ 0.017259] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.018428] cpuidle: using governor ladder [ 0.018479] cpuidle: using governor menu [ 0.037517] msm_bus_fabric_init_driver [ 0.037798] msm_bus_device 580000.ad-hoc-bus: Util-fact is missing, default to 100 [ 0.037817] msm_bus_device 580000.ad-hoc-bus: Vrail-comp is missing, default to 100 [ 0.037838] msm_bus_device 580000.ad-hoc-bus: Failed to get bus clk for bus4096 ctx1 [ 0.037886] msm_bus_device 580000.ad-hoc-bus: Util-fact is missing, default to 100 [ 0.037901] msm_bus_device 580000.ad-hoc-bus: Vrail-comp is missing, default to 100 [ 0.037919] msm_bus_device 580000.ad-hoc-bus: Failed to get bus clk for bus1024 ctx1 [ 0.101884] usbcore: registered new interface driver usbfs [ 0.101955] usbcore: registered new interface driver hub [ 0.102065] usbcore: registered new device driver usb [ 0.102118] pps_core: LinuxPPS API ver. 1 registered [ 0.102130] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.102130] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.102159] PTP clock support registered [ 0.103232] clocksource: Switched to clocksource arch_sys_counter [ 0.104225] NET: Registered protocol family 2 [ 0.105002] TCP established hash table entries: 2048 (order: 1, 8192 bytes) [ 0.105052] TCP bind hash table entries: 2048 (order: 2, 16384 bytes) [ 0.105109] TCP: Hash tables configured (established 2048 bind 2048) [ 0.105231] UDP hash table entries: 256 (order: 1, 8192 bytes) [ 0.105271] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) [ 0.105494] NET: Registered protocol family 1 [ 0.106544] No memory allocated for crashlog [ 0.106750] workingset: timestamp_bits=30 max_order=16 bucket_order=0 [ 0.110665] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.110682] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc. [ 0.116666] io scheduler noop registered [ 0.116687] io scheduler deadline registered (default) [ 0.119024] bam-dma-engine 8e04000.dma: num-channels unspecified in dt [ 0.119047] bam-dma-engine 8e04000.dma: num-ees unspecified in dt [ 0.119850] tcsr 1949000.tcsr: setting wifi_glb_cfg = 41000000 [ 0.119954] tcsr 194b000.tcsr: setting usb hs phy mode select = e700e7 [ 0.120040] tcsr 1953000.ess_tcsr: setting ess interface select = 0 [ 0.120123] tcsr 1957000.tcsr: setting wifi_noc_memtype_m0_m2 = 2222222 [ 0.120389] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled [ 0.122426] msm_serial 78af000.serial: msm_serial: detected port #0 [ 0.122481] msm_serial 78af000.serial: uartclk = 1843200 [ 0.122539] 78af000.serial: ttyMSM0 at MMIO 0x78af000 (irq = 25, base_baud = 115200) is a MSM [ 0.122568] msm_serial: console setup on port #0 [ 0.676505] console [ttyMSM0] enabled [ 0.681426] msm_serial: driver initialized [ 0.689387] loop: module loaded [ 0.690639] spi_qup 78b5000.spi: IN:block:16, fifo:64, OUT:block:16, fifo:64 [ 0.693066] m25p80 spi0.0: mx25l25635e (32768 Kbytes) [ 0.699150] 13 fixed-partitions partitions found on MTD device spi0.0 [ 0.704005] Creating 13 MTD partitions on "spi0.0": [ 0.710428] 0x000000000000-0x000000040000 : "SBL1" [ 0.715873] 0x000000040000-0x000000060000 : "MIBIB" [ 0.720587] 0x000000060000-0x0000000c0000 : "QSEE" [ 0.725390] 0x0000000c0000-0x0000000d0000 : "CDT" [ 0.730218] 0x0000000d0000-0x0000000e0000 : "DDRPARAMS" [ 0.735039] 0x0000000e0000-0x0000000f0000 : "APPSBLENV" [ 0.740019] 0x0000000f0000-0x0000003cc000 : "urlader" [ 0.745263] 0x00000011dc00-0x000000120000 : "urlader_config" [ 0.750421] 0x000000120000-0x0000001a0000 : "tffs1" [ 0.756246] 0x0000001a0000-0x000000220000 : "tffs2" [ 0.760752] 0x000000220000-0x0000002a0000 : "uboot" [ 0.765678] 0x0000002a0000-0x000001f00000 : "firmware" [ 0.770763] 2 fit-fw partitions found on MTD device firmware [ 0.775118] 0x0000002a0000-0x0000004b0000 : "kernel" [ 0.781627] 0x0000004af4fc-0x000001f00000 : "rootfs" [ 0.786452] mtd: device 13 (rootfs) set to be root filesystem [ 0.790961] 1 squashfs-split partitions found on MTD device rootfs [ 0.796465] 0x000000760000-0x000001f00000 : "rootfs_data" [ 0.803154] 0x000001f00000-0x000002000000 : "jffs2" [ 0.809374] libphy: ipq40xx_mdio: probed [ 0.843788] ESS reset ok! [ 0.876770] ESS reset ok! [ 1.076886] PHY 2 single test PSGMII issue happen! [ 1.280382] PHY2 test see issue! [ 1.312508] ESS reset ok! [ 1.442514] PHY 1 single test PSGMII issue happen! [ 1.706842] PHY1 test see issue! [ 1.742992] ESS reset ok! [ 2.174016] GPIO line 499 (enable USB3 power) hogged as output/high [ 2.174622] libphy: Fixed MDIO Bus: probed [ 2.284830] i2c /dev entries driver [ 2.286501] cpufreq: cpufreq_online: CPU0: Running at unlisted freq: 632000 KHz [ 2.287210] cpufreq: cpufreq_online: CPU0: Unlisted initial frequency changed to: 716000 KHz [ 2.324071] NET: Registered protocol family 10 [ 2.325549] Segment Routing with IPv6 [ 2.327482] NET: Registered protocol family 17 [ 2.331185] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to [ 2.335828] 8021q: 802.1Q VLAN Support v1.8 [ 2.348681] Registering SWP/SWPB emulation handler X[ 2.364653] VFS: Mounted root (squashfs filesystem) readonly on device 31:13. [ 2.366247] Freeing unused kernel memory: 1024K [ 2.515322] random: fast init done [ 3.834756] init: Console is alive [ 3.834989] init: - watchdog - [ 5.535958] kmodloader: loading kernel modules from /etc/modules-boot.d/* [ 6.083913] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [ 6.084508] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 [ 6.090279] xhci-hcd xhci-hcd.0.auto: hcc params 0x0228f665 hci version 0x100 quirks 0x0000000002010010 [ 6.097055] xhci-hcd xhci-hcd.0.auto: irq 94, io mem 0x08a00000 [ 6.113010] hub 1-0:1.0: USB hub found [ 6.114033] hub 1-0:1.0: 1 port detected [ 6.117106] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [ 6.119860] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 [ 6.125173] xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed [ 6.132798] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 6.139864] hub 2-0:1.0: USB hub found [ 6.147673] hub 2-0:1.0: 1 port detected [ 6.263800] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [ 6.264350] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 3 [ 6.270241] xhci-hcd xhci-hcd.1.auto: hcc params 0x0220f665 hci version 0x100 quirks 0x0000000002010010 [ 6.276914] xhci-hcd xhci-hcd.1.auto: irq 95, io mem 0x06000000 [ 6.286040] hub 3-0:1.0: USB hub found [ 6.291182] hub 3-0:1.0: 1 port detected [ 6.295389] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [ 6.299047] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 4 [ 6.304376] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0 SuperSpeed [ 6.312022] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM. [ 6.319188] hub 4-0:1.0: USB hub found [ 6.326974] hub 4-0:1.0: config failed, hub doesn't have any ports! (err -19) [ 6.336403] kmodloader: done loading kernel modules from /etc/modules-boot.d/* [ 6.343472] init: - preinit - [ 7.887629] random: jshn: uninitialized urandom read (4 bytes read) [ 7.918534] random: jshn: uninitialized urandom read (4 bytes read) [ 8.031895] random: jshn: uninitialized urandom read (4 bytes read) [ 8.548435] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 8.548527] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Press the [f] key and hit [enter] to enter failsafe mode Press the [1], [2], [3] or [4] key and hit [enter] to select the debug level [ 11.981255] jffs2: notice: (175) jffs2_build_xattr_subsystem: complete building xattr subsystem, 15 of xdatum [ 11.992595] mount_root: switching to jffs2 overlay [ 12.016351] overlayfs: upper fs does not support tmpfile. [ 12.029463] urandom-seed: Seeding with /etc/urandom.seed [ 12.128281] procd: - early - [ 12.128419] procd: - watchdog - [ 12.803387] procd: - watchdog - [ 12.803762] procd: - ubus - [ 12.918548] urandom_read: 5 callbacks suppressed [ 12.918557] random: ubusd: uninitialized urandom read (4 bytes read) [ 12.978801] random: ubusd: uninitialized urandom read (4 bytes read) [ 12.980038] procd: - init - Please press Enter to activate this console. [ 13.285090] kmodloader: loading kernel modules from /etc/modules.d/* [ 13.287919] ip6_tables: (C) 2000-2006 Netfilter Core Team [ 13.295697] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180 [ 13.295811] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d [ 13.305093] ip_tables: (C) 2000-2006 Netfilter Core Team [ 13.314915] nf_conntrack version 0.5.0 (4096 buckets, 16384 max) [ 13.345076] xt_time: kernel timezone is -0000 [ 13.370400] PPP generic driver version 2.4.2 [ 13.371290] NET: Registered protocol family 24 [ 13.569992] ath10k_ahb a000000.wifi: Direct firmware load for ath10k/QCA4019/hw1.0/firmware-6.bin failed with [ 13.570044] ath10k_ahb a000000.wifi: Falling back to user helper [ 13.609759] firmware ath10k!QCA4019!hw1.0!firmware-6.bin: firmware_loading_store: map pages failed [ 14.175248] ath10k_ahb a000000.wifi: qca4019 hw1.0 target 0x01000000 chip_id 0x003b00ff sub 0000:0000 [ 14.175296] ath10k_ahb a000000.wifi: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 1 [ 14.187042] ath10k_ahb a000000.wifi: firmware ver 10.4-3.5.3-00057 api 5 features no-p2p,mfp,peer-flow-ctrl,b [ 14.254474] ath10k_ahb a000000.wifi: board_file api 2 bmi_id 0:16 crc32 83549421 [ 15.639342] ath10k_ahb a000000.wifi: htt-ver 2.2 wmi-op 6 htt-op 4 cal pre-cal-file max-sta 512 raw 0 hwcrypt [ 15.889156] ath10k_ahb a800000.wifi: Direct firmware load for ath10k/QCA4019/hw1.0/firmware-6.bin failed with [ 15.889206] ath10k_ahb a800000.wifi: Falling back to user helper [ 16.269114] firmware ath10k!QCA4019!hw1.0!firmware-6.bin: firmware_loading_store: map pages failed [ 16.269426] ath10k_ahb a800000.wifi: qca4019 hw1.0 target 0x01000000 chip_id 0x003b00ff sub 0000:0000 [ 16.277124] ath10k_ahb a800000.wifi: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 1 [ 16.290399] ath10k_ahb a800000.wifi: firmware ver 10.4-3.5.3-00057 api 5 features no-p2p,mfp,peer-flow-ctrl,b [ 16.337390] ath10k_ahb a800000.wifi: board_file api 2 bmi_id 0:17 crc32 83549421 [ 17.722867] ath10k_ahb a800000.wifi: htt-ver 2.2 wmi-op 6 htt-op 4 cal pre-cal-file max-sta 512 raw 0 hwcrypt [ 17.745421] kmodloader: done loading kernel modules from /etc/modules.d/* [ 19.435103] random: crng init done [ 21.588903] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 21.592498] br-GUEST: port 1(eth0.200) entered blocking state [ 21.593704] br-GUEST: port 1(eth0.200) entered disabled state [ 21.599867] device eth0.200 entered promiscuous mode [ 21.605331] device eth0 entered promiscuous mode [ 21.610942] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 21.621363] br-GUEST: port 1(eth0.200) entered blocking state [ 21.621423] br-GUEST: port 1(eth0.200) entered forwarding state [ 21.627121] IPv6: ADDRCONF(NETDEV_UP): br-GUEST: link is not ready [ 21.672492] br-iot: port 1(eth0.120) entered blocking state [ 21.672534] br-iot: port 1(eth0.120) entered disabled state [ 21.677465] device eth0.120 entered promiscuous mode [ 21.685564] br-iot: port 1(eth0.120) entered blocking state [ 21.687694] br-iot: port 1(eth0.120) entered forwarding state [ 21.730805] br-lan: port 1(eth0.100) entered blocking state [ 21.730856] br-lan: port 1(eth0.100) entered disabled state [ 21.736030] device eth0.100 entered promiscuous mode [ 21.744178] br-lan: port 1(eth0.100) entered blocking state [ 21.745985] br-lan: port 1(eth0.100) entered forwarding state [ 21.762633] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready [ 22.643577] IPv6: ADDRCONF(NETDEV_CHANGE): br-GUEST: link becomes ready BusyBox v1.28.4 () built-in shell (ash) _______ ________ __ | |.-----.-----.-----.| | | |.----.| |_ | - || _ | -__| || | | || _|| _| |_______|| __|_____|__|__||________||__| |____| |__| W I R E L E S S F R E E D O M ----------------------------------------------------- OpenWrt 18.06.4, r7808-ef686b7292 ----------------------------------------------------- root@fb4040:/#

ipq40xx, IPQ4018, 32Flash, 256RAM, 4Port, GigabitEthernet, 802.11ac, 802.11bgn, EVA, 4core, ARMv7, wlan, USB2.0, USB3.0

ipq40xx ipq4018 32flash 256ram 4port gigabitethernet 802.11ac 802.11bgn eva 4core armv7 wlan usb2.0 usb3.0

AVM FRITZ!Box 4040

Supported Versions

Hardware Highlights

Installation

Installation via fritzflash

Installation via EVA FTP

Flashing Stock Firmware

Flash Layout

Upgrading OpenWrt

sysupgrade

Debricking

Failsafe mode

Basic configuration

Specific Configuration

Network interfaces

VLAN Setup

Example 1: VLAN on LAN

Example 2: VLAN on WAN

Slow Performance Issue

Buttons

Hardware

Info

Photos

Opening the case

Serial

JTAG

Bootloader mods

Hardware mods

Bootlogs

OEM bootlog

OpenWrt bootlog

External Links

Tags