[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd030] [ 0.000000] Linux version 7.2.7-aurora-bam1 (q@aiaiai) (aarch64-linux-gnu-gcc (Debian 14.2.0-19) 14.2.0, GNU ld (GNU Binutils for Debian) 2.44) #5 SMP PREEMPT Wed Sep 30 04:59:18 +06 2026 [ 0.000000] KASLR enabled [ 0.000000] random: crng init done [ 0.000000] Machine model: JZ08AU Aurora (RAM boot BAM1) [ 0.000000] earlycon: msm_serial_dm0 at MMIO 0x00000000078b0000 (options '115200n8') [ 0.000000] printk: legacy bootconsole [msm_serial_dm0] enabled [ 0.000000] printk: debug: ignoring loglevel setting. [ 0.000000] OF: reserved mem: 0x000000008e700000..0x000000008e7fffff (1024 KiB) nomap non-reusable mba [ 0.000000] OF: reserved mem: 0x0000000086000000..0x00000000862fffff (3072 KiB) nomap non-reusable tz-apps@86000000 [ 0.000000] OF: reserved mem: 0x0000000086300000..0x00000000863fffff (1024 KiB) nomap non-reusable smem@86300000 [ 0.000000] OF: reserved mem: 0x0000000086400000..0x00000000864fffff (1024 KiB) nomap non-reusable hypervisor@86400000 [ 0.000000] OF: reserved mem: 0x0000000086500000..0x000000008667ffff (1536 KiB) nomap non-reusable tz@86500000 [ 0.000000] OF: reserved mem: 0x0000000086680000..0x00000000866fffff (512 KiB) nomap non-reusable reserved@86680000 [ 0.000000] OF: reserved mem: 0x0000000086700000..0x00000000867dffff (896 KiB) nomap non-reusable rmtfs@86700000 [ 0.000000] OF: reserved mem: 0x00000000867e0000..0x00000000867fffff (128 KiB) nomap non-reusable rfsa@867e0000 [ 0.000000] OF: reserved mem: 0x0000000086800000..0x000000008b5fffff (79872 KiB) nomap non-reusable mpss@86800000 [ 0.000000] cma: Reserved 32 MiB at 0x000000009de00000 [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv1.0 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: MIGRATE_INFO_TYPE not supported. [ 0.000000] psci: SMC Calling Convention v1.0 [ 0.000000] psci: OSI mode supported. [ 0.000000] psci: [Firmware Bug]: failed to set PC mode: -3 [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000080000000-0x000000009fffffff] [ 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 0x0000000080000000-0x0000000085ffffff] [ 0.000000] node 0: [mem 0x0000000086000000-0x000000008b5fffff] [ 0.000000] node 0: [mem 0x000000008b600000-0x000000008e6fffff] [ 0.000000] node 0: [mem 0x000000008e700000-0x000000008e7fffff] [ 0.000000] node 0: [mem 0x000000008e800000-0x000000009fffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x000000009fffffff] [ 0.000000] percpu: Embedded 19 pages/cpu s48408 r0 d29416 u77824 [ 0.000000] pcpu-alloc: s48408 r0 d29416 u77824 alloc=19*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] CPU features: detected: ARM errata 826319, 827319, 824069, or 819472 [ 0.000000] alternatives: applying boot alternatives [ 0.000000] Kernel command line: earlycon console=ttyMSM0,115200n8 ignore_loglevel loglevel=8 rdinit=/init [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes [ 0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes, linear) [ 0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes, linear) [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 0MB [ 0.000000] software IO TLB: area num 4. [ 0.000000] software IO TLB: SWIOTLB bounce buffer size roundup to 1MB [ 0.000000] software IO TLB: mapped [mem 0x000000009d480000-0x000000009d580000] (1MB) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 131072 [ 0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. [ 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: gic_handle_irq [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.000000] arch_timer: cp15 timer running at 19.20MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns [ 0.000000] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns [ 0.010988] Console: colour dummy device 80x25 [ 0.018715] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800) [ 0.023191] pid_max: default: 32768 minimum: 301 [ 0.033777] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes, linear) [ 0.038209] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes, linear) [ 0.045582] VFS: Finished mounting rootfs on nullfs [ 0.055494] rcu: Hierarchical SRCU implementation. [ 0.057831] rcu: Max phase no-delay instances is 1000. [ 0.062927] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level [ 0.068788] smp: Bringing up secondary CPUs ... [ 0.076880] Detected VIPT I-cache on CPU1 [ 0.077054] CPU1: Booted secondary processor 0x0000000001 [0x410fd030] [ 0.077902] Detected VIPT I-cache on CPU2 [ 0.078065] CPU2: Booted secondary processor 0x0000000002 [0x410fd030] [ 0.078879] Detected VIPT I-cache on CPU3 [ 0.079033] CPU3: Booted secondary processor 0x0000000003 [0x410fd030] [ 0.079165] smp: Brought up 1 node, 4 CPUs [ 0.111995] SMP: Total of 4 processors activated. [ 0.116058] CPU: All CPU(s) started at EL1 [ 0.120855] CPU features: detected: 32-bit EL0 Support [ 0.124818] CPU features: detected: CRC32 instructions [ 0.129961] CPU features: detected: PMUv3 [ 0.135129] alternatives: applying system-wide alternatives [ 0.140365] Memory: 353700K/524288K available (11904K kernel code, 3340K rwdata, 5696K rodata, 1408K init, 463K bss, 135484K reserved, 32768K cma-reserved) [ 0.145304] devtmpfs: initialized [ 0.172888] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear) [ 0.173025] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear). [ 0.180041] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL [ 0.187809] 0 pages in range for non-PLT usage [ 0.187817] 518528 pages in range for PLT usage [ 0.198640] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.204026] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.210380] thermal_sys: Registered thermal governor 'step_wise' [ 0.210391] thermal_sys: Registered thermal governor 'power_allocator' [ 0.216959] cpuidle: using governor menu [ 0.229383] NET: Registered PF_QIPCRTR protocol family [ 0.233445] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.238330] ASID allocator initialised with 32768 entries [ 0.245596] Serial: AMBA PL011 UART driver [ 0.253227] CPUidle PSCI: Initialized CPU PM domain topology using OSI mode [ 0.262410] /soc@0/usb@78d9000: Fixed dependency cycle(s) with /soc@0/usb@78d9000/ulpi/phy [ 0.262518] /soc@0/usb@78d9000/ulpi/phy: Fixed dependency cycle(s) with /soc@0/usb@78d9000 [ 0.269670] /soc@0/interrupt-controller@b000000: Fixed dependency cycle(s) with /soc@0/interrupt-controller@b000000 [ 0.290664] /soc@0/usb@78d9000: Fixed dependency cycle(s) with /soc@0/usb@78d9000/ulpi/phy [ 0.290821] /soc@0/usb@78d9000/ulpi/phy: Fixed dependency cycle(s) with /soc@0/usb@78d9000 [ 0.300447] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages [ 0.306122] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page [ 0.312968] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages [ 0.319033] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page [ 0.325978] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages [ 0.332052] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page [ 0.338999] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages [ 0.345072] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page [ 0.357361] iommu: Default domain type: Translated [ 0.358082] iommu: DMA domain TLB invalidation policy: strict mode [ 0.364036] pps_core: LinuxPPS API ver. 1 registered [ 0.369127] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.374261] PTP clock support registered [ 0.383448] EDAC MC: Ver: 3.0.0 [ 0.387635] scmi_core: SCMI protocol bus registered [ 0.391339] qcom_scm: convention: smc arm 32 [ 0.394987] qcom_scm firmware:scm: SHM Bridge not supported [ 0.399687] qcom_scm firmware:scm: qseecom: found qseecom with version 0x800000 [ 0.404829] qcom_scm firmware:scm: qseecom: untested machine, skipping [ 0.412832] FPGA manager framework [ 0.420207] clocksource: Switched to clocksource arch_sys_counter [ 0.422385] VFS: Disk quotas dquot_6.6.0 [ 0.428287] VFS: Dquot-cache hash table entries: 512 (4096 bytes) [ 0.441371] NET: Registered PF_INET protocol family [ 0.441523] IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 0.445895] tcp_listen_portaddr_hash hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.452398] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) [ 0.460631] TCP established hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 0.468653] TCP bind hash table entries: 4096 (order: 5, 131072 bytes, linear) [ 0.476472] TCP: Hash tables configured (established 4096 bind 4096) [ 0.483440] UDP hash table entries: 256 (order: 2, 16384 bytes, linear) [ 0.490007] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 0.496568] Unpacking initramfs... [ 0.503303] Initialise system trusted keyrings [ 0.505592] workingset: timestamp_bits=46 (anon: 41) max_order=17 bucket_order=0 (anon: 0) [ 0.510295] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.518326] 9p: Installing v9fs 9p2000 file system support [ 0.524342] NET: Registered PF_ALG protocol family [ 0.529326] Key type asymmetric registered [ 0.534058] Asymmetric key parser 'x509' registered [ 0.538255] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 239) [ 0.542921] io scheduler mq-deadline registered [ 0.550565] io scheduler kyber registered [ 0.554883] io scheduler bfq registered [ 0.568118] ledtrig-cpu: registered to indicate activity on CPUs [ 0.568616] IPMI message handler: version 39.2 [ 0.573340] ipmi device interface [ 0.577781] ipmi_si: IPMI System Interface driver [ 0.581115] ipmi_si: Unable to find any System Interface(s) [ 0.604431] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.608474] msm_serial 78b0000.serial: msm_serial: detected port #0 [ 0.609596] msm_serial 78b0000.serial: uartclk = 7372800 [ 0.616350] 78b0000.serial: ttyMSM0 at MMIO 0x78b0000 (irq = 17, base_baud = 460800) is a MSM [ 0.621406] msm_serial: console setup on port #0 [ 0.621532] printk: legacy console [ttyMSM0] enabled [ 0.630135] printk: legacy bootconsole [msm_serial_dm0] disabled [ 0.645111] msm_serial: driver initialized [ 0.648531] qcom-iommu 1ef0000.iommu: iommu sec: pgtable size: 94208 [ 0.668322] loop: module loaded [ 0.696126] spmi_pmic_arb 200f000.spmi: PMIC arbiter version v2 (0x20010000) [ 0.712842] tun: Universal TUN/TAP device driver, 1.6 [ 0.713597] VFIO - User Level meta-driver version: 0.3 [ 0.722001] rtc-pm8xxx 200f000.spmi:pmic@0:rtc@6000: registered as rtc0 [ 0.722084] rtc-pm8xxx 200f000.spmi:pmic@0:rtc@6000: setting system clock to 1970-01-01T02:19:43 UTC (8383) [ 0.729082] i2c_dev: i2c /dev entries driver [ 0.742858] input: pm8941_pwrkey as /devices/platform/soc@0/200f000.spmi/spmi-0/0-00/200f000.spmi:pmic@0:pon@800/200f000.spmi:pmic@0:pon@800:pwrkey/input/input0 [ 0.755348] qcom_rng 22000.rng: TRNG support not detected [ 0.757784] clocksource: arch_mmio_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns [ 0.762331] arch-timer-mmio b020000.timer: mmio timer running at 19.20MHz (virt) [ 0.779407] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 (0,8000003f) counters available [ 0.782254] cs_system_cfg: CoreSight Configuration manager initialised [ 0.793137] gnss: GNSS driver registered with major 505 [ 0.797957] NET: Registered PF_INET6 protocol family [ 0.803096] Segment Routing with IPv6 [ 0.806743] In-situ OAM (IOAM) with IPv6 [ 0.810306] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 0.814922] NET: Registered PF_PACKET protocol family [ 0.820295] 9pnet: Installing 9P2000 support [ 0.825170] Key type dns_resolver registered [ 0.839475] registered taskstats version 1 [ 0.839526] Loading compiled-in X.509 certificates [ 0.875686] qcom-smsm smsm: mbox_request_channel: can't parse "mboxes" property [ 0.875744] qcom-smsm smsm: mbox_request_channel: can't parse "mboxes" property [ 0.881889] qcom-smsm smsm: mbox_request_channel: can't parse "mboxes" property [ 0.920713] msm_hsusb 78d9000.usb: Failed to create device link (0x180) with supplier remoteproc for /soc@0/usb@78d9000/ulpi/phy [ 0.924840] remoteproc remoteproc0: releasing 4080000.remoteproc [ 0.943098] s3: Bringing 0uV into 1250000-1250000uV [ 0.943846] s4: Bringing 0uV into 1850000-1850000uV [ 0.947792] l2: Bringing 0uV into 1200000-1200000uV [ 0.950218] remoteproc remoteproc0: releasing 4080000.remoteproc [ 0.952321] l5: Bringing 0uV into 1800000-1800000uV [ 0.963401] l6: Bringing 0uV into 1800000-1800000uV [ 0.966252] remoteproc remoteproc0: releasing 4080000.remoteproc [ 0.967672] l7: Bringing 0uV into 1800000-1800000uV [ 0.976350] remoteproc remoteproc0: releasing 4080000.remoteproc [ 0.979025] l8: Bringing 0uV into 2900000-2900000uV [ 0.983287] gcc-msm8916 1800000.clock-controller: sync_state() pending due to 4080000.remoteproc [ 0.989685] l9: Bringing 0uV into 3300000-3300000uV [ 0.993947] gcc-msm8916 1800000.clock-controller: sync_state() pending due to 7824900.mmc [ 1.003533] l11: Bringing 0uV into 2950000-2950000uV [ 1.007944] clk: Disabling unused clocks [ 1.016286] l12: Bringing 0uV into 1800000-1800000uV [ 1.021149] PM: genpd: Disabling unused power domains [ 1.025153] l13: Bringing 0uV into 3075000-3075000uV [ 1.042551] remoteproc remoteproc0: 4080000.remoteproc is available [ 1.075973] mmc0: SDHCI controller on 7824900.mmc [7824900.mmc] using ADMA [ 1.195998] mmc0: Card appears overclocked; req 177770000 Hz, actual 177777777 Hz [ 1.196059] mmc0: Card appears overclocked; req 177770000 Hz, actual 177777777 Hz [ 1.204817] mmc0: new HS200 MMC card at address 0001 [ 1.210993] mmcblk0: mmc0:0001 H4G2a 3.64 GiB [ 1.222702] mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 p9 p10 p11 p12 p13 p14 p15 p16 p17 p18 p19 p20 p21 p22 p23 p24 p25 p26 p27 [ 1.224824] Freeing initrd memory: 11672K [ 1.228025] mmcblk0boot0: mmc0:0001 H4G2a 4.00 MiB [ 1.238018] Freeing unused kernel memory: 1408K [ 1.238195] Run /init as init process [ 1.241385] with arguments: [ 1.243562] mmcblk0boot1: mmc0:0001 H4G2a 4.00 MiB [ 1.245190] /init [ 1.245196] with environment: [ 1.253229] mmcblk0rpmb: mmc0:0001 H4G2a 4.00 MiB, chardev (506:0) [ 1.255241] HOME=/ [ 1.264459] TERM=linux [ 1.905028] [init] EMMC: all 30 block devices read-only (getro=1) [ 6.884736] [init] B1: modem FAT mounted RO at /firmware, firmware path /firmware/image [ 6.920697] configfs-gadget.aurora gadget.0: HOST MAC [ 6.920739] configfs-gadget.aurora gadget.0: MAC [ 6.926397] l13: voltage operation not allowed [ 6.933274] [init] usb gadget bound to UDC 'ci_hdrc.0' [ 6.949565] [init] ncm ifname='usb0' dev_addr= host_addr= [ 6.972419] [init] network: usb0 172.16.42.1/24 up (static, no DHCP) [ 9.549496] [aurora-modem] === START (conf /etc/aurora/modem.conf, apn 'internet.beeline.ru', ip-type ipv4) [ 9.623513] remoteproc remoteproc0: powering up 4080000.remoteproc [ 9.624034] remoteproc remoteproc0: Booting fw image mba.mbn, size 234176 [ 9.637531] [aurora-modem] STATE OFF -> RMTFS_READY [ 9.643091] [aurora-modem] STATE RMTFS_READY -> MPSS_BOOTING [ 9.676472] qcom-q6v5-mss 4080000.remoteproc: MBA booted without debug policy, loading mpss [ 10.220171] remoteproc remoteproc0: remote processor 4080000.remoteproc is now up [ 10.317472] [aurora-modem] MSS running after 0.7s [ 10.323700] [aurora-modem] STATE MPSS_BOOTING -> MPSS_RUNNING [ 10.814863] wwan wwan0: port wwan0at0 attached [ 10.816365] wwan wwan0: port wwan0at1 attached [ 10.976316] wwan wwan0: port wwan0qmi0 attached [ 11.178797] [aurora-modem] wwan0qmi0 + wwan0 present after 0.8s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post) [ 13.368586] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY [ 13.379915] [aurora-modem] DMS Mode: 'shutting-down' after 0.2s [ 13.431678] [aurora-modem] STATE QMI_READY -> SIM_READY [ 13.441833] [aurora-modem] UIM card present, USIM app ready after 0.0s [ 13.449278] [aurora-modem] SET: qmicli -p -d /dev/wwan0qmi0 --dms-set-operating-mode=online (retried only on DeviceNotReady, <= 30s) [ 16.697804] [aurora-modem] DMS online after 3.2s [ 16.703294] [aurora-modem] STATE SIM_READY -> RADIO_ONLINE [ 17.039474] [aurora-modem] REG t+0.3s: not-registered-searching / PS detached / none (none, ) - pci= si=/ cl=/ [ 20.330449] [aurora-modem] REG t+3.6s: registered / PS attached / available (none, ps) 250-99 pci=390 si=7661/156416008 cl=7661/156416008 [ 20.343556] [aurora-modem] registered: MCC: '250' MNC: '99' Description: 'Beeline' MCC: '250' MNC: '99' after 3.6s [ 20.350339] [aurora-modem] STATE RADIO_ONLINE -> REGISTERED [ 20.406032] udevd[1490]: starting version 3.2.14 [ 20.410704] udevd[1490]: specified group 'tty' unknown [ 20.411129] udevd[1490]: specified group 'dialout' unknown [ 20.415095] udevd[1490]: specified group 'kmem' unknown [ 20.420555] udevd[1490]: specified group 'input' unknown [ 20.425492] udevd[1490]: specified group 'video' unknown [ 20.431125] udevd[1490]: specified group 'audio' unknown [ 20.436459] udevd[1490]: specified group 'lp' unknown [ 20.441653] udevd[1490]: specified group 'disk' unknown [ 20.446554] udevd[1490]: specified group 'cdrom' unknown [ 20.479841] udevd[1491]: starting eudev-3.2.14 [ 20.847594] [aurora-modem] udevd started [ 20.877312] [aurora-modem] dbus started [ 20.891627] [aurora-modem] polkitd started [ 20.911945] [aurora-modem] ModemManager started pid 1535 (log mm-0.log) [ 61.085790] [aurora-modem] MM modem 0 detected after 40.2s [ 61.193741] [aurora-modem] STATE REGISTERED -> MM_READY [ 61.393984] [aurora-modem] MM state disabled after 0.2s [ 61.542428] [aurora-modem] mmcli -m 0 --simple-connect="apn=internet.beeline.ru,user=beeline,password=***,allowed-auth=pap,ip-type=ipv4" [ 72.185942] [aurora-modem] MM state connected, registration: home [ 72.269857] [aurora-modem] bearer 1: wwan0 10.80.93.64/25 gw 10.80.93.65 dns 10.10.22.1,194.186.191.1 mtu 1430 [ 72.326539] [aurora-modem] STATE MM_READY -> BEARER_CONNECTED [ 72.338974] [aurora-modem] === START OK in 62.8s (wwan0 10.80.93.64/25, routes: 10.10.22.1 194.186.191.1 77.88.8.8 1.1.1.1 ) [ 115.000766] [p6] date=1970-01-01T02:21:37 (clock not set by test) [ 115.025905] [p6] autostart: === service start rc=0 72.19 / START OK in 62.8s [ 120.372623] [aurora-modem] === DISCONNECT (from BEARER_CONNECTED: mss=running rmtfs=1043 mm=1535 bam=active) [ 120.998500] [aurora-modem] simple-disconnect rc=0 (bearers: 1 ) [ 121.166493] [aurora-modem] no bearer connected [ 122.802144] [aurora-modem] BAM-DMUX suspended after 1.6s [ 122.809447] [aurora-modem] STATE BEARER_CONNECTED -> MM_IDLE [ 122.950578] [aurora-modem] === DISCONNECT done rc=0 in 2.5s (mss=running rmtfs='1043' mm='1535' mm_state=registered bam=suspended) [ 128.063565] [aurora-modem] === START (conf /etc/aurora/modem.conf, apn 'internet.beeline.ru', ip-type ipv4) [ 128.123292] [aurora-modem] rmtfs 1043 + MSS running already - reuse [ 128.129231] [aurora-modem] STATE MM_IDLE -> MPSS_RUNNING [ 128.142728] [aurora-modem] wwan0qmi0 + wwan0 present after 0.0s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post) [ 130.169149] [aurora-modem] ModemManager already running - skip qmicli, use MM state [ 130.175063] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY [ 130.336977] [aurora-modem] MM modem 0 detected after 0.1s [ 130.438880] [aurora-modem] STATE QMI_READY -> MM_READY [ 130.632286] [aurora-modem] MM state registered after 0.2s [ 130.825240] [aurora-modem] mmcli -m 0 --simple-connect="apn=internet.beeline.ru,user=beeline,password=***,allowed-auth=pap,ip-type=ipv4" [ 134.068441] [aurora-modem] MM state connected, registration: home [ 134.149644] [aurora-modem] bearer 1: wwan0 10.80.93.64/25 gw 10.80.93.65 dns 10.10.22.1,194.186.191.1 mtu 1430 [ 134.207341] [aurora-modem] STATE MM_READY -> BEARER_CONNECTED [ 134.217865] [aurora-modem] === START OK in 6.2s (wwan0 10.80.93.64/25, routes: 10.10.22.1 194.186.191.1 77.88.8.8 1.1.1.1 ) [ 138.755440] [p6] cycle 1: 1 reconnect+data 0 10.80.93.64 DATATEST PASS dns=1(ya=5.255.255.242 go=142.251.150.119) ping=3+3 http=code=302 https=[](skip) if=wwan0 addr=10.80.93.64 9 wwan0, active running 1043 1535 ok 0/0 0 [ 138.815559] [aurora-modem] === DISCONNECT (from BEARER_CONNECTED: mss=running rmtfs=1043 mm=1535 bam=active) [ 139.453441] [aurora-modem] simple-disconnect rc=0 (bearers: 1 ) [ 139.632048] [aurora-modem] no bearer connected [ 141.247533] [aurora-modem] BAM-DMUX suspended after 1.6s [ 141.253482] [aurora-modem] STATE BEARER_CONNECTED -> MM_IDLE [ 141.395818] [aurora-modem] === DISCONNECT done rc=0 in 2.5s (mss=running rmtfs='1043' mm='1535' mm_state=registered bam=suspended) [ 146.501083] [aurora-modem] === START (conf /etc/aurora/modem.conf, apn 'internet.beeline.ru', ip-type ipv4) [ 146.560323] [aurora-modem] rmtfs 1043 + MSS running already - reuse [ 146.567989] [aurora-modem] STATE MM_IDLE -> MPSS_RUNNING [ 146.582422] [aurora-modem] wwan0qmi0 + wwan0 present after 0.0s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post) [ 148.609052] [aurora-modem] ModemManager already running - skip qmicli, use MM state [ 148.614901] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY [ 148.777470] [aurora-modem] MM modem 0 detected after 0.1s [ 148.891797] [aurora-modem] STATE QMI_READY -> MM_READY [ 149.094630] [aurora-modem] MM state registered after 0.2s [ 149.284544] [aurora-modem] mmcli -m 0 --simple-connect="apn=internet.beeline.ru,user=beeline,password=***,allowed-auth=pap,ip-type=ipv4" [ 152.565211] [aurora-modem] MM state connected, registration: home [ 152.685842] [aurora-modem] bearer 1: wwan0 10.80.93.64/25 gw 10.80.93.65 dns 10.10.22.1,194.186.191.1 mtu 1430 [ 152.758765] [aurora-modem] STATE MM_READY -> BEARER_CONNECTED [ 152.781034] [aurora-modem] === START OK in 6.3s (wwan0 10.80.93.64/25, routes: 10.10.22.1 194.186.191.1 77.88.8.8 1.1.1.1 ) [ 157.529903] [p6] cycle 2: 2 reconnect+data 0 10.80.93.64 DATATEST PASS dns=1(ya=5.255.255.242 go=142.251.150.119) ping=3+3 http=code=302 https=[](skip) if=wwan0 addr=10.80.93.64 9 wwan0, active running 1043 1535 ok 0/0 0 [ 157.592349] [aurora-modem] === DISCONNECT (from BEARER_CONNECTED: mss=running rmtfs=1043 mm=1535 bam=active) [ 158.246056] [aurora-modem] simple-disconnect rc=0 (bearers: 1 ) [ 158.434592] [aurora-modem] no bearer connected [ 159.539393] [aurora-modem] BAM-DMUX suspended after 1.0s [ 159.545423] [aurora-modem] STATE BEARER_CONNECTED -> MM_IDLE [ 159.685369] [aurora-modem] === DISCONNECT done rc=0 in 2.0s (mss=running rmtfs='1043' mm='1535' mm_state=registered bam=suspended) [ 164.793094] [aurora-modem] === START (conf /etc/aurora/modem.conf, apn 'internet.beeline.ru', ip-type ipv4) [ 164.855691] [aurora-modem] rmtfs 1043 + MSS running already - reuse [ 164.861721] [aurora-modem] STATE MM_IDLE -> MPSS_RUNNING [ 164.875001] [aurora-modem] wwan0qmi0 + wwan0 present after 0.0s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post) [ 166.901670] [aurora-modem] ModemManager already running - skip qmicli, use MM state [ 166.907722] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY [ 167.068898] [aurora-modem] MM modem 0 detected after 0.1s [ 167.172744] [aurora-modem] STATE QMI_READY -> MM_READY [ 167.368861] [aurora-modem] MM state registered after 0.2s [ 167.562257] [aurora-modem] mmcli -m 0 --simple-connect="apn=internet.beeline.ru,user=beeline,password=***,allowed-auth=pap,ip-type=ipv4" [ 171.147381] [aurora-modem] MM state connected, registration: home [ 171.228113] [aurora-modem] bearer 1: wwan0 10.80.93.64/25 gw 10.80.93.65 dns 10.10.22.1,194.186.191.1 mtu 1430 [ 171.284878] [aurora-modem] STATE MM_READY -> BEARER_CONNECTED [ 171.296510] [aurora-modem] === START OK in 6.5s (wwan0 10.80.93.64/25, routes: 10.10.22.1 194.186.191.1 77.88.8.8 1.1.1.1 ) [ 176.073529] [p6] cycle 3: 3 reconnect+data 0 10.80.93.64 DATATEST PASS dns=1(ya=5.255.255.242 go=142.251.150.119) ping=3+3 http=code=302 https=[](skip) if=wwan0 addr=10.80.93.64 9 wwan0, active running 1043 1535 ok 0/0 0 [ 176.079884] [p6] RESULT before stop: all PASS [ 176.681693] [aurora-modem] === STOP (from BEARER_CONNECTED: mss=running rmtfs=1043 mm=1535 bam=active) [ 177.310224] [aurora-modem] simple-disconnect rc=0 (bearers: 1 ) [ 177.487546] [aurora-modem] no bearer connected [ 177.561663] [aurora-modem] wwan links down, addresses/routes removed [ 177.569727] [aurora-modem] STATE BEARER_CONNECTED -> WWAN_DOWN [ 180.260929] [aurora-modem] ModemManager stopped [ 180.300527] [aurora-modem] qmi-proxy stopped [ 180.306085] [aurora-modem] STATE WWAN_DOWN -> MM_STOPPED [ 180.333622] [aurora-modem] BAM-DMUX suspended after 0.0s [ 180.352045] wwan wwan0: port wwan0at0 disconnected [ 180.352574] wwan wwan0: port wwan0at1 disconnected [ 180.352654] [aurora-modem] SIGTERM rmtfs 1043 (rmtfs stops MSS, then exits) [ 180.356526] wwan wwan0: port wwan0qmi0 disconnected [ 180.374241] remoteproc remoteproc0: stopped remote processor 4080000.remoteproc [ 180.597935] [aurora-modem] MSS offline after 0.2s (rmtfs alive: no) [ 180.604984] [aurora-modem] STATE MM_STOPPED -> MPSS_STOPPED [ 180.620253] [aurora-modem] rmtfs exited after 0.3s [ 180.634479] [aurora-modem] STATE MPSS_STOPPED -> OFF [ 180.682987] [aurora-modem] === STOP done rc=0 in 4.0s (mss=offline rmtfs='' mm='' bam=suspended)