[  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
