[    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.000001] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.010991] Console: colour dummy device 80x25
[    0.018715] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800)
[    0.023190] pid_max: default: 32768 minimum: 301
[    0.033774] 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.045586] VFS: Finished mounting rootfs on nullfs
[    0.055507] rcu: Hierarchical SRCU implementation.
[    0.057831] rcu: 	Max phase no-delay instances is 1000.
[    0.062928] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level
[    0.068800] smp: Bringing up secondary CPUs ...
[    0.076884] Detected VIPT I-cache on CPU1
[    0.077062] CPU1: Booted secondary processor 0x0000000001 [0x410fd030]
[    0.077910] Detected VIPT I-cache on CPU2
[    0.078072] CPU2: Booted secondary processor 0x0000000002 [0x410fd030]
[    0.078894] Detected VIPT I-cache on CPU3
[    0.079049] CPU3: Booted secondary processor 0x0000000003 [0x410fd030]
[    0.079192] smp: Brought up 1 node, 4 CPUs
[    0.111996] SMP: Total of 4 processors activated.
[    0.116058] CPU: All CPU(s) started at EL1
[    0.120854] CPU features: detected: 32-bit EL0 Support
[    0.124818] CPU features: detected: CRC32 instructions
[    0.129972] CPU features: detected: PMUv3
[    0.135111] alternatives: applying system-wide alternatives
[    0.140407] Memory: 353708K/524288K available (11904K kernel code, 3340K rwdata, 5696K rodata, 1408K init, 463K bss, 135476K reserved, 32768K cma-reserved)
[    0.145313] devtmpfs: initialized
[    0.172937] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear)
[    0.173077] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear).
[    0.180106] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL
[    0.187860] 0 pages in range for non-PLT usage
[    0.187868] 518528 pages in range for PLT usage
[    0.198705] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.204077] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.210432] thermal_sys: Registered thermal governor 'step_wise'
[    0.210444] thermal_sys: Registered thermal governor 'power_allocator'
[    0.217006] cpuidle: using governor menu
[    0.229437] NET: Registered PF_QIPCRTR protocol family
[    0.233496] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.238382] ASID allocator initialised with 32768 entries
[    0.245650] Serial: AMBA PL011 UART driver
[    0.253290] CPUidle PSCI: Initialized CPU PM domain topology using OSI mode
[    0.262472] /soc@0/usb@78d9000: Fixed dependency cycle(s) with /soc@0/usb@78d9000/ulpi/phy
[    0.262580] /soc@0/usb@78d9000/ulpi/phy: Fixed dependency cycle(s) with /soc@0/usb@78d9000
[    0.269720] /soc@0/interrupt-controller@b000000: Fixed dependency cycle(s) with /soc@0/interrupt-controller@b000000
[    0.290753] /soc@0/usb@78d9000: Fixed dependency cycle(s) with /soc@0/usb@78d9000/ulpi/phy
[    0.290912] /soc@0/usb@78d9000/ulpi/phy: Fixed dependency cycle(s) with /soc@0/usb@78d9000
[    0.300538] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
[    0.306212] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
[    0.313058] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
[    0.319123] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
[    0.326067] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
[    0.332142] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
[    0.339089] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
[    0.345162] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
[    0.357429] iommu: Default domain type: Translated
[    0.358173] iommu: DMA domain TLB invalidation policy: strict mode
[    0.364128] pps_core: LinuxPPS API ver. 1 registered
[    0.369219] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.374351] PTP clock support registered
[    0.383538] EDAC MC: Ver: 3.0.0
[    0.387727] scmi_core: SCMI protocol bus registered
[    0.391430] qcom_scm: convention: smc arm 32
[    0.395078] qcom_scm firmware:scm: SHM Bridge not supported
[    0.399773] qcom_scm firmware:scm: qseecom: found qseecom with version 0x800000
[    0.404919] qcom_scm firmware:scm: qseecom: untested machine, skipping
[    0.412923] FPGA manager framework
[    0.420306] clocksource: Switched to clocksource arch_sys_counter
[    0.422476] VFS: Disk quotas dquot_6.6.0
[    0.428379] VFS: Dquot-cache hash table entries: 512 (4096 bytes)
[    0.441485] NET: Registered PF_INET protocol family
[    0.441651] IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.446049] tcp_listen_portaddr_hash hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.452507] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.460740] TCP established hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.468756] TCP bind hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.476582] TCP: Hash tables configured (established 4096 bind 4096)
[    0.483554] UDP hash table entries: 256 (order: 2, 16384 bytes, linear)
[    0.490123] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.496643] Unpacking initramfs...
[    0.497714] Initialise system trusted keyrings
[    0.505685] workingset: timestamp_bits=46 (anon: 41) max_order=17 bucket_order=0 (anon: 0)
[    0.510404] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.518426] 9p: Installing v9fs 9p2000 file system support
[    0.524445] NET: Registered PF_ALG protocol family
[    0.529436] Key type asymmetric registered
[    0.534171] Asymmetric key parser 'x509' registered
[    0.538366] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 239)
[    0.543039] io scheduler mq-deadline registered
[    0.550685] io scheduler kyber registered
[    0.555001] io scheduler bfq registered
[    0.568260] ledtrig-cpu: registered to indicate activity on CPUs
[    0.568732] IPMI message handler: version 39.2
[    0.573489] ipmi device interface
[    0.577922] ipmi_si: IPMI System Interface driver
[    0.581278] ipmi_si: Unable to find any System Interface(s)
[    0.604855] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.608951] msm_serial 78b0000.serial: msm_serial: detected port #0
[    0.610026] msm_serial 78b0000.serial: uartclk = 7372800
[    0.616810] 78b0000.serial: ttyMSM0 at MMIO 0x78b0000 (irq = 17, base_baud = 460800) is a MSM
[    0.621822] msm_serial: console setup on port #0
[    0.621929] printk: legacy console [ttyMSM0] enabled
[    0.630575] printk: legacy bootconsole [msm_serial_dm0] disabled
[    0.645077] msm_serial: driver initialized
[    0.648999] qcom-iommu 1ef0000.iommu: iommu sec: pgtable size: 94208
[    0.671592] loop: module loaded
[    0.704596] spmi_pmic_arb 200f000.spmi: PMIC arbiter version v2 (0x20010000)
[    0.721339] tun: Universal TUN/TAP device driver, 1.6
[    0.722101] VFIO - User Level meta-driver version: 0.3
[    0.730534] rtc-pm8xxx 200f000.spmi:pmic@0:rtc@6000: registered as rtc0
[    0.730620] rtc-pm8xxx 200f000.spmi:pmic@0:rtc@6000: setting system clock to 1970-01-01T00:00:50 UTC (50)
[    0.737577] i2c_dev: i2c /dev entries driver
[    0.751372] 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.763858] qcom_rng 22000.rng: TRNG support not detected
[    0.766113] clocksource: arch_mmio_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.770648] arch-timer-mmio b020000.timer: mmio timer running at 19.20MHz (virt)
[    0.787766] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 (0,8000003f) counters available
[    0.790546]  cs_system_cfg: CoreSight Configuration manager initialised
[    0.801444] gnss: GNSS driver registered with major 505
[    0.806258] NET: Registered PF_INET6 protocol family
[    0.811451] Segment Routing with IPv6
[    0.815037] In-situ OAM (IOAM) with IPv6
[    0.818614] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    0.823214] NET: Registered PF_PACKET protocol family
[    0.828528] 9pnet: Installing 9P2000 support
[    0.833481] Key type dns_resolver registered
[    0.847670] registered taskstats version 1
[    0.847725] Loading compiled-in X.509 certificates
[    0.884856] qcom-smsm smsm: mbox_request_channel: can't parse "mboxes" property
[    0.884914] qcom-smsm smsm: mbox_request_channel: can't parse "mboxes" property
[    0.891072] qcom-smsm smsm: mbox_request_channel: can't parse "mboxes" property
[    0.929946] msm_hsusb 78d9000.usb: Failed to create device link (0x180) with supplier remoteproc for /soc@0/usb@78d9000/ulpi/phy
[    0.935827] remoteproc remoteproc0: releasing 4080000.remoteproc
[    0.950057] s3: Bringing 0uV into 1250000-1250000uV
[    0.950488] s4: Bringing 0uV into 1850000-1850000uV
[    0.954463] l2: Bringing 0uV into 1200000-1200000uV
[    0.959304] remoteproc remoteproc0: releasing 4080000.remoteproc
[    0.959308] l5: Bringing 0uV into 1800000-1800000uV
[    0.959639] l6: Bringing 0uV into 1800000-1800000uV
[    0.972488] remoteproc remoteproc0: releasing 4080000.remoteproc
[    0.974867] l7: Bringing 0uV into 1800000-1800000uV
[    0.981706] remoteproc remoteproc0: releasing 4080000.remoteproc
[    0.986289] l8: Bringing 0uV into 2900000-2900000uV
[    0.996410] remoteproc remoteproc0: 4080000.remoteproc is available
[    0.996595] l9: Bringing 0uV into 3300000-3300000uV
[    1.005186] gcc-msm8916 1800000.clock-controller: sync_state() pending due to 7824900.mmc
[    1.007730] l11: Bringing 0uV into 2950000-2950000uV
[    1.012490] clk: Disabling unused clocks
[    1.020684] l12: Bringing 0uV into 1800000-1800000uV
[    1.025625] PM: genpd: Disabling unused power domains
[    1.029716] l13: Bringing 0uV into 3075000-3075000uV
[    1.068601] mmc0: SDHCI controller on 7824900.mmc [7824900.mmc] using ADMA
[    1.186868] mmc0: Card appears overclocked; req 177770000 Hz, actual 177777777 Hz
[    1.186927] mmc0: Card appears overclocked; req 177770000 Hz, actual 177777777 Hz
[    1.195752] mmc0: new HS200 MMC card at address 0001
[    1.201680] mmcblk0: mmc0:0001 H4G2a 3.64 GiB
[    1.211717]  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.215573] mmcblk0boot0: mmc0:0001 H4G2a 4.00 MiB
[    1.226142] Freeing initrd memory: 11664K
[    1.227329] Freeing unused kernel memory: 1408K
[    1.227490] Run /init as init process
[    1.230699]   with arguments:
[    1.234509]     /init
[    1.237495]   with environment:
[    1.237839] mmcblk0boot1: mmc0:0001 H4G2a 4.00 MiB
[    1.239686]     HOME=/
[    1.239690]     TERM=linux
[    1.255437] mmcblk0rpmb: mmc0:0001 H4G2a 4.00 MiB, chardev (506:0)
[    1.890600] [init] EMMC: all 30 block devices read-only (getro=1)
[    6.952029] [init] B1: modem FAT mounted RO at /firmware, firmware path /firmware/image
[    6.984926] configfs-gadget.aurora gadget.0: HOST MAC <MAC>
[    6.984968] configfs-gadget.aurora gadget.0: MAC <MAC>
[    6.990669] l13: voltage operation not allowed
[    7.001434] [init] usb gadget bound to UDC 'ci_hdrc.0'
[    7.019158] [init] ncm ifname='usb0' dev_addr=<MAC> host_addr=<MAC>
[    7.035922] [init] network: usb0 172.16.42.1/24 up (static, no DHCP)
[   54.590956] [j3] CYCLE 1 begin: emmc w=0 s=0 mss=offline rmtfs=''
[   54.613903] [aurora-modem] === START (conf /tmp/j3.conf, apn 'internet.beeline.ru', ip-type ipv4)
[   54.703719] [aurora-modem] long-lived rmtfs started: pid 1273 (rmtfs -r -P -v, no -s)
[   54.709415] [aurora-modem] STATE OFF -> RMTFS_READY
[   54.717528] [aurora-modem] STATE RMTFS_READY -> MPSS_BOOTING
[   54.724923] remoteproc remoteproc0: powering up 4080000.remoteproc
[   54.725418] remoteproc remoteproc0: Booting fw image mba.mbn, size 234176
[   54.776689] qcom-q6v5-mss 4080000.remoteproc: MBA booted without debug policy, loading mpss
[   55.323189] remoteproc remoteproc0: remote processor 4080000.remoteproc is now up
[   55.390828] [aurora-modem] MSS running after 0.7s (sysfs start)
[   55.397268] [aurora-modem] STATE MPSS_BOOTING -> MPSS_RUNNING
[   55.902708] wwan wwan0: port wwan0at0 attached
[   55.903879] wwan wwan0: port wwan0at1 attached
[   56.084528] wwan wwan0: port wwan0qmi0 attached
[   56.243396] [aurora-modem] wwan0qmi0 + wwan0 present after 0.8s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post)
[   58.429511] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY
[   58.440452] [aurora-modem] DMS Mode: 'shutting-down' after 0.2s
[   58.494797] [aurora-modem] STATE QMI_READY -> SIM_READY
[   58.502452] [aurora-modem] UIM card present, USIM app ready after 0.1s
[   58.508140] [aurora-modem] SET: qmicli -p -d /dev/wwan0qmi0 --dms-set-operating-mode=online (retried only on DeviceNotReady, <= 30s)
[   61.751558] [aurora-modem] DMS online after 3.2s
[   61.756962] [aurora-modem] STATE SIM_READY -> RADIO_ONLINE
[   67.952517] [aurora-modem] registered: MCC: '250' MNC: '99' Description: 'Beeline' MCC: '250' MNC: '99' after 6.2s
[   67.958043] [aurora-modem] STATE RADIO_ONLINE -> REGISTERED
[   68.140973] udevd[1566]: starting version 3.2.14
[   68.143419] udevd[1566]: specified group 'tty' unknown
[   68.144917] udevd[1566]: specified group 'dialout' unknown
[   68.149759] udevd[1566]: specified group 'kmem' unknown
[   68.155226] udevd[1566]: specified group 'input' unknown
[   68.160358] udevd[1566]: specified group 'video' unknown
[   68.166020] udevd[1566]: specified group 'audio' unknown
[   68.171257] udevd[1566]: specified group 'lp' unknown
[   68.176504] udevd[1566]: specified group 'disk' unknown
[   68.181393] udevd[1566]: specified group 'cdrom' unknown
[   68.212945] udevd[1567]: starting eudev-3.2.14
[   68.591772] [aurora-modem] udevd started
[   68.620597] [aurora-modem] dbus started
[   68.635102] [aurora-modem] polkitd started
[   68.655704] [aurora-modem] ModemManager started pid 1611 (log mm-0.log)
[  117.749012] [aurora-modem] MM modem 0 detected after 49.1s
[  117.855464] [aurora-modem] STATE REGISTERED -> MM_READY
[  118.049728] [aurora-modem] MM state disabled after 0.2s
[  118.195556] [aurora-modem] mmcli -m 0 --simple-connect="apn=internet.beeline.ru,user=beeline,password=***,allowed-auth=pap,ip-type=ipv4"
[  129.158717] [aurora-modem] MM state connected, registration: home
[  129.235409] [aurora-modem] bearer 1: wwan0 10.45.164.101/30 gw 10.45.164.102 dns 10.10.22.3,194.186.191.1 mtu 1500
[  129.293918] [aurora-modem] STATE MM_READY -> BEARER_CONNECTED
[  129.306208] [aurora-modem] === START OK in 74.7s (wwan0 10.45.164.101/30, routes: 10.10.22.3 194.186.191.1 77.88.8.8 1.1.1.1 )
[  129.306987] [j3] CYCLE 1 start rc=0
[  135.151185] [j3] CYCLE 1 DATATEST PASS dns=1(ya=5.255.255.242 go=142.251.150.119) ping=3+3 http=code=302 https=[code=204 code=200 ](1) if=wwan0 addr=10.45.164.101 rc=0
[  135.213209] [aurora-modem] === STOP (from BEARER_CONNECTED: mss=running rmtfs=1273 mm=1611 bam=active)
[  135.854577] [aurora-modem] simple-disconnect rc=0 (bearers: 1 )
[  136.022889] [aurora-modem] no bearer connected
[  136.025346] [aurora-modem] MODEM_STOP_DISABLE=0: no MM disable
[  136.134637] [aurora-modem] wwan links down, addresses/routes removed
[  136.147272] [aurora-modem] STATE BEARER_CONNECTED -> WWAN_DOWN
[  138.835336] [aurora-modem] ModemManager stopped
[  138.885876] [aurora-modem] qmi-proxy stopped
[  138.890928] [aurora-modem] STATE WWAN_DOWN -> MM_STOPPED
[  138.917089] [aurora-modem] BAM-DMUX suspended after 0.0s
[  138.968153] wwan wwan0: port wwan0at0 disconnected
[  138.968704] wwan wwan0: port wwan0at1 disconnected
[  138.972497] wwan wwan0: port wwan0qmi0 disconnected
[  138.980237] remoteproc remoteproc0: stopped remote processor 4080000.remoteproc
[  138.984549] [aurora-modem] MSS stop via sysfs (long-lived rmtfs '1273' stays)
[  139.005716] [aurora-modem] MSS offline after 0.0s (rmtfs alive: yes)
[  139.010765] [aurora-modem] STATE MM_STOPPED -> MPSS_STOPPED
[  139.023074] [aurora-modem] STATE MPSS_STOPPED -> OFF
[  139.064854] [aurora-modem] === STOP done rc=0 in 3.9s (mss=offline rmtfs='1273' mm='' bam=suspended)
[  139.065659] [j3] CYCLE 1 stop rc=0
[  142.604507] [j3] CYCLE 1 RESULT reg=PASS lat=6.2s data=PASS checks=ok rmtfs=1273->1273 writes=0 bam=suspended
[  147.649712] [j3] CYCLE 2 begin: emmc w=0 s=0 mss=offline rmtfs='1273'
[  147.673428] [aurora-modem] === START (conf /tmp/j3.conf, apn 'internet.beeline.ru', ip-type ipv4)
[  147.761718] [aurora-modem] long-lived rmtfs reused: pid 1273
[  147.767201] [aurora-modem] STATE OFF -> RMTFS_READY
[  147.773023] [aurora-modem] STATE RMTFS_READY -> MPSS_BOOTING
[  147.781327] remoteproc remoteproc0: powering up 4080000.remoteproc
[  147.781767] remoteproc remoteproc0: Booting fw image mba.mbn, size 234176
[  147.828581] qcom-q6v5-mss 4080000.remoteproc: MBA booted without debug policy, loading mpss
[  148.373251] remoteproc remoteproc0: remote processor 4080000.remoteproc is now up
[  148.449810] [aurora-modem] MSS running after 0.7s (sysfs start)
[  148.455205] [aurora-modem] STATE MPSS_BOOTING -> MPSS_RUNNING
[  148.885929] wwan wwan0: port wwan0at0 attached
[  148.886784] wwan wwan0: port wwan0at1 attached
[  149.062194] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 0
[  149.062242] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 1
[  149.068052] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 2
[  149.074792] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 3
[  149.081525] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 4
[  149.088311] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 5
[  149.095307] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 6
[  149.101864] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 7
[  149.109180] wwan wwan0: port wwan0qmi0 attached
[  149.305478] [aurora-modem] wwan0qmi0 + wwan0 present after 0.8s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post)
[  151.487391] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY
[  151.498750] [aurora-modem] DMS Mode: 'shutting-down' after 0.2s
[  151.551577] [aurora-modem] STATE QMI_READY -> SIM_READY
[  151.560449] [aurora-modem] UIM card present, USIM app ready after 0.1s
[  151.565972] [aurora-modem] SET: qmicli -p -d /dev/wwan0qmi0 --dms-set-operating-mode=online (retried only on DeviceNotReady, <= 30s)
[  154.809064] [aurora-modem] DMS online after 3.2s
[  154.815701] [aurora-modem] STATE SIM_READY -> RADIO_ONLINE
[  156.917658] [aurora-modem] registered: MCC: '250' MNC: '99' Description: 'Beeline' MCC: '250' MNC: '99' after 2.1s
[  156.922856] [aurora-modem] STATE RADIO_ONLINE -> REGISTERED
[  157.147987] [aurora-modem] ModemManager started pid 3205 (log mm-1.log)
[  205.780707] [aurora-modem] MM modem 0 detected after 48.6s
[  205.888129] [aurora-modem] STATE REGISTERED -> MM_READY
[  206.079591] [aurora-modem] MM state disabled after 0.2s
[  206.226305] [aurora-modem] mmcli -m 0 --simple-connect="apn=internet.beeline.ru,user=beeline,password=***,allowed-auth=pap,ip-type=ipv4"
[  217.165643] [aurora-modem] MM state connected, registration: home
[  217.250220] [aurora-modem] bearer 1: wwan0 10.44.134.61/30 gw 10.44.134.62 dns 10.10.22.3,194.186.191.1 mtu 1500
[  217.316273] [aurora-modem] STATE MM_READY -> BEARER_CONNECTED
[  217.327103] [aurora-modem] === START OK in 69.6s (wwan0 10.44.134.61/30, routes: 10.10.22.3 194.186.191.1 77.88.8.8 1.1.1.1 )
[  217.327883] [j3] CYCLE 2 start rc=0
[  223.462285] [j3] CYCLE 2 DATATEST PASS dns=1(ya=5.255.255.242 go=142.251.150.119) ping=3+3 http=code=302 https=[code=204 code=200 ](1) if=wwan0 addr=10.44.134.61 rc=0
[  223.524421] [aurora-modem] === STOP (from BEARER_CONNECTED: mss=running rmtfs=1273 mm=3205 bam=active)
[  224.155270] [aurora-modem] simple-disconnect rc=0 (bearers: 1 )
[  224.332953] [aurora-modem] no bearer connected
[  224.336488] [aurora-modem] MODEM_STOP_DISABLE=0: no MM disable
[  224.421262] [aurora-modem] wwan links down, addresses/routes removed
[  224.429773] [aurora-modem] STATE BEARER_CONNECTED -> WWAN_DOWN
[  227.140727] [aurora-modem] ModemManager stopped
[  227.181266] [aurora-modem] qmi-proxy stopped
[  227.188338] [aurora-modem] STATE WWAN_DOWN -> MM_STOPPED
[  227.215438] [aurora-modem] BAM-DMUX suspended after 0.0s
[  227.265307] wwan wwan0: port wwan0at0 disconnected
[  227.265749] wwan wwan0: port wwan0at1 disconnected
[  227.269910] wwan wwan0: port wwan0qmi0 disconnected
[  227.276607] remoteproc remoteproc0: stopped remote processor 4080000.remoteproc
[  227.281511] [aurora-modem] MSS stop via sysfs (long-lived rmtfs '1273' stays)
[  227.302438] [aurora-modem] MSS offline after 0.0s (rmtfs alive: yes)
[  227.307234] [aurora-modem] STATE MM_STOPPED -> MPSS_STOPPED
[  227.319855] [aurora-modem] STATE MPSS_STOPPED -> OFF
[  227.362195] [aurora-modem] === STOP done rc=0 in 3.8s (mss=offline rmtfs='1273' mm='' bam=suspended)
[  227.363514] [j3] CYCLE 2 stop rc=0
[  230.896953] [j3] CYCLE 2 RESULT reg=PASS lat=2.1s data=PASS checks=ok rmtfs=1273->1273 writes=0 bam=suspended
[  235.943929] [j3] CYCLE 3 begin: emmc w=0 s=0 mss=offline rmtfs='1273'
[  235.966909] [aurora-modem] === START (conf /tmp/j3.conf, apn 'internet.beeline.ru', ip-type ipv4)
[  236.054735] [aurora-modem] long-lived rmtfs reused: pid 1273
[  236.061496] [aurora-modem] STATE OFF -> RMTFS_READY
[  236.067241] [aurora-modem] STATE RMTFS_READY -> MPSS_BOOTING
[  236.074649] remoteproc remoteproc0: powering up 4080000.remoteproc
[  236.075095] remoteproc remoteproc0: Booting fw image mba.mbn, size 234176
[  236.124439] qcom-q6v5-mss 4080000.remoteproc: MBA booted without debug policy, loading mpss
[  236.669257] remoteproc remoteproc0: remote processor 4080000.remoteproc is now up
[  236.738845] [aurora-modem] MSS running after 0.7s (sysfs start)
[  236.744509] [aurora-modem] STATE MPSS_BOOTING -> MPSS_RUNNING
[  237.181015] wwan wwan0: port wwan0at0 attached
[  237.181799] wwan wwan0: port wwan0at1 attached
[  237.371076] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 0
[  237.371125] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 1
[  237.376962] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 2
[  237.383937] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 3
[  237.390430] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 4
[  237.397187] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 5
[  237.403969] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 6
[  237.410723] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 7
[  237.417712] wwan wwan0: port wwan0qmi0 attached
[  237.590193] [aurora-modem] wwan0qmi0 + wwan0 present after 0.8s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post)
[  239.773946] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY
[  239.785130] [aurora-modem] DMS Mode: 'shutting-down' after 0.2s
[  239.835573] [aurora-modem] STATE QMI_READY -> SIM_READY
[  239.843012] [aurora-modem] UIM card present, USIM app ready after 0.1s
[  239.849720] [aurora-modem] SET: qmicli -p -d /dev/wwan0qmi0 --dms-set-operating-mode=online (retried only on DeviceNotReady, <= 30s)
[  243.086825] [aurora-modem] DMS online after 3.2s
[  243.092247] [aurora-modem] STATE SIM_READY -> RADIO_ONLINE
[  251.331608] [aurora-modem] registered: MCC: '250' MNC: '99' Description: 'Beeline' MCC: '250' MNC: '99' after 8.2s
[  251.338372] [aurora-modem] STATE RADIO_ONLINE -> REGISTERED
[  251.561797] [aurora-modem] ModemManager started pid 4830 (log mm-2.log)
[  300.162226] [aurora-modem] MM modem 0 detected after 48.6s
[  300.266697] [aurora-modem] STATE REGISTERED -> MM_READY
[  300.466281] [aurora-modem] MM state disabled after 0.2s
[  300.611776] [aurora-modem] mmcli -m 0 --simple-connect="apn=internet.beeline.ru,user=beeline,password=***,allowed-auth=pap,ip-type=ipv4"
[  311.170253] [aurora-modem] MM state connected, registration: home
[  311.245773] [aurora-modem] bearer 1: wwan0 10.83.20.58/30 gw 10.83.20.57 dns 10.10.22.3,194.186.191.1 mtu 1430
[  311.313724] [aurora-modem] STATE MM_READY -> BEARER_CONNECTED
[  311.324659] [aurora-modem] === START OK in 75.4s (wwan0 10.83.20.58/30, routes: 10.10.22.3 194.186.191.1 77.88.8.8 1.1.1.1 )
[  311.325752] [j3] CYCLE 3 start rc=0
[  317.071772] [j3] CYCLE 3 DATATEST PASS dns=1(ya=5.255.255.242 go=142.251.150.119) ping=3+3 http=code=302 https=[code=204 code=200 ](1) if=wwan0 addr=10.83.20.58 rc=0
[  317.133454] [aurora-modem] === STOP (from BEARER_CONNECTED: mss=running rmtfs=1273 mm=4830 bam=active)
[  317.773871] [aurora-modem] simple-disconnect rc=0 (bearers: 1 )
[  317.954325] [aurora-modem] no bearer connected
[  317.956868] [aurora-modem] MODEM_STOP_DISABLE=0: no MM disable
[  318.044963] [aurora-modem] wwan links down, addresses/routes removed
[  318.054313] [aurora-modem] STATE BEARER_CONNECTED -> WWAN_DOWN
[  320.749496] [aurora-modem] ModemManager stopped
[  320.789502] [aurora-modem] qmi-proxy stopped
[  320.794566] [aurora-modem] STATE WWAN_DOWN -> MM_STOPPED
[  320.820139] [aurora-modem] BAM-DMUX suspended after 0.0s
[  320.871045] wwan wwan0: port wwan0at0 disconnected
[  320.871590] wwan wwan0: port wwan0at1 disconnected
[  320.875351] wwan wwan0: port wwan0qmi0 disconnected
[  320.883434] remoteproc remoteproc0: stopped remote processor 4080000.remoteproc
[  320.887449] [aurora-modem] MSS stop via sysfs (long-lived rmtfs '1273' stays)
[  320.909440] [aurora-modem] MSS offline after 0.0s (rmtfs alive: yes)
[  320.914273] [aurora-modem] STATE MM_STOPPED -> MPSS_STOPPED
[  320.926394] [aurora-modem] STATE MPSS_STOPPED -> OFF
[  320.968407] [aurora-modem] === STOP done rc=0 in 3.8s (mss=offline rmtfs='1273' mm='' bam=suspended)
[  320.969108] [j3] CYCLE 3 stop rc=0
[  324.506878] [j3] CYCLE 3 RESULT reg=PASS lat=8.2s data=PASS checks=ok rmtfs=1273->1273 writes=0 bam=suspended
[  329.557040] [j3] CYCLE 4 begin: emmc w=0 s=0 mss=offline rmtfs='1273'
[  329.579559] [aurora-modem] === START (conf /tmp/j3.conf, apn 'internet.beeline.ru', ip-type ipv4)
[  329.664221] [aurora-modem] long-lived rmtfs reused: pid 1273
[  329.670102] [aurora-modem] STATE OFF -> RMTFS_READY
[  329.675622] [aurora-modem] STATE RMTFS_READY -> MPSS_BOOTING
[  329.683387] remoteproc remoteproc0: powering up 4080000.remoteproc
[  329.683813] remoteproc remoteproc0: Booting fw image mba.mbn, size 234176
[  329.728579] qcom-q6v5-mss 4080000.remoteproc: MBA booted without debug policy, loading mpss
[  330.269257] remoteproc remoteproc0: remote processor 4080000.remoteproc is now up
[  330.350978] [aurora-modem] MSS running after 0.7s (sysfs start)
[  330.355674] [aurora-modem] STATE MPSS_BOOTING -> MPSS_RUNNING
[  330.779014] wwan wwan0: port wwan0at0 attached
[  330.779740] wwan wwan0: port wwan0at1 attached
[  330.963507] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 0
[  330.963559] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 1
[  330.969386] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 2
[  330.976186] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 3
[  330.982878] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 4
[  330.989651] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 5
[  330.996426] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 6
[  331.003182] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 7
[  331.010562] wwan wwan0: port wwan0qmi0 attached
[  331.206016] [aurora-modem] wwan0qmi0 + wwan0 present after 0.8s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post)
[  333.386845] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY
[  333.397732] [aurora-modem] DMS Mode: 'shutting-down' after 0.2s
[  333.450396] [aurora-modem] STATE QMI_READY -> SIM_READY
[  333.457814] [aurora-modem] UIM card present, USIM app ready after 0.1s
[  333.463122] [aurora-modem] SET: qmicli -p -d /dev/wwan0qmi0 --dms-set-operating-mode=online (retried only on DeviceNotReady, <= 30s)
[  336.700753] [aurora-modem] DMS online after 3.2s
[  336.706490] [aurora-modem] STATE SIM_READY -> RADIO_ONLINE
[  517.028749] [aurora-modem] FAIL in RADIO_ONLINE: not registered+PS attached after 180s (	Registration state: 'registration-denied')
[  517.105373] [aurora-modem] MODEM_KEEP_ON_FAIL=1: leaving stack as is
[  517.107596] [j3] CYCLE 4 start rc=1
[  517.379304] [j3] CYCLE 4 start FAILED in RADIO_ONLINE - snapshot BEFORE stop
[  517.388015] [j-snap] begin
[  540.843798] [j-snap] end
[  540.844471] [j3] CYCLE 4 snapshot done
[  540.854205] [j3] CYCLE 4 DATATEST not-run rc=9
[  540.915394] [aurora-modem] === STOP (from RADIO_ONLINE: mss=running rmtfs=1273 mm= bam=suspended)
[  540.998220] [aurora-modem] wwan links down, addresses/routes removed
[  541.008655] [aurora-modem] STATE RADIO_ONLINE -> WWAN_DOWN
[  541.067301] [aurora-modem] qmi-proxy stopped
[  541.073253] [aurora-modem] STATE WWAN_DOWN -> MM_STOPPED
[  541.099611] [aurora-modem] BAM-DMUX suspended after 0.0s
[  541.150749] wwan wwan0: port wwan0at0 disconnected
[  541.151337] wwan wwan0: port wwan0at1 disconnected
[  541.155060] wwan wwan0: port wwan0qmi0 disconnected
[  541.163405] remoteproc remoteproc0: stopped remote processor 4080000.remoteproc
[  541.167415] [aurora-modem] MSS stop via sysfs (long-lived rmtfs '1273' stays)
[  541.188175] [aurora-modem] MSS offline after 0.0s (rmtfs alive: yes)
[  541.193296] [aurora-modem] STATE MM_STOPPED -> MPSS_STOPPED
[  541.205908] [aurora-modem] STATE MPSS_STOPPED -> OFF
[  541.247892] [aurora-modem] === STOP done rc=0 in 0.3s (mss=offline rmtfs='1273' mm='' bam=suspended)
[  541.248775] [j3] CYCLE 4 stop rc=0
[  544.789313] [j3] CYCLE 4 RESULT reg=FAIL lat=>180s data=not-run checks=ok rmtfs=1273->1273 writes=1 bam=suspended
[  544.794793] [j3] SERIES STOP: cycle 4 start/data failure
[  544.805733] [j3] series end: MSS offline -> rmtfs-stop
[  545.065791] [aurora-modem] long-lived rmtfs 1273 exited
[  545.077697] [j3] rmtfs-stop rc=0 rmtfs=''
[  548.389007] [j3] FINAL: nv unchanged, emmc w=0 s=0, mss=offline, rmtfs='', mm='', bam=suspended, wwan-up=, usb ok
[  548.764584] [j3] j3-RUN-END
[  966.191371] [k] CYCLE 1 begin: emmc w=0 s=0 mss=offline rmtfs=''
[  966.215755] [aurora-modem] === START (conf /tmp/k.conf, apn 'internet.beeline.ru', ip-type ipv4)
[  966.281505] remoteproc remoteproc0: powering up 4080000.remoteproc
[  966.281919] remoteproc remoteproc0: Booting fw image mba.mbn, size 234176
[  966.293743] [aurora-modem] STATE OFF -> RMTFS_READY
[  966.298434] [aurora-modem] STATE RMTFS_READY -> MPSS_BOOTING
[  966.332573] qcom-q6v5-mss 4080000.remoteproc: MBA booted without debug policy, loading mpss
[  966.876006] remoteproc remoteproc0: remote processor 4080000.remoteproc is now up
[  966.965635] [aurora-modem] MSS running after 0.7s
[  966.970892] [aurora-modem] STATE MPSS_BOOTING -> MPSS_RUNNING
[  967.443606] wwan wwan0: port wwan0at0 attached
[  967.446446] wwan wwan0: port wwan0at1 attached
[  967.634690] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 0
[  967.634749] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 1
[  967.640606] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 2
[  967.647444] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 3
[  967.654046] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 4
[  967.660805] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 5
[  967.667585] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 6
[  967.674345] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 7
[  967.681328] wwan wwan0: port wwan0qmi0 attached
[  967.814131] [aurora-modem] wwan0qmi0 + wwan0 present after 0.8s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post)
[  969.996607] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY
[  970.010978] [aurora-modem] DMS Mode: 'shutting-down' after 0.2s
[  970.068502] [aurora-modem] STATE QMI_READY -> SIM_READY
[  970.077691] [aurora-modem] UIM card present, USIM app ready after 0.1s
[  982.093648] [aurora-modem] pre-online hook rc=0
[  982.099656] [aurora-modem] SET: qmicli -p -d /dev/wwan0qmi0 --dms-set-operating-mode=online (retried only on DeviceNotReady, <= 30s)
[  982.194317] [aurora-modem] DMS online after 0.1s
[  982.201681] [aurora-modem] STATE SIM_READY -> RADIO_ONLINE
[ 1162.392550] [aurora-modem] FAIL in RADIO_ONLINE: not registered+PS attached after 180s (	Registration state: 'registration-denied')
[ 1162.465880] [aurora-modem] MODEM_KEEP_ON_FAIL=1: leaving stack as is
[ 1162.466646] [k] CYCLE 1 start rc=1
[ 1162.739363] [k] CYCLE 1 start FAILED in RADIO_ONLINE - snapshot BEFORE stop
[ 1182.095565] [j-snap] begin
[ 1182.453889] [j-snap] end
[ 1182.455578] [k] CYCLE 1 snapshot done
[ 1182.463476] [k] CYCLE 1 DATATEST not-run rc=9
[ 1182.531762] [aurora-modem] === STOP (from RADIO_ONLINE: mss=running rmtfs=8475 mm= bam=suspended)
[ 1182.621067] [aurora-modem] wwan links down, addresses/routes removed
[ 1182.631099] [aurora-modem] STATE RADIO_ONLINE -> WWAN_DOWN
[ 1182.691703] [aurora-modem] qmi-proxy stopped
[ 1182.698006] [aurora-modem] STATE WWAN_DOWN -> MM_STOPPED
[ 1182.727943] [aurora-modem] BAM-DMUX suspended after 0.0s
[ 1182.760398] wwan wwan0: port wwan0at0 disconnected
[ 1182.761332] [aurora-modem] SIGTERM rmtfs 8475 (rmtfs stops MSS, then exits)
[ 1182.761353] wwan wwan0: port wwan0at1 disconnected
[ 1182.771873] wwan wwan0: port wwan0qmi0 disconnected
[ 1182.782774] remoteproc remoteproc0: stopped remote processor 4080000.remoteproc
[ 1183.003244] [aurora-modem] MSS offline after 0.2s (rmtfs alive: no)
[ 1183.009909] [aurora-modem] STATE MM_STOPPED -> MPSS_STOPPED
[ 1183.024472] [aurora-modem] rmtfs exited after 0.3s
[ 1183.038627] [aurora-modem] STATE MPSS_STOPPED -> OFF
[ 1183.086196] [aurora-modem] === STOP done rc=0 in 0.6s (mss=offline rmtfs='' mm='' bam=suspended)
[ 1183.086958] [k] CYCLE 1 stop rc=0
[ 1186.624837] [k] CYCLE 1 AT: 2 CEREG lines, last '+CEREG: 0,2', ERROR x2
[ 1186.639844] [k] CYCLE 1 RESULT reg=FAIL lat=>180s data=not-run checks=ok rmtfs=8475-> writes=1 bam=suspended
[ 1186.644979] [k] SERIES STOP: cycle 1 start/data failure
[ 1189.963261] [k] FINAL: nv unchanged, emmc w=0 s=0, mss=offline, rmtfs='', mm='', bam=suspended, wwan-up=, usb ok
[ 1190.339102] [k] k-RUN-END
[ 2269.227312] [l] CYCLE 1 begin: emmc w=0 s=0 mss=offline rmtfs=''
[ 2269.252748] [aurora-modem] === START (conf /tmp/l.conf, apn 'internet.beeline.ru', ip-type ipv4)
[ 2269.325717] remoteproc remoteproc0: powering up 4080000.remoteproc
[ 2269.326119] remoteproc remoteproc0: Booting fw image mba.mbn, size 234176
[ 2269.337637] [aurora-modem] STATE OFF -> RMTFS_READY
[ 2269.342490] [aurora-modem] STATE RMTFS_READY -> MPSS_BOOTING
[ 2269.376580] qcom-q6v5-mss 4080000.remoteproc: MBA booted without debug policy, loading mpss
[ 2269.916219] remoteproc remoteproc0: remote processor 4080000.remoteproc is now up
[ 2270.014441] [aurora-modem] MSS running after 0.7s
[ 2270.021216] [aurora-modem] STATE MPSS_BOOTING -> MPSS_RUNNING
[ 2270.480669] wwan wwan0: port wwan0at0 attached
[ 2270.482684] wwan wwan0: port wwan0at1 attached
[ 2270.660153] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 0
[ 2270.660202] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 1
[ 2270.666012] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 2
[ 2270.672826] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 3
[ 2270.679506] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 4
[ 2270.686258] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 5
[ 2270.693029] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 6
[ 2270.699798] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 7
[ 2270.706936] wwan wwan0: port wwan0qmi0 attached
[ 2270.866851] [aurora-modem] wwan0qmi0 + wwan0 present after 0.8s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post)
[ 2273.047575] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY
[ 2273.057972] [aurora-modem] DMS Mode: 'shutting-down' after 0.2s
[ 2273.108545] [aurora-modem] STATE QMI_READY -> SIM_READY
[ 2273.115549] [aurora-modem] UIM card present, USIM app ready after 0.1s
[ 2273.120584] [aurora-modem] SET: qmicli -p -d /dev/wwan0qmi0 --dms-set-operating-mode=online (retried only on DeviceNotReady, <= 30s)
[ 2276.358169] [aurora-modem] DMS online after 3.2s
[ 2276.363339] [aurora-modem] STATE SIM_READY -> RADIO_ONLINE
[ 2456.676255] [aurora-modem] FAIL in RADIO_ONLINE: not registered+PS attached after 180s (	Registration state: 'not-registered-searching')
[ 2456.749644] [aurora-modem] MODEM_KEEP_ON_FAIL=1: leaving stack as is
[ 2456.750398] [l] CYCLE 1 start rc=1
[ 2457.019087] [l] CYCLE 1 start FAILED in RADIO_ONLINE - snapshot BEFORE stop
[ 2457.028023] [j-snap] begin
