q/uzbek-plus
1
0
Fork 0
Code Issues Pull requests Projects Releases Packages Wiki Activity Actions
uzbek-plus/logs/b6j/m/dmesg-full.txt

764 lines
54 KiB
Text
Raw Blame History

This file contains invisible Unicode characters

This file contains invisible Unicode characters that are indistinguishable to humans but may be processed differently by a computer. If you think that this is intentional, you can safely ignore this warning. Use the Escape button to reveal them.

[ 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
[ 2457.356557] [j-snap] end
[ 2457.358940] [l] CYCLE 1 B6L passive observation 900 s (no cleanup, MSS/rmtfs/radio untouched)
[ 2457.382863] [l-obs] begin dur=900 period=15
[ 2477.770804] [l-obs] registered+attached at sample 2
[ 2499.143618] [l-obs] end RESULT=A-registered samples=3 dur=42s
[ 2499.150239] [l] CYCLE 1 observation: RESULT=A-registered samples=3 dur=42s
[ 2499.158937] [j-snap] begin
[ 2499.539580] [j-snap] end
[ 2499.540181] [l] CYCLE 1 snapshot done
[ 2499.548641] [l] CYCLE 1 DATATEST not-run rc=9
[ 2499.607340] [aurora-modem] === STOP (from RADIO_ONLINE: mss=running rmtfs=11714 mm= bam=suspended)
[ 2499.692967] [aurora-modem] wwan links down, addresses/routes removed
[ 2499.703030] [aurora-modem] STATE RADIO_ONLINE -> WWAN_DOWN
[ 2499.763652] [aurora-modem] qmi-proxy stopped
[ 2499.770752] [aurora-modem] STATE WWAN_DOWN -> MM_STOPPED
[ 2499.799636] [aurora-modem] BAM-DMUX suspended after 0.0s
[ 2499.832397] [aurora-modem] SIGTERM rmtfs 11714 (rmtfs stops MSS, then exits)
[ 2499.833830] wwan wwan0: port wwan0at0 disconnected
[ 2499.839070] wwan wwan0: port wwan0at1 disconnected
[ 2499.844173] wwan wwan0: port wwan0qmi0 disconnected
[ 2499.852757] remoteproc remoteproc0: stopped remote processor 4080000.remoteproc
[ 2500.071385] [aurora-modem] MSS offline after 0.2s (rmtfs alive: no)
[ 2500.078893] [aurora-modem] STATE MM_STOPPED -> MPSS_STOPPED
[ 2500.094116] [aurora-modem] rmtfs exited after 0.3s
[ 2500.110748] [aurora-modem] STATE MPSS_STOPPED -> OFF
[ 2500.160473] [aurora-modem] === STOP done rc=0 in 0.5s (mss=offline rmtfs='' mm='' bam=suspended)
[ 2500.161696] [l] CYCLE 1 stop rc=0
[ 2503.697116] [l] CYCLE 1 RESULT reg=FAIL lat=>180s data=not-run checks=ok rmtfs=11714-> writes=1 bam=suspended
[ 2503.702526] [l] SERIES STOP: cycle 1 start/data failure
[ 2507.043401] [l] FINAL: nv unchanged, emmc w=0 s=0, mss=offline, rmtfs='', mm='', bam=suspended, wwan-up=, usb ok
[ 2507.419108] [l] l-RUN-END
[ 2873.006336] [m] CYCLE 1 begin: emmc w=0 s=0 mss=offline rmtfs=''
[ 2873.030830] [aurora-modem] === START (conf /tmp/m.conf, apn 'internet.beeline.ru', ip-type ipv4)
[ 2873.103535] remoteproc remoteproc0: powering up 4080000.remoteproc
[ 2873.103912] remoteproc remoteproc0: Booting fw image mba.mbn, size 234176
[ 2873.116542] [aurora-modem] STATE OFF -> RMTFS_READY
[ 2873.121732] [aurora-modem] STATE RMTFS_READY -> MPSS_BOOTING
[ 2873.148604] qcom-q6v5-mss 4080000.remoteproc: MBA booted without debug policy, loading mpss
[ 2873.692189] remoteproc remoteproc0: remote processor 4080000.remoteproc is now up
[ 2873.792891] [aurora-modem] MSS running after 0.7s
[ 2873.798089] [aurora-modem] STATE MPSS_BOOTING -> MPSS_RUNNING
[ 2874.257581] wwan wwan0: port wwan0at0 attached
[ 2874.259369] wwan wwan0: port wwan0at1 attached
[ 2874.428267] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 0
[ 2874.428365] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 1
[ 2874.434137] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 2
[ 2874.441010] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 3
[ 2874.447624] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 4
[ 2874.454382] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 5
[ 2874.461152] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 6
[ 2874.467931] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 7
[ 2874.474923] wwan wwan0: port wwan0qmi0 attached
[ 2874.644603] [aurora-modem] wwan0qmi0 + wwan0 present after 0.8s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post)
[ 2876.825125] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY
[ 2876.836812] [aurora-modem] DMS Mode: 'shutting-down' after 0.2s
[ 2876.892057] [aurora-modem] STATE QMI_READY -> SIM_READY
[ 2876.901819] [aurora-modem] UIM card present, USIM app ready after 0.1s
[ 2876.910181] [aurora-modem] SET: qmicli -p -d /dev/wwan0qmi0 --dms-set-operating-mode=online (retried only on DeviceNotReady, <= 30s)
[ 2880.150699] [aurora-modem] DMS online after 3.2s
[ 2880.156626] [aurora-modem] STATE SIM_READY -> RADIO_ONLINE
[ 2880.476269] [aurora-modem] REG t+0.3s: not-registered-searching / PS detached / none (none, ) - pci= si=/ cl=/
[ 2883.753903] [aurora-modem] REG t+3.6s: not-registered-searching / PS detached / limited (limited-regional, camped) 250-99 pci=55 si=/156465929 cl=7661/156465929
[ 2887.040643] [aurora-modem] REG t+6.9s: registered / PS attached / available (none, ps) 250-99 pci=55 si=7661/156465929 cl=7661/156465929
[ 2887.053664] [aurora-modem] registered: MCC: '250' MNC: '99' Description: 'Beeline' MCC: '250' MNC: '99' after 6.9s
[ 2887.059346] [aurora-modem] STATE RADIO_ONLINE -> REGISTERED
[ 2887.279448] [aurora-modem] ModemManager started pid 15160 (log mm-3.log)
[ 2935.872772] [aurora-modem] MM modem 0 detected after 48.6s
[ 2935.971190] [aurora-modem] STATE REGISTERED -> MM_READY
[ 2936.163529] [aurora-modem] MM state disabled after 0.2s
[ 2936.309219] [aurora-modem] mmcli -m 0 --simple-connect="apn=internet.beeline.ru,user=beeline,password=***,allowed-auth=pap,ip-type=ipv4"
[ 2947.165690] [aurora-modem] MM state connected, registration: home
[ 2947.246107] [aurora-modem] bearer 1: wwan0 10.42.49.49/30 gw 10.42.49.50 dns 10.10.22.1,194.186.191.1 mtu 1500
[ 2947.304689] [aurora-modem] STATE MM_READY -> BEARER_CONNECTED
[ 2947.316634] [aurora-modem] === START OK in 74.3s (wwan0 10.42.49.49/30, routes: 10.10.22.1 194.186.191.1 77.88.8.8 1.1.1.1 )
[ 2947.318194] [m] CYCLE 1 start rc=0
[ 2953.097263] [m] 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.42.49.49 rc=0
[ 2953.160604] [aurora-modem] === STOP (from BEARER_CONNECTED: mss=running rmtfs=14565 mm=15160 bam=active)
[ 2953.803558] [aurora-modem] simple-disconnect rc=0 (bearers: 1 )
[ 2953.977846] [aurora-modem] no bearer connected
[ 2953.980188] [aurora-modem] MODEM_STOP_DISABLE=0: no MM disable
[ 2954.067909] [aurora-modem] wwan links down, addresses/routes removed
[ 2954.077320] [aurora-modem] STATE BEARER_CONNECTED -> WWAN_DOWN
[ 2956.757496] [aurora-modem] ModemManager stopped
[ 2956.806835] [aurora-modem] qmi-proxy stopped
[ 2956.812226] [aurora-modem] STATE WWAN_DOWN -> MM_STOPPED
[ 2956.836122] [aurora-modem] BAM-DMUX suspended after 0.0s
[ 2956.862807] wwan wwan0: port wwan0at0 disconnected
[ 2956.863256] wwan wwan0: port wwan0at1 disconnected
[ 2956.863315] [aurora-modem] SIGTERM rmtfs 14565 (rmtfs stops MSS, then exits)
[ 2956.870476] wwan wwan0: port wwan0qmi0 disconnected
[ 2956.884580] remoteproc remoteproc0: stopped remote processor 4080000.remoteproc
[ 2957.109648] [aurora-modem] MSS offline after 0.2s (rmtfs alive: no)
[ 2957.118950] [aurora-modem] STATE MM_STOPPED -> MPSS_STOPPED
[ 2957.134223] [aurora-modem] rmtfs exited after 0.3s
[ 2957.151146] [aurora-modem] STATE MPSS_STOPPED -> OFF
[ 2957.203791] [aurora-modem] === STOP done rc=0 in 4.0s (mss=offline rmtfs='' mm='' bam=suspended)
[ 2957.204796] [m] CYCLE 1 stop rc=0
[ 2960.774621] [m] CYCLE 1 RESULT reg=PASS lat=6.9s data=PASS checks=ok rmtfs=14565-> writes=0 bam=suspended
[ 2965.822458] [m] CYCLE 2 begin: emmc w=0 s=0 mss=offline rmtfs=''
[ 2965.846637] [aurora-modem] === START (conf /tmp/m.conf, apn 'internet.beeline.ru', ip-type ipv4)
[ 2965.919249] remoteproc remoteproc0: powering up 4080000.remoteproc
[ 2965.919671] remoteproc remoteproc0: Booting fw image mba.mbn, size 234176
[ 2965.931205] [aurora-modem] STATE OFF -> RMTFS_READY
[ 2965.935883] [aurora-modem] STATE RMTFS_READY -> MPSS_BOOTING
[ 2965.964631] qcom-q6v5-mss 4080000.remoteproc: MBA booted without debug policy, loading mpss
[ 2966.505179] remoteproc remoteproc0: remote processor 4080000.remoteproc is now up
[ 2966.613925] [aurora-modem] MSS running after 0.7s
[ 2966.620839] [aurora-modem] STATE MPSS_BOOTING -> MPSS_RUNNING
[ 2967.066322] wwan wwan0: port wwan0at0 attached
[ 2967.068149] wwan wwan0: port wwan0at1 attached
[ 2967.254771] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 0
[ 2967.254825] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 1
[ 2967.260661] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 2
[ 2967.267637] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 3
[ 2967.274125] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 4
[ 2967.280888] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 5
[ 2967.287651] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 6
[ 2967.294440] bam-dmux 4080000.remoteproc:bam-dmux: Channel already open: 7
[ 2967.301709] wwan wwan0: port wwan0qmi0 attached
[ 2967.468821] [aurora-modem] wwan0qmi0 + wwan0 present after 0.8s; +2 s (JZ02 rpmsg_wwan_ctrl port-race guard, proven start_post)
[ 2969.654448] [aurora-modem] STATE MPSS_RUNNING -> QMI_READY
[ 2969.665223] [aurora-modem] DMS Mode: 'shutting-down' after 0.2s
[ 2969.715051] [aurora-modem] STATE QMI_READY -> SIM_READY
[ 2969.722243] [aurora-modem] UIM card present, USIM app ready after 0.1s
[ 2969.727500] [aurora-modem] SET: qmicli -p -d /dev/wwan0qmi0 --dms-set-operating-mode=online (retried only on DeviceNotReady, <= 30s)
[ 2972.957860] [aurora-modem] DMS online after 3.2s
[ 2972.963400] [aurora-modem] STATE SIM_READY -> RADIO_ONLINE
[ 2973.283230] [aurora-modem] REG t+0.3s: not-registered-searching / PS detached / none (none, ) - pci= si=/ cl=/
[ 2976.562880] [aurora-modem] REG t+3.6s: not-registered-searching / PS detached / limited (limited-regional, camped) 250-99 pci=55 si=/156465929 cl=7661/156465929
[ 3017.865586] [aurora-modem] REG t+44.9s: registration-denied / PS detached / limited (none, none) 250-01 pci=47 si=41028/135972149 cl=41028/135972149
[ 3040.238480] [aurora-modem] REG t+67.2s: not-registered-searching / PS detached / limited (limited-regional, camped) 250-99 pci=55 si=41028/156465929 cl=7661/156465929
[ 3132.321564] [aurora-modem] REG t+159.3s: not-registered-searching / PS detached / limited (none, none) 250-20 pci=64 si=26366/156182544 cl=65535/4294967295
[ 3145.089683] [aurora-modem] REG t+172.1s: not-registered-searching / PS detached / limited (limited-regional, camped) 250-99 pci=55 si=26366/156465929 cl=7661/156465929
[ 3240.323649] [aurora-modem] REG t+267.3s: not-registered-searching / PS detached / limited (none, none) 250-99 pci=149 si=7661/157614181 cl=7661/157614181
[ 3243.679583] [aurora-modem] REG t+270.7s: not-registered-searching / PS detached / limited (none, none) 250-99 pci=147 si=7661/157614183 cl=7661/157614183
[ 3262.877722] [aurora-modem] REG t+289.9s: not-registered-searching / PS detached / limited (none, none) 250-99 pci=55 si=7661/156465929 cl=7661/156465929
[ 3294.776835] [aurora-modem] REG t+321.8s: not-registered-searching / PS detached / limited (none, none) 250-99 pci=187 si=7661/158607205 cl=7661/158607205
[ 3317.105899] [aurora-modem] REG t+344.1s: not-registered-searching / PS detached / limited (none, none) 250-99 pci=149 si=7661/157614181 cl=7661/157614181
[ 3333.149751] [aurora-modem] REG t+360.2s: not-registered-searching / PS detached / limited (none, none) 250-99 pci=55 si=7661/156465929 cl=7661/156465929
[ 3333.166730] [aurora-modem] FAIL in RADIO_ONLINE: REGISTRATION_TIMEOUT after 360s (not-registered-searching, PS detached)
[ 3333.245247] [aurora-modem] MODEM_KEEP_ON_FAIL=1: leaving stack as is
[ 3333.246123] [m] CYCLE 2 start rc=1
[ 3333.522336] [m] CYCLE 2 start FAILED in RADIO_ONLINE - snapshot BEFORE stop
[ 3333.530098] [j-snap] begin
[ 3356.999929] [j-snap] end
[ 3357.000667] [m] CYCLE 2 snapshot done
[ 3357.009303] [m] CYCLE 2 DATATEST not-run rc=9
[ 3357.070818] [aurora-modem] === STOP (from RADIO_ONLINE: mss=running rmtfs=16520 mm= bam=suspended)
[ 3357.152769] [aurora-modem] wwan links down, addresses/routes removed
[ 3357.163079] [aurora-modem] STATE RADIO_ONLINE -> WWAN_DOWN
[ 3357.219007] [aurora-modem] qmi-proxy stopped
[ 3357.225241] [aurora-modem] STATE WWAN_DOWN -> MM_STOPPED
[ 3357.252594] [aurora-modem] BAM-DMUX suspended after 0.0s
[ 3357.285086] [aurora-modem] SIGTERM rmtfs 16520 (rmtfs stops MSS, then exits)
[ 3357.285401] wwan wwan0: port wwan0at0 disconnected
[ 3357.291854] wwan wwan0: port wwan0at1 disconnected
[ 3357.296525] wwan wwan0: port wwan0qmi0 disconnected
[ 3357.305816] remoteproc remoteproc0: stopped remote processor 4080000.remoteproc
[ 3357.523980] [aurora-modem] MSS offline after 0.2s (rmtfs alive: no)
[ 3357.531004] [aurora-modem] STATE MM_STOPPED -> MPSS_STOPPED
[ 3357.546229] [aurora-modem] rmtfs exited after 0.3s
[ 3357.560090] [aurora-modem] STATE MPSS_STOPPED -> OFF
[ 3357.609003] [aurora-modem] === STOP done rc=0 in 0.5s (mss=offline rmtfs='' mm='' bam=suspended)
[ 3357.609882] [m] CYCLE 2 stop rc=0
[ 3361.214656] [m] CYCLE 2 RESULT reg=FAIL lat=>180s data=not-run checks=ok rmtfs=16520-> writes=1 bam=suspended
[ 3361.219927] [m] SERIES STOP: cycle 2 start/data failure
[ 3364.528944] [m] FINAL: nv unchanged, emmc w=0 s=0, mss=offline, rmtfs='', mm='', bam=suspended, wwan-up=, usb ok
Reference in a new issue View git blame Copy permalink