search

espressif / esp-hosted

Hosted Solution (Linux/MCU) with ESP32 (Wi-Fi + BT + BLE)
Other
669 stars 154 forks source link

Unable to connect to certain APs #167

Open doutordre opened 1 year ago

doutordre commented 1 year ago

Hello,

I'm having trouble connecting my RPi CM3+, hosting an ESP32 through SDIO, to any AP at my office or to mobile hotspots. I can connect to my home AP without problems.

I also had some trouble earlier with signal integrity which was fixed by following #26

To demonstrate, below are the dmesg and ESP32 outputs when I attempt to connect to my mobile phone hotspot "test".

dmesg ``` [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 5.15.61-v8+ (dom@buildbot) (aarch64-linux-gnu-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (2 [ 0.000000] random: crng init done [ 0.000000] Machine model: Raspberry Pi Compute Module 3 Plus Rev 1.0 [ 0.000000] efi: UEFI not found. [ 0.000000] Reserved memory: created CMA memory pool at 0x000000001ec00000, size 256 MiB [ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000000000-0x000000003b3fffff] [ 0.000000] DMA32 empty [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000000000-0x000000003b3fffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff] [ 0.000000] On node 0, zone DMA: 19456 pages in unavailable ranges [ 0.000000] percpu: Embedded 28 pages/cpu s77272 r8192 d29224 u114688 [ 0.000000] pcpu-alloc: s77272 r8192 d29224 u114688 alloc=28*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) [ 0.000000] CPU features: detected: ARM erratum 843419 [ 0.000000] CPU features: detected: ARM erratum 845719 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 238896 [ 0.000000] Kernel command line: coherent_pool=1M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=Compositet [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] Memory: 665492K/970752K available (11520K kernel code, 1956K rwdata, 4140K rodata, 3840K init, 974K bss, 43116K ) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] ftrace: allocating 38040 entries in 149 pages [ 0.000000] ftrace: allocated 149 pages with 4 groups [ 0.000000] trace event string verifier disabled [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. [ 0.000000] Trampoline variant of Tasks RCU enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] Root IRQ handler: bcm2836_arm_irqchip_handle_irq [ 0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns [ 0.000001] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns [ 0.000356] Console: colour dummy device 80x25 [ 0.001285] printk: console [tty1] enabled [ 0.001372] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800) [ 0.001436] pid_max: default: 32768 minimum: 301 [ 0.001644] LSM: Security Framework initializing [ 0.001929] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.001999] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.003715] cgroup: Disabling memory control group subsystem [ 0.007599] rcu: Hierarchical SRCU implementation. [ 0.009061] EFI services will not be available. [ 0.009842] smp: Bringing up secondary CPUs ... [ 0.011390] Detected VIPT I-cache on CPU1 [ 0.011486] CPU1: Booted secondary processor 0x0000000001 [0x410fd034] [ 0.013357] Detected VIPT I-cache on CPU2 [ 0.013427] CPU2: Booted secondary processor 0x0000000002 [0x410fd034] [ 0.015068] Detected VIPT I-cache on CPU3 [ 0.015126] CPU3: Booted secondary processor 0x0000000003 [0x410fd034] [ 0.015340] smp: Brought up 1 node, 4 CPUs [ 0.015494] SMP: Total of 4 processors activated. [ 0.015528] CPU features: detected: 32-bit EL0 Support [ 0.015559] CPU features: detected: 32-bit EL1 Support [ 0.015593] CPU features: detected: CRC32 instructions [ 0.056674] CPU: All CPU(s) started at EL2 [ 0.056821] alternatives: patching kernel code [ 0.058802] devtmpfs: initialized [ 0.080745] Enabled cp15_barrier support [ 0.080828] Enabled setend support [ 0.080874] KASLR enabled [ 0.081155] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.081226] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) [ 0.093089] pinctrl core: initialized pinctrl subsystem [ 0.094319] DMI not present or invalid. [ 0.095309] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.106508] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations [ 0.107040] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.108535] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.108724] audit: initializing netlink subsys (disabled) [ 0.109173] audit: type=2000 audit(0.108:1): state=initialized audit_enabled=0 res=1 [ 0.110058] thermal_sys: Registered thermal governor 'step_wise' [ 0.110389] cpuidle: using governor menu [ 0.110952] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.111264] ASID allocator initialised with 32768 entries [ 0.111535] Serial: AMBA PL011 UART driver [ 0.125472] bcm2835-mbox 3f00b880.mailbox: mailbox enabled [ 0.152749] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-08-26T14:04:36, variant start [ 0.156775] raspberrypi-firmware soc:firmware: Firmware hash is 102f1e848393c2112206fadffaaf86db04e98326 [ 0.217228] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 [ 0.222780] vgaarb: loaded [ 0.223415] SCSI subsystem initialized [ 0.223772] usbcore: registered new interface driver usbfs [ 0.223877] usbcore: registered new interface driver hub [ 0.223996] usbcore: registered new device driver usb [ 0.224562] usb_phy_generic phy: supply vcc not found, using dummy regulator [ 0.224903] usb_phy_generic phy: dummy supplies not allowed for exclusive requests [ 0.225437] pps_core: LinuxPPS API ver. 1 registered [ 0.225474] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.225538] PTP clock support registered [ 0.227906] clocksource: Switched to clocksource arch_sys_counter [ 0.364744] VFS: Disk quotas dquot_6.6.0 [ 0.364923] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.365196] FS-Cache: Loaded [ 0.365542] CacheFiles: Loaded [ 0.366887] simple-framebuffer 3eaa6000.framebuffer: framebuffer at 0x3eaa6000, 0x151800 bytes [ 0.366941] simple-framebuffer 3eaa6000.framebuffer: format=a8r8g8b8, mode=720x480x32, linelength=2880 [ 0.373383] Console: switching to colour frame buffer device 90x30 [ 0.381875] simple-framebuffer 3eaa6000.framebuffer: fb0: simplefb registered! [ 0.400397] NET: Registered PF_INET protocol family [ 0.403847] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.408669] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) [ 0.415175] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) [ 0.418729] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 0.422448] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) [ 0.426250] TCP: Hash tables configured (established 8192 bind 8192) [ 0.430151] UDP hash table entries: 512 (order: 2, 16384 bytes, linear) [ 0.433878] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) [ 0.437974] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 0.442949] RPC: Registered named UNIX socket transport module. [ 0.446638] RPC: Registered udp transport module. [ 0.450250] RPC: Registered tcp transport module. [ 0.453685] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.457134] PCI: CLS 0 bytes, default 64 [ 0.463582] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available [ 0.470972] kvm [1]: IPA Size Limit: 40 bits [ 0.476252] kvm [1]: Hyp mode initialized successfully [ 1.737270] Initialise system trusted keyrings [ 1.741201] workingset: timestamp_bits=46 max_order=18 bucket_order=0 [ 1.755349] zbud: loaded [ 1.761360] FS-Cache: Netfs 'nfs' registered for caching [ 1.765704] NFS: Registering the id_resolver key type [ 1.768997] Key type id_resolver registered [ 1.772149] Key type id_legacy registered [ 1.775306] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 1.778371] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... [ 1.783150] Key type asymmetric registered [ 1.786160] Asymmetric key parser 'x509' registered [ 1.789218] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 1.792721] io scheduler mq-deadline registered [ 1.795661] io scheduler kyber registered [ 1.816189] bcm2835-rng 3f104000.rng: hwrng registered [ 1.820045] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) [ 1.828051] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 [ 1.850463] brd: module loaded [ 1.868831] loop: module loaded [ 1.873018] Loading iSCSI transport class v2.0-870. [ 1.883052] usbcore: registered new interface driver r8152 [ 1.886259] usbcore: registered new interface driver lan78xx [ 1.889313] usbcore: registered new interface driver smsc95xx [ 1.892876] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 2.624456] Core Release: 2.80a [ 2.627311] Setting default values for core params [ 2.630200] Finished setting default values for core params [ 2.833534] Using Buffer DMA mode [ 2.836376] Periodic Transfer Interrupt Enhancement - disabled [ 2.839276] Multiprocessor Interrupt Enhancement - disabled [ 2.842247] OTG VER PARAM: 0, OTG VER FLAG: 0 [ 2.845170] Dedicated Tx FIFOs mode [ 2.852758] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = ffffffc008469000 dma = 0x00000000df000000 len=9024 [ 2.861181] FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled [ 2.874740] dwc_otg: Microframe scheduler enabled [ 2.874809] WARN::hcd_init_fiq:497: MPHI regs_base at ffffffc00806d000 [ 2.879949] dwc_otg 3f980000.usb: DWC OTG Controller [ 2.882580] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 [ 2.885317] dwc_otg 3f980000.usb: irq 74, io mem 0x00000000 [ 2.888005] Init: Port Power? op_state=1 [ 2.890663] Init: Power Port (0) [ 2.893903] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15 [ 2.899453] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.902451] usb usb1: Product: DWC OTG Controller [ 2.905385] usb usb1: Manufacturer: Linux 5.15.61-v8+ dwc_otg_hcd [ 2.908343] usb usb1: SerialNumber: 3f980000.usb [ 2.912308] hub 1-0:1.0: USB hub found [ 2.915222] hub 1-0:1.0: 1 port detected [ 2.919222] dwc_otg: FIQ enabled [ 2.919240] dwc_otg: NAK holdoff enabled [ 2.919254] dwc_otg: FIQ split-transaction FSM enabled [ 2.919277] Module dwc_common_port init [ 2.920155] usbcore: registered new interface driver uas [ 2.923114] usbcore: registered new interface driver usb-storage [ 2.926510] mousedev: PS/2 mouse device common for all mice [ 2.935060] sdhci: Secure Digital Host Controller Interface driver [ 2.937847] sdhci: Copyright(c) Pierre Ossman [ 2.941310] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.946331] ledtrig-cpu: registered to indicate activity on CPUs [ 2.949839] hid: raw HID events driver (C) Jiri Kosina [ 2.952912] usbcore: registered new interface driver usbhid [ 2.955759] usbhid: USB HID core driver [ 2.959034] ashmem: initialized [ 2.970115] NET: Registered PF_PACKET protocol family [ 2.973191] Key type dns_resolver registered [ 2.977744] registered taskstats version 1 [ 2.980505] Loading compiled-in X.509 certificates [ 2.984222] Key type ._fscrypt registered [ 2.986921] Key type .fscrypt registered [ 2.989514] Key type fscrypt-provisioning registered [ 3.011561] uart-pl011 3f201000.serial: cts_event_workaround enabled [ 3.014381] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 99, base_baud = 0) is a PL011 rev2 [ 4.185240] printk: console [ttyAMA0] enabled [ 4.196379] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [ 4.206112] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [ 4.218873] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0 [ 4.227528] mmc-bcm2835 3f300000.mmc: DMA channel allocated [ 4.262968] sdhost: log_buf @ 000000001f7f324a (c27db000) [ 4.292868] mmc1: queuing unknown CIS tuple 0x01 [d9 01 ff] (3 bytes) [ 4.310939] mmc1: queuing unknown CIS tuple 0x1a [01 01 00 02 07] (5 bytes) [ 4.324520] mmc1: queuing unknown CIS tuple 0x1b [c1 41 30 30 ff ff ff ff] (8 bytes) [ 4.335126] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) [ 4.348686] of_cfs_init [ 4.354128] of_cfs_init: OK [ 4.365303] Waiting for root device PARTUUID=c6c08292-02... [ 4.382268] mmc1: new SDIO card at address 0001 [ 4.455318] mmc0: new high speed MMC card at address 0001 [ 4.465043] mmcblk0: mmc0:0001 8GTF4R 7.28 GiB [ 4.476284] mmcblk0: p1 p2 [ 4.482601] mmcblk0: mmc0:0001 8GTF4R 7.28 GiB [ 4.490477] mmcblk0boot0: mmc0:0001 8GTF4R 4.00 MiB [ 4.501746] mmcblk0boot1: mmc0:0001 8GTF4R 4.00 MiB [ 4.512488] mmcblk0rpmb: mmc0:0001 8GTF4R 512 KiB, chardev (242:0) [ 4.542170] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem [ 4.552408] EXT4-fs (mmcblk0p2): write access will be enabled during recovery [ 4.792516] EXT4-fs (mmcblk0p2): orphan cleanup on readonly fs [ 4.802347] EXT4-fs (mmcblk0p2): 1 orphan inode deleted [ 4.810228] EXT4-fs (mmcblk0p2): recovery complete [ 4.827096] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. [ 4.842088] VFS: Mounted root (ext4 filesystem) readonly on device 179:2. [ 4.859966] devtmpfs: mounted [ 4.876282] Freeing unused kernel memory: 3840K [ 4.883623] Run /sbin/init as init process [ 4.890330] with arguments: [ 4.890345] /sbin/init [ 4.890360] with environment: [ 4.890375] HOME=/ [ 4.890388] TERM=linux [ 5.436731] systemd[1]: System time before build time, advancing clock. [ 5.703448] NET: Registered PF_INET6 protocol family [ 5.713632] Segment Routing with IPv6 [ 5.720040] In-situ OAM (IOAM) with IPv6 [ 5.817794] systemd[1]: systemd 247.3-7+deb11u1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINI) [ 5.851075] systemd[1]: Detected architecture arm64. [ 5.892857] systemd[1]: Set hostname to . [ 6.095822] uart-pl011 3f201000.serial: no DMA platform data [ 6.960607] systemd[1]: Queued start job for default target Multi-User System. [ 6.977427] systemd[1]: Created slice system-getty.slice. [ 6.992121] systemd[1]: Created slice system-modprobe.slice. [ 7.006710] systemd[1]: Created slice system-serial\x2dgetty.slice. [ 7.021968] systemd[1]: Created slice system-systemd\x2dfsck.slice. [ 7.036982] systemd[1]: Created slice User and Session Slice. [ 7.050828] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 7.067046] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 7.084171] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ 7.105698] systemd[1]: Reached target Local Encrypted Volumes. [ 7.119842] systemd[1]: Reached target Paths. [ 7.132251] systemd[1]: Reached target Slices. [ 7.145125] systemd[1]: Reached target Swap. [ 7.159062] systemd[1]: Listening on Syslog Socket. [ 7.173069] systemd[1]: Listening on fsck to fsckd communication Socket. [ 7.188323] systemd[1]: Listening on initctl Compatibility Named Pipe. [ 7.204639] systemd[1]: Listening on Journal Audit Socket. [ 7.219122] systemd[1]: Listening on Journal Socket (/dev/log). [ 7.234318] systemd[1]: Listening on Journal Socket. [ 7.252193] systemd[1]: Listening on udev Control Socket. [ 7.266378] systemd[1]: Listening on udev Kernel Socket. [ 7.280299] systemd[1]: Condition check resulted in Huge Pages File System being skipped. [ 7.300878] systemd[1]: Mounting POSIX Message Queue File System... [ 7.321457] systemd[1]: Mounting RPC Pipe File System... [ 7.341387] systemd[1]: Mounting Kernel Debug File System... [ 7.361981] systemd[1]: Mounting Kernel Trace File System... [ 7.376552] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. [ 7.400678] systemd[1]: Starting Restore / save the current clock... [ 7.421756] systemd[1]: Starting Set the console keyboard layout... [ 7.444242] systemd[1]: Starting Create list of static device nodes for the current kernel... [ 7.472870] systemd[1]: Starting Load Kernel Module configfs... [ 7.494760] systemd[1]: Starting Load Kernel Module drm... [ 7.517946] systemd[1]: Starting Load Kernel Module fuse... [ 7.537683] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. [ 7.561823] systemd[1]: Starting File System Check on Root Device... [ 7.592463] systemd[1]: Starting Journal Service... [ 7.618323] systemd[1]: Starting Load Kernel Modules... [ 7.639047] fuse: init (API version 7.34) [ 7.669779] systemd[1]: Starting Coldplug All udev Devices... [ 7.727535] systemd[1]: Mounted POSIX Message Queue File System. [ 7.758204] systemd[1]: Mounted RPC Pipe File System. [ 7.786218] systemd[1]: Mounted Kernel Debug File System. [ 7.800625] systemd[1]: Mounted Kernel Trace File System. [ 7.816914] systemd[1]: Finished Restore / save the current clock. [ 7.834725] systemd[1]: Finished Create list of static device nodes for the current kernel. [ 7.857667] systemd[1]: modprobe@configfs.service: Succeeded. [ 7.869895] systemd[1]: Finished Load Kernel Module configfs. [ 7.886936] systemd[1]: modprobe@drm.service: Succeeded. [ 7.898415] systemd[1]: Finished Load Kernel Module drm. [ 7.915358] systemd[1]: modprobe@fuse.service: Succeeded. [ 7.926971] systemd[1]: Finished Load Kernel Module fuse. [ 7.943351] systemd[1]: Finished Load Kernel Modules. [ 7.965479] systemd[1]: Mounting FUSE Control File System... [ 7.986808] systemd[1]: Mounting Kernel Configuration File System... [ 8.007460] systemd[1]: Started File System Check Daemon to report status. [ 8.029664] systemd[1]: Starting Apply Kernel Variables... [ 8.057894] systemd[1]: Finished File System Check on Root Device. [ 8.073651] systemd[1]: Started Journal Service. [ 8.314926] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null). Quota mode: none. [ 8.394486] systemd-journald[142]: Received client request to flush runtime journal. [ 8.431183] systemd-journald[142]: File /var/log/journal/62d8705de3884a46ae34dbe9cd5a5d1a/system.journal corrupted or unclea. [ 9.845523] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [ 9.859253] mc: Linux media interface: v0.10 [ 9.861392] bcm2835_vc_sm_cma_probe: Videocore shared memory driver [ 9.861454] [vc_sm_connected_init]: start [ 9.877138] [vc_sm_connected_init]: installed successfully [ 9.970114] videodev: Linux video capture interface: v2.00 [ 10.095107] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 10.113721] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. [ 10.153302] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. [ 10.153909] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. [ 10.162104] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 [ 10.162797] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 [ 10.163403] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 [ 10.164042] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 [ 10.164103] bcm2835-isp bcm2835-isp: Register output node 0 with media controller [ 10.164139] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller [ 10.164167] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller [ 10.164195] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller [ 10.178949] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 [ 10.193015] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 [ 10.193727] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 [ 10.194207] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 [ 10.194255] bcm2835-isp bcm2835-isp: Register output node 0 with media controller [ 10.194289] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller [ 10.194319] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller [ 10.194364] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller [ 10.194697] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp [ 10.207367] bcm2835-codec bcm2835-codec: Device registered as /dev/video10 [ 10.207469] bcm2835-codec bcm2835-codec: Loaded V4L2 decode [ 10.213879] bcm2835-codec bcm2835-codec: Device registered as /dev/video11 [ 10.213999] bcm2835-codec bcm2835-codec: Loaded V4L2 encode [ 10.220222] bcm2835-codec bcm2835-codec: Device registered as /dev/video12 [ 10.220307] bcm2835-codec bcm2835-codec: Loaded V4L2 isp [ 10.226727] bcm2835-codec bcm2835-codec: Device registered as /dev/video18 [ 10.226806] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx [ 10.233934] bcm2835-codec bcm2835-codec: Device registered as /dev/video31 [ 10.234018] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image [ 10.345454] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. [ 10.348152] bcm2835_audio bcm2835_audio: there is not valid maps for state default [ 10.380735] bcm2835_audio bcm2835_audio: card created with 8 channels [ 11.267642] checking generic (3eaa6000 151800) vs hw (0 ffffffffffffffff) [ 11.267696] fb0: switching to vc4 from simple [ 11.325746] Console: switching to colour dummy device 80x25 [ 11.333221] vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4]) [ 11.342890] Registered IR keymap rc-cec [ 11.350794] rc rc0: vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0 [ 11.351189] input: vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0/input0 [ 11.706248] vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4]) [ 11.712268] Registered IR keymap rc-cec [ 11.712586] rc rc0: vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0 [ 11.712933] input: vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0/input1 [ 11.723619] vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4]) [ 11.727333] vc4-drm soc:gpu: bound 3f004000.txp (ops vc4_txp_ops [vc4]) [ 11.727804] vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4]) [ 11.733469] vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4]) [ 11.733967] vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4]) [ 11.734316] vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4]) [ 11.748854] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0 [ 11.749805] vc4-drm soc:gpu: [drm] Cannot find any crtc or sizes [ 12.500405] 8021q: 802.1Q VLAN Support v1.8 [ 12.730263] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS [ 12.898266] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 12.962438] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 12.984059] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid [ 21.985071] vc4-drm soc:gpu: [drm] Cannot find any crtc or sizes [ 35.808028] cam-dummy-reg: disabling [ 90.133315] systemd-journald[142]: File /var/log/journal/62d8705de3884a46ae34dbe9cd5a5d1a/user-1000.journal corrupted or unc. [ 91.687088] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 91.687202] MAI: soc_pcm_open() failed (-19) [ 91.689240] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 91.689377] MAI: soc_pcm_open() failed (-19) [ 91.690858] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 91.691029] MAI: soc_pcm_open() failed (-19) [ 91.705707] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 91.705847] MAI: soc_pcm_open() failed (-19) [ 91.706419] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 91.706514] MAI: soc_pcm_open() failed (-19) [ 91.707076] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 91.707171] MAI: soc_pcm_open() failed (-19) [ 116.110237] Bluetooth: Core ver 2.22 [ 116.110393] NET: Registered PF_BLUETOOTH protocol family [ 116.110403] Bluetooth: HCI device and connection manager initialized [ 116.110432] Bluetooth: HCI socket layer initialized [ 116.110447] Bluetooth: L2CAP socket layer initialized [ 116.110475] Bluetooth: SCO socket layer initialized [ 116.219957] esp32_sdio: loading out-of-tree module taints kernel. [ 116.222078] esp_reset, ESP32: Triggering ESP reset. [ 116.428121] esp_sdio: probe of mmc1:0001:1 failed with error -110 [ 116.428247] esp_sdio: probe of mmc1:0001:2 failed with error -110 [ 117.024453] mmc1: card 0001 removed [ 118.356061] mmc1: queuing unknown CIS tuple 0x01 [d9 01 ff] (3 bytes) [ 118.363683] mmc1: queuing unknown CIS tuple 0x1a [01 01 00 02 07] (5 bytes) [ 118.366995] mmc1: queuing unknown CIS tuple 0x1b [c1 41 30 30 ff ff ff ff] (8 bytes) [ 118.372766] mmc1: new SDIO card at address 0001 [ 118.373239] esp_probe: ESP network device detected [ 118.374164] esp_sdio: probe of mmc1:0001:2 failed with error -22 [ 118.537426] Received ESP bootup event [ 118.537448] EVENT: 3 [ 118.537459] ESP chipset detected [esp32] [ 118.537468] EVENT: 0 [ 118.537475] ESP peripheral capabilities: 0x1d [ 118.743914] ESP Bluetooth init [ 118.744287] Capabilities: 0x1d. Features supported are: [ 118.744300] * WLAN on SDIO [ 118.744306] * BT/BLE [ 118.744313] - HCI over SDIO [ 118.744320] - BT/BLE dual mode [ 118.744327] EVENT: 1 [ 118.744334] esp32: process_fw_data ESP chipset's last reset cause: [ 118.744343] POWERON_RESET [ 118.744350] esp32: ESP Firmware version: 1.0.1 [ 119.681845] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 119.681874] Bluetooth: BNEP filters: protocol multicast [ 119.681899] Bluetooth: BNEP socket layer initialized [ 119.704665] NET: Registered PF_ALG protocol family [ 119.849351] Bluetooth: RFCOMM TTY layer initialized [ 119.849406] Bluetooth: RFCOMM socket layer initialized [ 119.849442] Bluetooth: RFCOMM ver 1.11 [ 157.020294] Connection request: test\x1f fa:aa:52:98:97:30 6 [ 158.627748] Disconnect event for ssid test\x1f [reason:201] [ 160.333319] Connection request: test\x1f fa:aa:52:98:97:30 6 [ 161.940345] Disconnect event for ssid test\x1f [reason:201] [ 163.646073] Connection request: test\x1f fa:aa:52:98:97:30 6 [ 165.252874] Disconnect event for ssid test\x1f [reason:201] [ 166.981159] Connection request: test\x1f fa:aa:52:98:97:30 6 [ 168.592892] Disconnect event for ssid test\x1f [reason:201] ```
ESP32 output ``` ets Jul 29 2019 12:21:46 rst:0x1 (POWERON_RESET),boot:0x3f (SPI_FAST_FLASH_BOOT) configsip: 271414342, SPIWP:0xee clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00 mode:DIO, clock div:2 load:0x3fff0030,len:6680 load:0x40078000,len:14848 load:0x40080400,len:3792 entry 0x40080694 I (28) boot: ESP-IDF v4.4.1-472-gc9140caf8c-dirty 2nd stage bootloader I (28) boot: compile time 21:33:21 I (29) boot: chip revision: 3 I (33) boot_comm: chip revision: 3, min. bootloader chip revision: 0 I (40) boot.esp32: SPI Speed : 40MHz I (44) boot.esp32: SPI Mode : DIO I (49) boot.esp32: SPI Flash Size : 4MB I (54) boot: Enabling RNG early entropy source... I (59) boot: Partition Table: I (62) boot: ## Label Usage Type ST Offset Length I (70) boot: 0 nvs WiFi data 01 02 00009000 00004000 I (77) boot: 1 otadata OTA data 01 00 0000d000 00002000 I (85) boot: 2 phy_init RF data 01 01 0000f000 00001000 I (92) boot: 3 factory factory app 00 00 00010000 00100000 I (100) boot: 4 ota_0 OTA app 00 10 00110000 00100000 I (107) boot: 5 ota_1 OTA app 00 11 00210000 00100000 I (115) boot: End of partition table I (119) boot: Defaulting to factory image I (124) boot_comm: chip revision: 3, min. application chip revision: 0 I (131) esp_image: segment 0: paddr=00010020 vaddr=3f400020 size=15e00h ( 89600) map I (172) esp_image: segment 1: paddr=00025e28 vaddr=3ffbdb60 size=0553ch ( 21820) load I (181) esp_image: segment 2: paddr=0002b36c vaddr=40080000 size=04cach ( 19628) load I (189) esp_image: segment 3: paddr=00030020 vaddr=400d0020 size=769dch (485852) map I (365) esp_image: segment 4: paddr=000a6a04 vaddr=40084cac size=181d4h ( 98772) load I (406) esp_image: segment 5: paddr=000bebe0 vaddr=50000000 size=00010h ( 16) load I (420) boot: Loaded app from partition at offset 0x10000 I (421) boot: Disabling RNG early entropy source... I (432) cpu_start: Pro cpu up. I (433) cpu_start: Starting app cpu, entry point is 0x4008121c I (0) cpu_start: App cpu up. I (449) cpu_start: Pro cpu start user code I (449) cpu_start: cpu freq: 240000000 I (449) cpu_start: Application information: I (453) cpu_start: Project name: network_adapter I (459) cpu_start: App version: 293978e I (464) cpu_start: Compile time: Jul 20 2022 21:33:02 I (470) cpu_start: ELF file SHA256: 573ea08fdc881889... I (476) cpu_start: ESP-IDF: v4.4.1-472-gc9140caf8c-dirty I (483) heap_init: Initializing. RAM available for dynamic allocation: I (490) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (496) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (502) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (508) heap_init: At 3FFD1498 len 0000EB68 (58 KiB): DRAM I (514) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (521) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (527) heap_init: At 4009CE80 len 00003180 (12 KiB): IRAM I (534) spi_flash: detected chip: generic I (538) spi_flash: flash io: dio W (542) spi_flash: Detected size(8192k) larger than the size in the binary image header(4096k). Using the size in the binary image header. I (556) cpu_start: Starting scheduler on PRO CPU. I (0) cpu_start: Starting scheduler on APP CPU. I (570) FW_MAIN: ********************************************************************* I (576) FW_MAIN: ESP-Hosted Firmware version :: 1.0.1 I (584) FW_MAIN: Transport used :: SDIO I (590) FW_MAIN: ********************************************************************* I (596) FW_MAIN: Supported features are: I (600) FW_MAIN: - WLAN over SDIO I (604) FW_BT: - BT/BLE I (606) FW_BT: - HCI Over SDIO I (610) FW_BT: - BT/BLE dual mode I (612) FW_MAIN: Capabilities: 0x1d I (624) system_api: Base MAC address is not set I (624) system_api: read default base MAC address from EFUSE I (628) BTDM_INIT: BT controller compile version [5688ed5] I (632) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07 I (966) FW_MAIN: ESP Bluetooth MAC addr: 30-83-98-d6-62-2e I (966) FW_SDIO_SLAVE: Using SDIO interface I (1836) FW_MAIN: Start Data Path I (1968) FW_SDIO_SLAVE: last reset cause: 1x I (1968) FW_MAIN: Initial set up done I (2158) FW_MAIN: INIT Interface command I (2162) wifi:mode : sta (30:83:98:d6:62:2c) I (2164) wifi:enable tsf I (2164) FW_CMD: Wifi Sta mode set I (2188) FW_MAIN: Get MAC command I (2190) FW_CMD: 0x3ffe3a18 30 83 98 d6 62 2c |0...b,| I (31490) FW_MAIN: Scan request I (32776) FW_MAIN: STA connect request I (32776) FW_CMD: Attempting secured connection I (32776) FW_CMD: test, channel: 6 I (34038) FW_CMD: STA Disconnect event: 201 I (34038) FW_CMD: cleanup_ap_bssid I (34052) FW_MAIN: Delete key request I (34072) FW_MAIN: Delete key request I (34082) FW_MAIN: Delete key request I (34100) FW_MAIN: Delete key request I (34110) FW_MAIN: Delete key request I (34120) FW_MAIN: Delete key request I (34140) FW_MAIN: Scan request I (35426) FW_MAIN: STA connect request I (35426) FW_CMD: Attempting secured connection I (35426) FW_CMD: test, channel: 6 I (36688) FW_CMD: STA Disconnect event: 201 I (36688) FW_CMD: cleanup_ap_bssid I (36712) FW_MAIN: Delete key request I (36732) FW_MAIN: Delete key request I (36740) FW_MAIN: Delete key request I (36750) FW_MAIN: Delete key request I (36770) FW_MAIN: Delete key request I (36780) FW_MAIN: Delete key request I (36788) FW_MAIN: Scan request I (38076) FW_MAIN: STA connect request I (38076) FW_CMD: Attempting secured connection I (38076) FW_CMD: test, channel: 6 I (39338) FW_CMD: STA Disconnect event: 201 I (39338) FW_CMD: cleanup_ap_bssid I (39362) FW_MAIN: Delete key request I (39380) FW_MAIN: Delete key request I (39390) FW_MAIN: Delete key request I (39410) FW_MAIN: Delete key request I (39418) FW_MAIN: Delete key request I (39438) FW_MAIN: Delete key request I (39458) FW_MAIN: Scan request I (40748) FW_MAIN: STA connect request I (40748) FW_CMD: Attempting secured connection I (40748) FW_CMD: test, channel: 6 I (42010) FW_CMD: STA Disconnect event: 201 I (42010) FW_CMD: cleanup_ap_bssid I (42024) FW_MAIN: Delete key request I (42042) FW_MAIN: Delete key request I (42052) FW_MAIN: Delete key request I (42072) FW_MAIN: Delete key request I (42082) FW_MAIN: Delete key request I (42100) FW_MAIN: Delete key request I (42110) FW_MAIN: Scan request ```

Note that at my office there are many hidden APs when I scan with iwlist. When I'm back I'll post the logs here.

mantriyogesh commented 1 year ago

Hello @doutordre

Thanks a lot, for checking the detailed documentation and the issues before raising the issue. Much appreciated. I understand your issue, that some APs you are not able to connect and some you are not able to connect. The question pops to me, are you able to connect the same AP in these two environments?

doutordre commented 1 year ago

Hi @mantriyogesh, Thank you for your prompt reply.

I looked further at this and found out why I couldn't connect to my office (corporate) network: they use EAP as security so obviously the wpa.conf file had to reflect this. I'm not very experienced in Linux or networks, so I wasn't aware of this fact. I'll have to work this out with our IT department. I found this out because I was able to connect to my office's Guest network!

In any case, I haven't been able to connect to mobile hotspots. It doesn't work either in my office or my home. As I was suspicious my mobile phone (Xiaomi A2 lite) was at fault I generated a mobile hotspot on my laptop called "pc_test". The problem persists, but with a different error code. The logs are as follows

dmesg ``` [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 5.15.61-v8+ (dom@buildbot) (aarch64-linux-gnu-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU 2 [ 0.000000] random: crng init done [ 0.000000] Machine model: Raspberry Pi Compute Module 3 Plus Rev 1.0 [ 0.000000] efi: UEFI not found. [ 0.000000] Reserved memory: created CMA memory pool at 0x000000001ec00000, size 256 MiB [ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000000000-0x000000003b3fffff] [ 0.000000] DMA32 empty [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000000000-0x000000003b3fffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff] [ 0.000000] On node 0, zone DMA: 19456 pages in unavailable ranges [ 0.000000] percpu: Embedded 28 pages/cpu s77272 r8192 d29224 u114688 [ 0.000000] pcpu-alloc: s77272 r8192 d29224 u114688 alloc=28*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) [ 0.000000] CPU features: detected: ARM erratum 843419 [ 0.000000] CPU features: detected: ARM erratum 845719 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 238896 [ 0.000000] Kernel command line: coherent_pool=1M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=Compot [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] Memory: 665492K/970752K available (11520K kernel code, 1956K rwdata, 4140K rodata, 3840K init, 974K bss, 431) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] ftrace: allocating 38040 entries in 149 pages [ 0.000000] ftrace: allocated 149 pages with 4 groups [ 0.000000] trace event string verifier disabled [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. [ 0.000000] Trampoline variant of Tasks RCU enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] Root IRQ handler: bcm2836_arm_irqchip_handle_irq [ 0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns [ 0.000001] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns [ 0.000353] Console: colour dummy device 80x25 [ 0.001282] printk: console [tty1] enabled [ 0.001368] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800) [ 0.001432] pid_max: default: 32768 minimum: 301 [ 0.001637] LSM: Security Framework initializing [ 0.001920] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.001988] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.003712] cgroup: Disabling memory control group subsystem [ 0.007611] rcu: Hierarchical SRCU implementation. [ 0.009052] EFI services will not be available. [ 0.009841] smp: Bringing up secondary CPUs ... [ 0.011375] Detected VIPT I-cache on CPU1 [ 0.011472] CPU1: Booted secondary processor 0x0000000001 [0x410fd034] [ 0.013339] Detected VIPT I-cache on CPU2 [ 0.013405] CPU2: Booted secondary processor 0x0000000002 [0x410fd034] [ 0.015044] Detected VIPT I-cache on CPU3 [ 0.015101] CPU3: Booted secondary processor 0x0000000003 [0x410fd034] [ 0.015314] smp: Brought up 1 node, 4 CPUs [ 0.015468] SMP: Total of 4 processors activated. [ 0.015503] CPU features: detected: 32-bit EL0 Support [ 0.015534] CPU features: detected: 32-bit EL1 Support [ 0.015568] CPU features: detected: CRC32 instructions [ 0.056585] CPU: All CPU(s) started at EL2 [ 0.056736] alternatives: patching kernel code [ 0.058716] devtmpfs: initialized [ 0.080634] Enabled cp15_barrier support [ 0.080717] Enabled setend support [ 0.080763] KASLR enabled [ 0.081042] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.081113] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) [ 0.093729] pinctrl core: initialized pinctrl subsystem [ 0.094942] DMI not present or invalid. [ 0.095931] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.107253] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations [ 0.107789] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.109282] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.109492] audit: initializing netlink subsys (disabled) [ 0.109944] audit: type=2000 audit(0.108:1): state=initialized audit_enabled=0 res=1 [ 0.110818] thermal_sys: Registered thermal governor 'step_wise' [ 0.111150] cpuidle: using governor menu [ 0.111710] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.112024] ASID allocator initialised with 32768 entries [ 0.112291] Serial: AMBA PL011 UART driver [ 0.126231] bcm2835-mbox 3f00b880.mailbox: mailbox enabled [ 0.153077] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-08-26T14:04:36, variant start [ 0.157104] raspberrypi-firmware soc:firmware: Firmware hash is 102f1e848393c2112206fadffaaf86db04e98326 [ 0.217483] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 [ 0.222985] vgaarb: loaded [ 0.223673] SCSI subsystem initialized [ 0.224031] usbcore: registered new interface driver usbfs [ 0.224135] usbcore: registered new interface driver hub [ 0.224254] usbcore: registered new device driver usb [ 0.224818] usb_phy_generic phy: supply vcc not found, using dummy regulator [ 0.225163] usb_phy_generic phy: dummy supplies not allowed for exclusive requests [ 0.225710] pps_core: LinuxPPS API ver. 1 registered [ 0.225748] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.225812] PTP clock support registered [ 0.228140] clocksource: Switched to clocksource arch_sys_counter [ 0.352900] VFS: Disk quotas dquot_6.6.0 [ 0.353081] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.353359] FS-Cache: Loaded [ 0.353699] CacheFiles: Loaded [ 0.355056] simple-framebuffer 3eaa6000.framebuffer: framebuffer at 0x3eaa6000, 0x151800 bytes [ 0.355111] simple-framebuffer 3eaa6000.framebuffer: format=a8r8g8b8, mode=720x480x32, linelength=2880 [ 0.361543] Console: switching to colour frame buffer device 90x30 [ 0.370030] simple-framebuffer 3eaa6000.framebuffer: fb0: simplefb registered! [ 0.388779] NET: Registered PF_INET protocol family [ 0.392250] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.397088] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) [ 0.403584] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) [ 0.407134] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 0.410849] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) [ 0.414679] TCP: Hash tables configured (established 8192 bind 8192) [ 0.418577] UDP hash table entries: 512 (order: 2, 16384 bytes, linear) [ 0.422295] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) [ 0.426391] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 0.431362] RPC: Registered named UNIX socket transport module. [ 0.435049] RPC: Registered udp transport module. [ 0.438652] RPC: Registered tcp transport module. [ 0.442077] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.445520] PCI: CLS 0 bytes, default 64 [ 0.451810] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available [ 0.459180] kvm [1]: IPA Size Limit: 40 bits [ 0.464427] kvm [1]: Hyp mode initialized successfully [ 1.737730] Initialise system trusted keyrings [ 1.741730] workingset: timestamp_bits=46 max_order=18 bucket_order=0 [ 1.755897] zbud: loaded [ 1.762061] FS-Cache: Netfs 'nfs' registered for caching [ 1.766307] NFS: Registering the id_resolver key type [ 1.769593] Key type id_resolver registered [ 1.772738] Key type id_legacy registered [ 1.775895] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 1.778955] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... [ 1.783837] Key type asymmetric registered [ 1.786848] Asymmetric key parser 'x509' registered [ 1.789889] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 1.793300] io scheduler mq-deadline registered [ 1.796343] io scheduler kyber registered [ 1.816842] bcm2835-rng 3f104000.rng: hwrng registered [ 1.820690] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) [ 1.828607] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 [ 1.851032] brd: module loaded [ 1.869389] loop: module loaded [ 1.873585] Loading iSCSI transport class v2.0-870. [ 1.883686] usbcore: registered new interface driver r8152 [ 1.886963] usbcore: registered new interface driver lan78xx [ 1.890017] usbcore: registered new interface driver smsc95xx [ 1.893606] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 2.625162] Core Release: 2.80a [ 2.628035] Setting default values for core params [ 2.630931] Finished setting default values for core params [ 2.834279] Using Buffer DMA mode [ 2.837122] Periodic Transfer Interrupt Enhancement - disabled [ 2.840023] Multiprocessor Interrupt Enhancement - disabled [ 2.843004] OTG VER PARAM: 0, OTG VER FLAG: 0 [ 2.845930] Dedicated Tx FIFOs mode [ 2.853460] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = ffffffc008469000 dma = 0x00000000df000000 len=904 [ 2.861891] FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled [ 2.875473] dwc_otg: Microframe scheduler enabled [ 2.875557] WARN::hcd_init_fiq:497: MPHI regs_base at ffffffc00806d000 [ 2.880701] dwc_otg 3f980000.usb: DWC OTG Controller [ 2.883338] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 [ 2.886083] dwc_otg 3f980000.usb: irq 74, io mem 0x00000000 [ 2.888795] Init: Port Power? op_state=1 [ 2.891460] Init: Power Port (0) [ 2.894688] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15 [ 2.900249] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.903214] usb usb1: Product: DWC OTG Controller [ 2.906145] usb usb1: Manufacturer: Linux 5.15.61-v8+ dwc_otg_hcd [ 2.909106] usb usb1: SerialNumber: 3f980000.usb [ 2.913076] hub 1-0:1.0: USB hub found [ 2.915987] hub 1-0:1.0: 1 port detected [ 2.920029] dwc_otg: FIQ enabled [ 2.920047] dwc_otg: NAK holdoff enabled [ 2.920062] dwc_otg: FIQ split-transaction FSM enabled [ 2.920085] Module dwc_common_port init [ 2.920904] usbcore: registered new interface driver uas [ 2.923859] usbcore: registered new interface driver usb-storage [ 2.927250] mousedev: PS/2 mouse device common for all mice [ 2.935762] sdhci: Secure Digital Host Controller Interface driver [ 2.938553] sdhci: Copyright(c) Pierre Ossman [ 2.942029] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.947458] ledtrig-cpu: registered to indicate activity on CPUs [ 2.951030] hid: raw HID events driver (C) Jiri Kosina [ 2.954113] usbcore: registered new interface driver usbhid [ 2.956988] usbhid: USB HID core driver [ 2.960252] ashmem: initialized [ 2.971272] NET: Registered PF_PACKET protocol family [ 2.974279] Key type dns_resolver registered [ 2.978820] registered taskstats version 1 [ 2.981593] Loading compiled-in X.509 certificates [ 2.985374] Key type ._fscrypt registered [ 2.988071] Key type .fscrypt registered [ 2.990685] Key type fscrypt-provisioning registered [ 3.012838] uart-pl011 3f201000.serial: cts_event_workaround enabled [ 3.015589] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 99, base_baud = 0) is a PL011 rev2 [ 4.186499] printk: console [ttyAMA0] enabled [ 4.197573] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [ 4.207332] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [ 4.220140] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0 [ 4.228792] mmc-bcm2835 3f300000.mmc: DMA channel allocated [ 4.264415] sdhost: log_buf @ 00000000d9144abb (c2708000) [ 4.293064] mmc1: queuing unknown CIS tuple 0x01 [d9 01 ff] (3 bytes) [ 4.312201] mmc1: queuing unknown CIS tuple 0x1a [01 01 00 02 07] (5 bytes) [ 4.325763] mmc1: queuing unknown CIS tuple 0x1b [c1 41 30 30 ff ff ff ff] (8 bytes) [ 4.336384] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) [ 4.349460] of_cfs_init [ 4.354784] of_cfs_init: OK [ 4.366202] Waiting for root device PARTUUID=c6c08292-02... [ 4.383161] mmc1: new SDIO card at address 0001 [ 4.456081] mmc0: new high speed MMC card at address 0001 [ 4.465738] mmcblk0: mmc0:0001 8GTF4R 7.28 GiB [ 4.476994] mmcblk0: p1 p2 [ 4.483269] mmcblk0: mmc0:0001 8GTF4R 7.28 GiB [ 4.491201] mmcblk0boot0: mmc0:0001 8GTF4R 4.00 MiB [ 4.502413] mmcblk0boot1: mmc0:0001 8GTF4R 4.00 MiB [ 4.513181] mmcblk0rpmb: mmc0:0001 8GTF4R 512 KiB, chardev (242:0) [ 4.545015] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. [ 4.560466] VFS: Mounted root (ext4 filesystem) readonly on device 179:2. [ 4.578686] devtmpfs: mounted [ 4.595121] Freeing unused kernel memory: 3840K [ 4.602615] Run /sbin/init as init process [ 4.609400] with arguments: [ 4.609414] /sbin/init [ 4.609429] with environment: [ 4.609442] HOME=/ [ 4.609456] TERM=linux [ 5.163478] systemd[1]: System time before build time, advancing clock. [ 5.430797] NET: Registered PF_INET6 protocol family [ 5.441059] Segment Routing with IPv6 [ 5.447548] In-situ OAM (IOAM) with IPv6 [ 5.546091] systemd[1]: systemd 247.3-7+deb11u1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYS) [ 5.579421] systemd[1]: Detected architecture arm64. [ 5.614801] systemd[1]: Set hostname to . [ 5.785735] uart-pl011 3f201000.serial: no DMA platform data [ 6.703181] systemd[1]: Queued start job for default target Multi-User System. [ 6.720358] systemd[1]: Created slice system-getty.slice. [ 6.734981] systemd[1]: Created slice system-modprobe.slice. [ 6.749768] systemd[1]: Created slice system-serial\x2dgetty.slice. [ 6.765023] systemd[1]: Created slice system-systemd\x2dfsck.slice. [ 6.779870] systemd[1]: Created slice User and Session Slice. [ 6.793873] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 6.810054] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 6.827085] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ 6.848654] systemd[1]: Reached target Local Encrypted Volumes. [ 6.862813] systemd[1]: Reached target Paths. [ 6.875234] systemd[1]: Reached target Slices. [ 6.887731] systemd[1]: Reached target Swap. [ 6.901189] systemd[1]: Listening on Syslog Socket. [ 6.914977] systemd[1]: Listening on fsck to fsckd communication Socket. [ 6.930167] systemd[1]: Listening on initctl Compatibility Named Pipe. [ 6.946442] systemd[1]: Listening on Journal Audit Socket. [ 6.961038] systemd[1]: Listening on Journal Socket (/dev/log). [ 6.976398] systemd[1]: Listening on Journal Socket. [ 6.994770] systemd[1]: Listening on udev Control Socket. [ 7.009243] systemd[1]: Listening on udev Kernel Socket. [ 7.023091] systemd[1]: Condition check resulted in Huge Pages File System being skipped. [ 7.043603] systemd[1]: Mounting POSIX Message Queue File System... [ 7.063765] systemd[1]: Mounting RPC Pipe File System... [ 7.083747] systemd[1]: Mounting Kernel Debug File System... [ 7.104603] systemd[1]: Mounting Kernel Trace File System... [ 7.118707] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. [ 7.142829] systemd[1]: Starting Restore / save the current clock... [ 7.164417] systemd[1]: Starting Set the console keyboard layout... [ 7.187161] systemd[1]: Starting Create list of static device nodes for the current kernel... [ 7.215360] systemd[1]: Starting Load Kernel Module configfs... [ 7.237051] systemd[1]: Starting Load Kernel Module drm... [ 7.260365] systemd[1]: Starting Load Kernel Module fuse... [ 7.279957] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. [ 7.303783] systemd[1]: Starting File System Check on Root Device... [ 7.349905] systemd[1]: Starting Journal Service... [ 7.362425] fuse: init (API version 7.34) [ 7.406543] systemd[1]: Starting Load Kernel Modules... [ 7.445419] systemd[1]: Starting Coldplug All udev Devices... [ 7.478639] systemd[1]: Mounted POSIX Message Queue File System. [ 7.494350] systemd[1]: Mounted RPC Pipe File System. [ 7.509081] systemd[1]: Mounted Kernel Debug File System. [ 7.523648] systemd[1]: Mounted Kernel Trace File System. [ 7.541083] systemd[1]: Finished Restore / save the current clock. [ 7.559422] systemd[1]: Finished Create list of static device nodes for the current kernel. [ 7.582969] systemd[1]: modprobe@configfs.service: Succeeded. [ 7.595687] systemd[1]: Finished Load Kernel Module configfs. [ 7.612834] systemd[1]: modprobe@drm.service: Succeeded. [ 7.625255] systemd[1]: Finished Load Kernel Module drm. [ 7.642043] systemd[1]: modprobe@fuse.service: Succeeded. [ 7.653365] systemd[1]: Finished Load Kernel Module fuse. [ 7.670239] systemd[1]: Finished Load Kernel Modules. [ 7.693077] systemd[1]: Mounting FUSE Control File System... [ 7.713737] systemd[1]: Mounting Kernel Configuration File System... [ 7.751136] systemd[1]: Started File System Check Daemon to report status. [ 7.775884] systemd[1]: Starting Apply Kernel Variables... [ 7.798361] systemd[1]: Started Journal Service. [ 8.042542] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null). Quota mode: none. [ 8.130965] systemd-journald[141]: Received client request to flush runtime journal. [ 8.147650] systemd-journald[141]: File /var/log/journal/62d8705de3884a46ae34dbe9cd5a5d1a/system.journal corrupted or un. [ 9.551048] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [ 9.565606] bcm2835_vc_sm_cma_probe: Videocore shared memory driver [ 9.565657] [vc_sm_connected_init]: start [ 9.566472] mc: Linux media interface: v0.10 [ 9.581057] [vc_sm_connected_init]: installed successfully [ 9.672438] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. [ 9.674893] bcm2835_audio bcm2835_audio: there is not valid maps for state default [ 9.699550] bcm2835_audio bcm2835_audio: card created with 8 channels [ 9.757904] videodev: Linux video capture interface: v2.00 [ 9.861609] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 9.879786] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 9.886011] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. [ 9.899871] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 [ 9.901404] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 [ 9.902014] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 [ 9.902478] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 [ 9.902533] bcm2835-isp bcm2835-isp: Register output node 0 with media controller [ 9.902581] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller [ 9.902609] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller [ 9.902637] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller [ 9.911886] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. [ 9.921953] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. [ 9.936457] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 [ 9.937060] bcm2835-codec bcm2835-codec: Device registered as /dev/video10 [ 9.937130] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 [ 9.937195] bcm2835-codec bcm2835-codec: Loaded V4L2 decode [ 9.938074] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 [ 9.938815] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 [ 9.938863] bcm2835-isp bcm2835-isp: Register output node 0 with media controller [ 9.938895] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller [ 9.938923] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller [ 9.938951] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller [ 9.939351] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp [ 9.954807] bcm2835-codec bcm2835-codec: Device registered as /dev/video11 [ 9.955375] bcm2835-codec bcm2835-codec: Loaded V4L2 encode [ 9.969021] bcm2835-codec bcm2835-codec: Device registered as /dev/video12 [ 9.969100] bcm2835-codec bcm2835-codec: Loaded V4L2 isp [ 9.985276] bcm2835-codec bcm2835-codec: Device registered as /dev/video18 [ 9.985368] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx [ 9.992366] bcm2835-codec bcm2835-codec: Device registered as /dev/video31 [ 9.992447] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image [ 10.823124] checking generic (3eaa6000 151800) vs hw (0 ffffffffffffffff) [ 10.823176] fb0: switching to vc4 from simple [ 10.889831] Console: switching to colour dummy device 80x25 [ 10.909262] vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4]) [ 10.919195] Registered IR keymap rc-cec [ 10.919521] rc rc0: vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0 [ 10.919877] input: vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0/input0 [ 11.338266] vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4]) [ 11.345813] Registered IR keymap rc-cec [ 11.346138] rc rc0: vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0 [ 11.346468] input: vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0/input1 [ 11.357517] vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4]) [ 11.358110] vc4-drm soc:gpu: bound 3f004000.txp (ops vc4_txp_ops [vc4]) [ 11.358588] vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4]) [ 11.359075] vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4]) [ 11.359540] vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4]) [ 11.359876] vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4]) [ 11.401210] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0 [ 11.402166] vc4-drm soc:gpu: [drm] Cannot find any crtc or sizes [ 11.984021] 8021q: 802.1Q VLAN Support v1.8 [ 12.194585] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS [ 12.449966] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 12.499316] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 12.507284] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid [ 21.477390] vc4-drm soc:gpu: [drm] Cannot find any crtc or sizes [ 35.812234] cam-dummy-reg: disabling [ 53.940080] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 53.940240] MAI: soc_pcm_open() failed (-19) [ 53.941587] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 53.941711] MAI: soc_pcm_open() failed (-19) [ 53.942909] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 53.943014] MAI: soc_pcm_open() failed (-19) [ 53.958486] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 53.958615] MAI: soc_pcm_open() failed (-19) [ 53.959205] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 53.959304] MAI: soc_pcm_open() failed (-19) [ 53.959877] hdmi-audio-codec hdmi-audio-codec.1.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19 [ 53.959990] MAI: soc_pcm_open() failed (-19) [ 63.740869] Bluetooth: Core ver 2.22 [ 63.741015] NET: Registered PF_BLUETOOTH protocol family [ 63.741025] Bluetooth: HCI device and connection manager initialized [ 63.741057] Bluetooth: HCI socket layer initialized [ 63.741073] Bluetooth: L2CAP socket layer initialized [ 63.741104] Bluetooth: SCO socket layer initialized [ 63.851204] esp32_sdio: loading out-of-tree module taints kernel. [ 63.853777] esp_reset, ESP32: Triggering ESP reset. [ 64.060345] esp_sdio: probe of mmc1:0001:1 failed with error -110 [ 64.060475] esp_sdio: probe of mmc1:0001:2 failed with error -110 [ 64.804635] mmc1: card 0001 removed [ 64.984841] mmc1: queuing unknown CIS tuple 0x01 [d9 01 ff] (3 bytes) [ 64.999044] mmc1: queuing unknown CIS tuple 0x1a [01 01 00 02 07] (5 bytes) [ 65.005259] mmc1: queuing unknown CIS tuple 0x1b [c1 41 30 30 ff ff ff ff] (8 bytes) [ 65.010980] mmc1: new SDIO card at address 0001 [ 65.011468] esp_probe: ESP network device detected [ 65.012954] esp_sdio: probe of mmc1:0001:2 failed with error -22 [ 66.164122] Received ESP bootup event [ 66.164145] EVENT: 3 [ 66.164157] ESP chipset detected [esp32] [ 66.164166] EVENT: 0 [ 66.164173] ESP peripheral capabilities: 0x1d [ 66.372145] ESP Bluetooth init [ 66.372508] Capabilities: 0x1d. Features supported are: [ 66.372519] * WLAN on SDIO [ 66.372526] * BT/BLE [ 66.372533] - HCI over SDIO [ 66.372539] - BT/BLE dual mode [ 66.372555] EVENT: 1 [ 66.372563] esp32: process_fw_data ESP chipset's last reset cause: [ 66.372572] POWERON_RESET [ 66.372579] esp32: ESP Firmware version: 1.0.1 [ 67.318177] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 67.318206] Bluetooth: BNEP filters: protocol multicast [ 67.318231] Bluetooth: BNEP socket layer initialized [ 67.341522] NET: Registered PF_ALG protocol family [ 67.490164] Bluetooth: RFCOMM TTY layer initialized [ 67.490211] Bluetooth: RFCOMM socket layer initialized [ 67.490248] Bluetooth: RFCOMM ver 1.11 [ 78.292006] Connection request: pc_test 02:42:38:0c:af:c1 1 [ 78.436490] Connection status: 0 [ 78.441531] NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! [ 78.499036] NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! [ 78.501304] mac: 000000001113e3dc: 02 42 38 0c af c1 .B8... [ 78.571527] esp32: [0x4] Command failed [ 78.571585] esp32: wait_and_decode_cmd_resp(priv, cmd_node) failure, ret: -1 [ 78.571683] mac: 00000000efb3114b: ff ff ff ff ff ff ...... [ 79.409037] Disconnect event for ssid pc_test [reason:2] [ 81.142384] Connection request: pc_test 02:42:38:0c:af:c1 1 [ 81.211543] Disconnect event for ssid pc_test [reason:2] [ 82.929350] Connection request: pc_test 02:42:38:0c:af:c1 1 [ 83.064018] Connection status: 0 [ 83.069159] NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! [ 83.096615] NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! [ 83.100898] mac: 00000000a4de9f0f: 02 42 38 0c af c1 .B8... [ 83.154449] mac: 00000000efb3114b: ff ff ff ff ff ff ...... [ 83.246575] Disconnect event for ssid pc_test [reason:2] [ 84.954449] Connection request: pc_test 02:42:38:0c:af:c1 1 [ 85.069043] Connection status: 0 [ 85.076687] NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! [ 85.106632] NOHZ tick-stop error: Non-RCU local softirq work is pending, handler #08!!! [ 85.108926] mac: 00000000fb9c77ac: 02 42 38 0c af c1 .B8... [ 85.159426] mac: 00000000efb3114b: ff ff ff ff ff ff ...... [ 85.251607] Disconnect event for ssid pc_test [reason:2] [ 86.974416] Connection request: pc_test 02:42:38:0c:af:c1 1 [ 87.049149] Disconnect event for ssid pc_test [reason:2] [ 88.797082] Connection request: pc_test 02:42:38:0c:af:c1 1 [ 88.891629] Disconnect event for ssid pc_test [reason:2] [ 90.634555] Connection request: pc_test 02:42:38:0c:af:c1 1 [ 90.734169] Disconnect event for ssid pc_test [reason:2] ```
ESP32 ``` ets Jul 29 2019 12:21:46 rst:0x1 (POWERON_RESET),boot:0x3f (SPI_FAST_FLASH_BOOT) configsip: 271414342, SPIWP:0xee clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00 mode:DIO, clock div:2 load:0x3fff0030,len:6680 load:0x40078000,len:14848 load:0x40080400,len:3792 entry 0x40080694 I (28) boot: ESP-IDF v4.4.1-472-gc9140caf8c-dirty 2nd stage bootloader I (28) boot: compile time 21:33:21 I (29) boot: chip revision: 3 I (33) boot_comm: chip revision: 3, min. bootloader chip revision: 0 I (40) boot.esp32: SPI Speed : 40MHz I (44) boot.esp32: SPI Mode : DIO I (49) boot.esp32: SPI Flash Size : 4MB I (54) boot: Enabling RNG early entropy source... I (59) boot: Partition Table: I (62) boot: ## Label Usage Type ST Offset Length I (70) boot: 0 nvs WiFi data 01 02 00009000 00004000 I (77) boot: 1 otadata OTA data 01 00 0000d000 00002000 I (85) boot: 2 phy_init RF data 01 01 0000f000 00001000 I (92) boot: 3 factory factory app 00 00 00010000 00100000 I (100) boot: 4 ota_0 OTA app 00 10 00110000 00100000 I (107) boot: 5 ota_1 OTA app 00 11 00210000 00100000 I (115) boot: End of partition table I (119) boot: Defaulting to factory image I (124) boot_comm: chip revision: 3, min. application chip revision: 0 I (131) esp_image: segment 0: paddr=00010020 vaddr=3f400020 size=15e00h ( 89600) map I (172) esp_image: segment 1: paddr=00025e28 vaddr=3ffbdb60 size=0553ch ( 21820) load I (181) esp_image: segment 2: paddr=0002b36c vaddr=40080000 size=04cach ( 19628) load I (189) esp_image: segment 3: paddr=00030020 vaddr=400d0020 size=769dch (485852) map I (365) esp_image: segment 4: paddr=000a6a04 vaddr=40084cac size=181d4h ( 98772) load I (406) esp_image: segment 5: paddr=000bebe0 vaddr=50000000 size=00010h ( 16) load I (420) boot: Loaded app from partition at offset 0x10000 I (421) boot: Disabling RNG early entropy source... I (432) cpu_start: Pro cpu up. I (433) cpu_start: Starting app cpu, entry point is 0x4008121c I (0) cpu_start: App cpu up. I (449) cpu_start: Pro cpu start user code I (449) cpu_start: cpu freq: 240000000 I (449) cpu_start: Application information: I (453) cpu_start: Project name: network_adapter I (459) cpu_start: App version: 293978e I (464) cpu_start: Compile time: Jul 20 2022 21:33:02 I (470) cpu_start: ELF file SHA256: 573ea08fdc881889... I (476) cpu_start: ESP-IDF: v4.4.1-472-gc9140caf8c-dirty I (483) heap_init: Initializing. RAM available for dynamic allocation: I (490) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (496) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (502) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (508) heap_init: At 3FFD1498 len 0000EB68 (58 KiB): DRAM I (514) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (521) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (527) heap_init: At 4009CE80 len 00003180 (12 KiB): IRAM I (534) spi_flash: detected chip: generic I (538) spi_flash: flash io: dio W (542) spi_flash: Detected size(8192k) larger than the size in the binary image header(4096k). Using the size in the binary image header. I (556) cpu_start: Starting scheduler on PRO CPU. I (0) cpu_start: Starting scheduler on APP CPU. I (570) FW_MAIN: ********************************************************************* I (576) FW_MAIN: ESP-Hosted Firmware version :: 1.0.1 I (584) FW_MAIN: Transport used :: SDIO I (590) FW_MAIN: ********************************************************************* I (596) FW_MAIN: Supported features are: I (600) FW_MAIN: - WLAN over SDIO I (604) FW_BT: - BT/BLE I (606) FW_BT: - HCI Over SDIO I (610) FW_BT: - BT/BLE dual mode I (612) FW_MAIN: Capabilities: 0x1d I (624) system_api: Base MAC address is not set I (624) system_api: read default base MAC address from EFUSE I (628) BTDM_INIT: BT controller compile version [5688ed5] I (632) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07 I (962) FW_MAIN: ESP Bluetooth MAC addr: 30-83-98-d6-62-2e I (962) FW_SDIO_SLAVE: Using SDIO interface I (1042) FW_MAIN: Start Data Path I (1964) FW_SDIO_SLAVE: last reset cause: 1x I (1964) FW_MAIN: Initial set up done I (2144) FW_MAIN: INIT Interface command I (2148) wifi:mode : sta (30:83:98:d6:62:2c) I (2150) wifi:enable tsf I (2150) FW_CMD: Wifi Sta mode set I (2174) FW_MAIN: Get MAC command I (2174) FW_CMD: 0x3ffe3a18 30 83 98 d6 62 2c |0...b,| I (10400) FW_MAIN: Scan request I (11698) FW_MAIN: STA connect request I (11698) FW_CMD: Attempting secured connection I (11698) FW_CMD: pc_test, channel: 1 I (11710) STA Connection: 0x3ffd032c 02 42 38 0c af c1 |.B8...| I (11710) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (11716) wifi:state: init -> auth (b0) I (11752) wifi:state: auth -> assoc (0) I (11770) FW_CMD: handle_wpa_sta_rx_mgmt:508 ASSOC Response[1] recvd I (11772) FW_CMD: STA connect event [channel 0] I (11772) BSSID: 0x3ffd3a81 02 42 38 0c af c1 |.B8...| I (11780) wifi:state: assoc -> run (10) I (11852) FW_MAIN: Add key request W (11852) wifi:ipencil I (11872) FW_MAIN: Set default key request I (11884) FW_MAIN: Scan request W (11884) wifi:sta_scan: STA is connecting, scan are not allowed! I (11884) FW_CMD: Scan failed ret=[0xffffffff] I (11904) FW_MAIN: Add key request W (11904) wifi:ipencil I (11904) FW_CMD: process_add_key:1175 set auth to done I (11904) wifi:connected with pc_test, aid = 7, channel 1, BW20, bssid = 02:42:38:0c:af:c1 I (11910) wifi:security: WPA2-PSK, phy: bgn, rssi: -29 I (11914) wifi:pm start, type: 1 I (11926) wifi:AP's beacon interval = 102400 us, DTIM period = 3 I (12546) wifi:state: run -> init (2c0) I (12546) wifi:pm stop, total sleep time: 536248 us / 786855 us I (12548) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (12550) FW_CMD: STA disconnected I (12554) FW_CMD: STA Disconnect event: 2 I (12558) FW_CMD: cleanup_ap_bssid I (12578) FW_MAIN: Delete key request I (12598) FW_MAIN: Delete key request I (12608) FW_MAIN: Delete key request I (12616) FW_MAIN: Delete key request I (12636) FW_MAIN: Delete key request I (12646) FW_MAIN: Delete key request I (12664) FW_MAIN: Scan request I (13942) FW_MAIN: Scan request I (13964) FW_MAIN: STA connect request I (13964) FW_CMD: Attempting secured connection I (13964) FW_CMD: pc_test, channel: 1 I (13978) STA Connection: 0x3ffd032c 02 42 38 0c af c1 |.B8...| I (13978) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (13982) wifi:state: init -> auth (b0) I (13986) wifi:state: auth -> init (2c0) I (13988) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (13992) FW_CMD: STA disconnected I (13996) FW_CMD: STA Disconnect event: 2 I (14000) FW_CMD: cleanup_ap_bssid I (14016) FW_MAIN: Delete key request I (14034) FW_MAIN: Delete key request I (14044) FW_MAIN: Delete key request I (14062) FW_MAIN: Delete key request I (14072) FW_MAIN: Delete key request I (14092) FW_MAIN: Delete key request I (14110) FW_MAIN: Scan request I (15400) FW_MAIN: STA connect request I (15400) FW_CMD: Attempting secured connection I (15402) FW_CMD: pc_test, channel: 1 I (15452) STA Connection: 0x3ffd032c 02 42 38 0c af c1 |.B8...| I (15452) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (15456) wifi:state: init -> auth (b0) I (15468) wifi:state: auth -> assoc (0) I (15474) FW_CMD: handle_wpa_sta_rx_mgmt:508 ASSOC Response[1] recvd I (15474) FW_CMD: STA connect event [channel 0] I (15476) BSSID: 0x3ffd3a81 02 42 38 0c af c1 |.B8...| I (15482) wifi:state: assoc -> run (10) I (15536) FW_MAIN: Add key request W (15536) wifi:ipencil I (15554) FW_MAIN: Set default key request I (15574) FW_MAIN: Add key request W (15574) wifi:ipencil I (15574) FW_CMD: process_add_key:1175 set auth to done I (15574) wifi:connected with pc_test, aid = 9, channel 1, BW20, bssid = 02:42:38:0c:af:c1 I (15582) wifi:security: WPA2-PSK, phy: bgn, rssi: -30 I (15586) wifi:pm start, type: 1 I (15612) wifi:AP's beacon interval = 102400 us, DTIM period = 3 I (15614) wifi:state: run -> init (2c0) I (15614) wifi:pm stop, total sleep time: 0 us / 34166 us I (15616) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (15620) FW_CMD: STA disconnected I (15624) FW_CMD: STA Disconnect event: 2 I (15628) FW_CMD: cleanup_ap_bssid I (15640) FW_MAIN: Delete key request I (15660) FW_MAIN: Delete key request I (15678) FW_MAIN: Delete key request I (15688) FW_MAIN: Delete key request I (15698) FW_MAIN: Delete key request I (15718) FW_MAIN: Delete key request I (15730) FW_MAIN: Scan request I (17024) FW_MAIN: STA connect request I (17024) FW_CMD: Attempting secured connection I (17024) FW_CMD: pc_test, channel: 1 I (17034) STA Connection: 0x3ffd032c 02 42 38 0c af c1 |.B8...| I (17034) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (17038) wifi:state: init -> auth (b0) I (17072) wifi:state: auth -> assoc (0) I (17076) FW_CMD: handle_wpa_sta_rx_mgmt:508 ASSOC Response[1] recvd I (17076) FW_CMD: STA connect event [channel 0] I (17078) BSSID: 0x3ffd3a81 02 42 38 0c af c1 |.B8...| I (17086) wifi:state: assoc -> run (10) I (17138) FW_MAIN: Add key request W (17138) wifi:ipencil I (17158) FW_MAIN: Set default key request I (17176) FW_MAIN: Add key request W (17176) wifi:ipencil I (17176) FW_CMD: process_add_key:1175 set auth to done I (17178) wifi:connected with pc_test, aid = 10, channel 1, BW20, bssid = 02:42:38:0c:af:c1 I (17184) wifi:security: WPA2-PSK, phy: bgn, rssi: -28 I (17188) wifi:pm start, type: 1 I (17222) wifi:state: run -> init (2c0) I (17222) wifi:pm stop, total sleep time: 0 us / 39507 us I (17222) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (17226) FW_CMD: STA disconnected I (17228) FW_CMD: STA Disconnect event: 2 I (17232) FW_CMD: cleanup_ap_bssid I (17250) FW_MAIN: Delete key request I (17270) FW_MAIN: Delete key request I (17278) FW_MAIN: Delete key request I (17298) FW_MAIN: Delete key request I (17308) FW_MAIN: Delete key request I (17326) FW_MAIN: Delete key request I (17346) FW_MAIN: Scan request I (18632) FW_MAIN: STA connect request I (18632) FW_CMD: Attempting secured connection I (18632) FW_CMD: pc_test, channel: 1 I (18646) STA Connection: 0x3ffd032c 02 42 38 0c af c1 |.B8...| I (18646) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (18650) wifi:state: init -> auth (b0) I (18662) wifi:state: auth -> init (2c0) I (18662) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (18662) FW_CMD: STA disconnected I (18666) FW_CMD: STA Disconnect event: 2 I (18670) FW_CMD: cleanup_ap_bssid I (18690) FW_MAIN: Delete key request I (18710) FW_MAIN: Delete key request I (18728) FW_MAIN: Delete key request I (18748) FW_MAIN: Delete key request I (18766) FW_MAIN: Delete key request I (18786) FW_MAIN: Delete key request I (18806) FW_MAIN: Scan request I (20102) FW_MAIN: STA connect request I (20102) FW_CMD: Attempting secured connection I (20102) FW_CMD: pc_test, channel: 1 I (20122) STA Connection: 0x3ffd032c 02 42 38 0c af c1 |.B8...| I (20124) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (20126) wifi:state: init -> auth (b0) I (20130) wifi:state: auth -> init (2c0) I (20132) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (20138) FW_CMD: STA disconnected I (20140) FW_CMD: STA Disconnect event: 2 I (20144) FW_CMD: cleanup_ap_bssid I (20158) FW_MAIN: Delete key request I (20178) FW_MAIN: Delete key request I (20198) FW_MAIN: Delete key request I (20216) FW_MAIN: Delete key request I (20236) FW_MAIN: Delete key request I (20254) FW_MAIN: Delete key request I (20274) FW_MAIN: Scan request I (21560) FW_MAIN: STA connect request I (21560) FW_CMD: Attempting secured connection I (21560) FW_CMD: pc_test, channel: 1 I (21596) STA Connection: 0x3ffd032c 02 42 38 0c af c1 |.B8...| I (21596) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (21600) wifi:state: init -> auth (b0) I (21604) wifi:state: auth -> init (2c0) I (21606) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1 I (21610) FW_CMD: STA disconnected I (21614) FW_CMD: STA Disconnect event: 2 I (21618) FW_CMD: cleanup_ap_bssid I (21640) FW_MAIN: Delete key request I (21660) FW_MAIN: Delete key request I (21670) FW_MAIN: Delete key request I (21678) FW_MAIN: Delete key request I (21698) FW_MAIN: Delete key request I (21708) FW_MAIN: Delete key request I (21726) FW_MAIN: Scan request ```

I'll have a look at the thread you linked and search the espressif foruns in the meantime. Many thanks

mantriyogesh commented 1 year ago

Can you please take Wi-Fi sniffer log? (it should be easy on laptops. Check Wi-Fi monitor mode if Linux) Possibly in open security mode? If this issue is not experienced on open mode, please make sure M1-M4 are captured in the capture for your security mode.

mantriyogesh commented 1 year ago

Hi @doutordre , Any update on this?

doutordre commented 1 year ago

Hi @mantriyogesh,

I have attempted connecting the ESP32 to my mobile hotspot as a private network with WPA2-Personal security and as an open network. It failed and gave a very similar output as the logs above detail.

My laptop runs Windows so I have made a log with Wireshark of a connection to my mobile hotspot as an open network. I don't know what we're looking for so I have run it without any filters applied. The logs are in attachment

Let me know if this is of any help. open_network_test.txt private_network_test.txt