TRB500 loses connection and is denied

Figure · March 4, 2024, 8:31pm

Hi, the 5G connection on my TRB500 drops quite randomly and is unable to connect without a full reboot.

Two weeks ago I disabled the Auto APN and set the correct custom APN which reduced the connection losses to perhaps once a week. However, I’m hoping to resolve this problem entirely.

Can’t find any help from logs, this is from mobifd.log:

[Mon Mar  4 21:52:47] [gsm.modem0] Network registration status received: "3"(Denied)
[Mon Mar  4 21:52:47] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Mon Mar  4 21:52:47] Touch: /tmp/vuci/denied_event_3-1
[Mon Mar  4 21:52:47] [gsm.modem0] Network registration status received: "2"(Searching)
[Mon Mar  4 21:52:47] [gsm.modem0] Mobile state: "Mobile connection setup is complete"

Current firmware: TRB500_R_00.07.06.3

ruter-boy · March 4, 2024, 10:45pm

I have similar issues, did you manage to get hold of support?
I’m starting to think that the Quectel modem inside is no-good… qualcomm based seems much more robust and also provides higher peak speeds over 5G

Daumantas · March 5, 2024, 5:20am

Hello,

Usually denied messages are initiated by the network, rather than the device itself. If your internal modem firmware is older than RG501QEUAAR12A08M4G (e.g. RG501QEUAAR12A07M4G), try performing the following procedure and check if it helps:

Additionally, perhaps the SIM in question was tried on a different device? Have any of the mobile settings (other than the APN) been changed on the device? Perhaps band locking is implemented?
How long have you tried waiting after the connection gets denied to see if it recovers?
As a temporary solution, I would suggest configuring ping reboot by navigating to System → Maintenance → Auto Reboot. Select the action as “modem reboot”, which will only reboot the internal modem instead of the entire device. This will help to minimize the disruption as much as possible. If this action does not help, try changing the auto reboot action to “Reboot”.

Best regards,

Figure · March 5, 2024, 7:28pm

Thanks for the quick reply.

My modem firmware is RG501QEUAAR12A08M4G_04.200.04.200. This is a fairly new device, got it at the beginning of January.

I’ve had this SIM for 4 years, not sure if it has worked flawlessly though the previous router was some Chinese brand that had other issues I couldn’t diagnose due to closed system. But anyway it always managed to get the connection up without rebooting.

I have not altered any settings other than the APN, so other than that it runs on factory settings. No band locking. The only config I’ve made is to set a Reboot Scheduler at 4 AM every day.

I’ve waited maybe five minutes max for the connection to recover, but no luck.

I activated the ping reboot now, thanks. Is “modem reboot” different than the “Restart Connection” on Status > Network > Mobile (that doesn’t work when the connection gets denied)?

Btw here are all the error codes from system.log if they are of any help:

[    0.177504] sd_vdd: regulator get failed, ret=-517
[    0.600417] scm_call failed: func id 0x2000c10, ret: -1, syscall returns: 0x0, 0x0, 0x0
[    0.604381] ipa ipa3_tz_unlock_reg:6958 scm call SCM_SVC_MP failed: -5
[    0.612251] ipa ipa3_pre_init:7215 Failed to unlock memory region using TZ
[    0.758550] pci-msm: probe of 1c00000.qcom,pcie failed with error -1
[    0.964423] qcom_smp2p: probe of 17811008.qcom,smp2p-modem failed with error -22
[    1.062045] diag: diag_pcie_register: failed registering pcie channels
[   14.396455] subsys-pil-tz 4080000.qcom,mss: Subsystem error monitoring/handling services are up
[   37.029510] ipa-wan __ipa_wwan_close:1184 [rmnet_ipa0]: ipa3_deregister_intf failed -22

system · March 19, 2024, 8:32pm

This topic was automatically closed after 15 days. New replies are no longer allowed.

Daumantas · March 21, 2024, 6:11am

Hello,

Apologies for a late response.
As these errors are seen during the boot, it’s unlikely that they are causing any issues later on.
Ping reboot has multiple actions available that might help here:

Reboot - full device reboot, same as pressing the Reboot button in the WebUI;
Modem reboot - only reboots the internal modem. Is performed much faster, but it may not be able to resolve all of the issues;
Restart connection - simply restarts the data connection (since the device is not even registered to the carrier, this option will not help).

At least for now, I would suggest to use Restart modem option, as it will be the least intrusive and should help.
Would it also be possible to get logread logs from the disconnection event? In particular, the part after the disconnection occurs is the most important, as it may help us understand why the modem does not recover on it’s own.

Best regards,

Figure · March 22, 2024, 2:45pm

Hi, thanks for getting back.

Good news is that since I setup the ping reboot for modem according to your instructions, the TRB500 has managed to recover from the disconnect.

I have few troubleshoot logs from previous disconnects:

The system.log just gets filled with queries to various IPs, these are the first rows I get in the troubleshoot logs, and it continues like this (queries, forwards, queries, forwards…).

Mon Mar  4 21:53:48 2024 daemon.info dnsmasq[3830]: 10502 192.168.2.152/5678 forwarded cloud.mikrotik.com to 62.241.198.246
Mon Mar  4 21:53:48 2024 kern.notice Password auth succeeded for admin on HTTP from 192.168.2.152
Mon Mar  4 21:53:48 2024 daemon.err uhttpd[2086]: vuci: accepted login for admin from 192.168.2.152
Mon Mar  4 21:53:49 2024 daemon.info dnsmasq[3830]: 10503 192.168.2.152/37916 query[A] www.google.com from 192.168.2.152
Mon Mar  4 21:53:49 2024 daemon.info dnsmasq[3830]: 10503 192.168.2.152/37916 forwarded www.google.com to 62.241.198.245
Mon Mar  4 21:53:49 2024 daemon.info dnsmasq[3830]: 10503 192.168.2.152/37916 forwarded www.google.com to 62.241.198.246

log.db doesn’t have anything around that timestamp it seems?

gsm_3-1xxx logs in tmp have a lot going on, do you want those? Safe to put in here?

I also looked if there would be denied_event_3-1 in /tmp/vuci/ (mobifd says that is touched), but the folder is empty.

admin > Event log didn’t have any messages from the disconnect before, only now after I’ve configured the ping reboot.

What log should I look, what are you interested in?

Figure · March 23, 2024, 8:11pm

So lost connection today, Ping Reboot restarted the modem after 2 unsuccessful tries at 21:33:10, and connection came back at 21:33:45. The ping reboot is set for 1 minute interval with 10 sec time out.

Here’s mobifd.log from that timeframe, ends at 21:33:35:

[Sat Mar 23 21:12:56] [gsm.modem0] Network registration status received: "1"(Registered, home)
[Sat Mar 23 21:12:56] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Sat Mar 23 21:12:56] Touch: /tmp/vuci/reload_routes
[Sat Mar 23 21:32:01] [gsm.modem0] Network registration status received: "3"(Denied)
[Sat Mar 23 21:32:01] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Sat Mar 23 21:32:01] Touch: /tmp/vuci/denied_event_3-1
[Sat Mar 23 21:32:03] [gsm.modem0] Network registration status received: "2"(Searching)
[Sat Mar 23 21:32:03] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Sat Mar 23 21:33:10] [gsm.modem0] Removing modem instance with UBUS ID: 407312421
[Sat Mar 23 21:33:10] Checking network.interface.'mob1s1a1' ubus ID
[Sat Mar 23 21:33:10] Interface 'mob1s1a1' going down
[Sat Mar 23 21:33:10] [gsm.modem0] Cancelling uloop timeouts
[Sat Mar 23 21:33:24] [gsm.modem0] Setting up modem instance
[Sat Mar 23 21:33:24] [gsm.modem0] Active SIM: "1"
[Sat Mar 23 21:33:24] [gsm.modem0] Created new modem instance
[Sat Mar 23 21:33:24] [gsm.modem0] Registering unsolicited listener
[Sat Mar 23 21:33:25] [gsm.modem0] Modem features were parsed successfully
[Sat Mar 23 21:33:25] [gsm.modem0] Starting modem settings init
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "mb_setup_sim_soft_recovery:884" (Called out method not supported), gsmd_method_id: "150"
[Sat Mar 23 21:33:25] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Sat Mar 23 21:33:25] [gsm.modem0] SIM is ready
[Sat Mar 23 21:33:25] [gsm.modem0] Init: functionality
[Sat Mar 23 21:33:25] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Sat Mar 23 21:33:25] [gsm.modem0] Init: disable_gea
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "generic_disable_gea1_gea2_cb:156" (Called out method not supported), gsmd_method_id: "169"
[Sat Mar 23 21:33:25] [gsm.modem0] Init: dhcp filter
[Sat Mar 23 21:33:25] [gsm.modem0] Init: ue usage
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "generic_ue_usage_cb:97" (Called out method not supported), gsmd_method_id: "83"
[Sat Mar 23 21:33:25] [gsm.modem0] Init: mbn_autosel
[Sat Mar 23 21:33:25] [gsm.modem0] Init: auto_timezone
[Sat Mar 23 21:33:25] [gsm.modem0] Init: mbn
[Sat Mar 23 21:33:25] [gsm.modem0] MBN: "ROW_Commercial", SELECTED: "1", ACTIVE: "1"
[Sat Mar 23 21:33:25] [gsm.modem0] Init: usbnet
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "generic_usbnet_set_cb:330" (Called out method not supported), gsmd_method_id: "79"
[Sat Mar 23 21:33:25] [gsm.modem0] Init: nat settings
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "generic_nat_set_cb:358" (Called out method not supported), gsmd_method_id: "81"
[Sat Mar 23 21:33:25] [gsm.modem0] Init: auth bit
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "generic_set_auth_bit:386" (Called out method not supported), gsmd_method_id: "151"
[Sat Mar 23 21:33:25] [gsm.modem0] Init: mgcfg
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "generic_mgcfg_cb:443" (Called out method not supported), gsmd_method_id: "105"
[Sat Mar 23 21:33:25] [gsm.modem0] Init: mtu
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "generic_set_mtu_info:414" (Called out method not supported), gsmd_method_id: "102"
[Sat Mar 23 21:33:25] [gsm.modem0] Init: msg fmt
[Sat Mar 23 21:33:25] [gsm.modem0] Init: urc port
[Sat Mar 23 21:33:25] [gsm.modem0] Init: signal query
[Sat Mar 23 21:33:25] [gsm.modem0] Init: sim state
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "generic_sim_state_cfg_cb:560" (Called out method not supported)
[Sat Mar 23 21:33:25] [gsm.modem0] Init: sms mode
[Sat Mar 23 21:33:25] [gsm.modem0] Init: roaming_svc
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "generic_set_roaming_svc:736" (Called out method not supported), gsmd_method_id: "36"
[Sat Mar 23 21:33:25] [gsm.modem0] Init: m2m_func
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "generic_set_m2m_state:776" (Called out method not supported), gsmd_method_id: "96"
[Sat Mar 23 21:33:25] [gsm.modem0] Init: urc_cause
[Sat Mar 23 21:33:25] [gsm.modem0] Init: modem_func
[Sat Mar 23 21:33:25] [gsm.modem0] Modem is in dynamic operation mode
[Sat Mar 23 21:33:25] [gsm.modem0] Init: sms_storage_check
[Sat Mar 23 21:33:25] [gsm.modem0] Init: set_urc_ind_cfg
[Sat Mar 23 21:33:25] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Sat Mar 23 21:33:25] [gsm.modem0] Init: functionality
[Sat Mar 23 21:33:25] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Sat Mar 23 21:33:25] [gsm.modem0] Init: net reg
[Sat Mar 23 21:33:25] [gsm.modem0] Init: net greg
[Sat Mar 23 21:33:25] [gsm.modem0] Init: net ereg
[Sat Mar 23 21:33:25] [gsm.modem0] Init: net c5greg
[Sat Mar 23 21:33:25] [gsm.modem0] Init: clip mode
[Sat Mar 23 21:33:25] [gsm.modem0] Init: CRC mode
[Sat Mar 23 21:33:25] [gsm.modem0] Init: CNMI mode
[Sat Mar 23 21:33:25] [gsm.modem0] Scheduling init process 'CNMI mode' for "200ms"
[Sat Mar 23 21:33:25] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Sat Mar 23 21:33:25] [gsm.modem0] Init process was sheduled, last init entry executed 'CNMI mode'
[Sat Mar 23 21:33:25] [gsm.modem0] Init: net mode
[Sat Mar 23 21:33:25] [gsm.modem0] Will use "auto" service mode.
[Sat Mar 23 21:33:25] [gsm.modem0] Modem is in correct service mode "Auto"
[Sat Mar 23 21:33:25] [gsm.modem0] GSM error in "set_network_category:281" (Called out method not supported), gsmd_method_id: "116"
[Sat Mar 23 21:33:25] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Sat Mar 23 21:33:25] [gsm.modem0] Scheduling init process 'net mode' for "1500ms"
[Sat Mar 23 21:33:27] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Sat Mar 23 21:33:27] [gsm.modem0] Init process was sheduled, last init entry executed 'net mode'
[Sat Mar 23 21:33:27] [gsm.modem0] Init: default bands
[Sat Mar 23 21:33:27] [gsm.modem0] Init: SMS storage
[Sat Mar 23 21:33:27] [gsm.modem0] SMS storage: "sm"
[Sat Mar 23 21:33:27] [gsm.modem0] Modem settings init completed
[Sat Mar 23 21:33:27] [gsm.modem0] Starting connection setup
[Sat Mar 23 21:33:27] Touch: /tmp/vuci/reload_routes
[Sat Mar 23 21:33:27] [gsm.modem0] Getting APN list
[Sat Mar 23 21:33:27] Registering event handler 'mob1s1a1'
[Sat Mar 23 21:33:27] [gsm.modem0] APN "IMS" is already set for PDP 2
[Sat Mar 23 21:33:27] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Sat Mar 23 21:33:27] [gsm.modem0] Preparing connection for 'mob1s1a1' interface
[Sat Mar 23 21:33:27] [gsm.modem0] Skipping first APN to not overwrite APN for 'mob1s1a1' interface
[Sat Mar 23 21:33:27] [gsm.modem0] APN "data.moimobile.fi" is already set for PDP 1
[Sat Mar 23 21:33:27] [gsm.modem0] Attempting to establish connection to operator with "15s" of timeout
[Sat Mar 23 21:33:27] [gsm.modem0] timeout: "15" get_timeout: "15"
[Sat Mar 23 21:33:27] [gsm.modem0] Modem is already in COPS: "1"(Auto) state
[Sat Mar 23 21:33:27] [gsm.modem0] Testing connection to "" operator
[Sat Mar 23 21:33:27] [gsm.modem0] Connected to operator "DNA MOI"
[Sat Mar 23 21:33:27] [gsm.modem0] Will use "auto" service mode.
[Sat Mar 23 21:33:27] [gsm.modem0] Modem is in correct service mode "Auto"
[Sat Mar 23 21:33:27] [gsm.modem0] GSM error in "set_network_category:281" (Called out method not supported), gsmd_method_id: "116"
[Sat Mar 23 21:33:27] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Sat Mar 23 21:33:27] [gsm.modem0] MBN: "ROW_Commercial", SELECTED: "1", ACTIVE: "1"
[Sat Mar 23 21:33:27] [gsm.modem0] MBN: "ROW_Commercial" saved to 'simcard' config
[Sat Mar 23 21:33:27] [gsm.modem0] -CFUN- Functionality: "Full"
[Sat Mar 23 21:33:27] [gsm.modem0] -COPS- Mode: "Auto", operator: "DNA MOI"
[Sat Mar 23 21:33:27] [gsm.modem0] -CREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "213", cell ID: "2848023", technology: "E-UTRAN-NR"
[Sat Mar 23 21:33:27] [gsm.modem0] -CGREG- Mode: "Enabled (with location information)", status: "Not registered", LAC: "", cell ID: "", technology: "Unknown"
[Sat Mar 23 21:33:27] [gsm.modem0] -CEREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "213", cell ID: "2848023", technology: "E-UTRAN-NR"
[Sat Mar 23 21:33:27] [gsm.modem0] -C5GREG- Mode: "Enabled (with location information)", status: "Not registered", LAC: "", cell ID: "", technology: "Unknown"
[Sat Mar 23 21:33:28] [gsm.modem0] IP address on PDP context "1": "10.88.236.142"
[Sat Mar 23 21:33:28] [gsm.modem0] Checking IP address on PDP context "1"
[Sat Mar 23 21:33:28] [gsm.modem0] PDP type on PDP context "1" is correct
[Sat Mar 23 21:33:28] Saving APN information in "network" config for 'mob1s1a1' interface
[Sat Mar 23 21:33:28] Reloading network

[Sat Mar 23 21:33:28] Checking network.interface.'mob1s1a1' ubus ID
[Sat Mar 23 21:33:28] Interface 'mob1s1a1' is going up
[Sat Mar 23 21:33:28] Setting IFUP timeout for 'mob1s1a1' of "120s"
[Sat Mar 23 21:33:35] Network event received "ifup" for 'mob1s1a1_4'
[Sat Mar 23 21:33:35] Auto APN is disabled for 'mob1s1a1'. Skipping Ping To DNS functionality
[Sat Mar 23 21:33:35] [gsm.modem0] VoLTE: "Inactive"
[Sat Mar 23 21:33:35] Connection setup completed for 'mob1s1a1'

Also, here’s some events from system.log, I removed all the basic traffic events (query, forward, reply):

--- cut ---
Sat Mar 23 21:23:00 2024 cron.err crond[3698]: USER root pid 22473 cmd /usr/sbin/ping_reboot.sh cfg02c21d
Sat Mar 23 21:23:00 2024 user.notice ping_reboot.sh: Ping successful.
Sat Mar 23 21:24:00 2024 cron.err crond[3698]: USER root pid 22581 cmd /usr/sbin/ping_reboot.sh cfg02c21d
Sat Mar 23 21:24:00 2024 user.notice ping_reboot.sh: Ping successful.
Sat Mar 23 21:24:56 2024 daemon.info dnsmasq[3805]: 10050 192.168.2.152/5678 query[A] cloud.mikrotik.com from 192.168.2.152
Sat Mar 23 21:24:56 2024 daemon.info dnsmasq[3805]: 10050 192.168.2.152/5678 cached cloud.mikrotik.com is 159.148.147.229
Sat Mar 23 21:25:00 2024 cron.err crond[3698]: USER root pid 22693 cmd /usr/sbin/ping_reboot.sh cfg02c21d
Sat Mar 23 21:25:00 2024 user.notice ping_reboot.sh: Ping successful.
Sat Mar 23 21:26:00 2024 cron.err crond[3698]: USER root pid 22803 cmd /usr/sbin/ping_reboot.sh cfg02c21d
Sat Mar 23 21:26:00 2024 user.notice ping_reboot.sh: Ping successful.
Sat Mar 23 21:26:56 2024 daemon.info dnsmasq[3805]: 10051 192.168.2.152/5678 query[A] cloud.mikrotik.com from 192.168.2.152
Sat Mar 23 21:26:56 2024 daemon.info dnsmasq[3805]: 10051 192.168.2.152/5678 cached cloud.mikrotik.com is 159.148.147.229
Sat Mar 23 21:27:00 2024 cron.err crond[3698]: USER root pid 22911 cmd /usr/sbin/ping_reboot.sh cfg02c21d
Sat Mar 23 21:27:00 2024 user.notice ping_reboot.sh: Ping successful.
Sat Mar 23 21:28:00 2024 cron.err crond[3698]: USER root pid 23021 cmd /usr/sbin/ping_reboot.sh cfg02c21d
Sat Mar 23 21:28:00 2024 user.notice ping_reboot.sh: Ping successful.
Sat Mar 23 21:28:56 2024 daemon.info dnsmasq[3805]: 10052 192.168.2.152/5678 query[A] cloud.mikrotik.com from 192.168.2.152
Sat Mar 23 21:28:56 2024 daemon.info dnsmasq[3805]: 10052 192.168.2.152/5678 cached cloud.mikrotik.com is 159.148.147.229
Sat Mar 23 21:29:00 2024 cron.err crond[3698]: USER root pid 23129 cmd /usr/sbin/ping_reboot.sh cfg02c21d
Sat Mar 23 21:29:00 2024 user.notice ping_reboot.sh: Ping successful.
Sat Mar 23 21:30:00 2024 cron.err crond[3698]: USER root pid 23239 cmd /usr/sbin/ping_reboot.sh cfg02c21d
Sat Mar 23 21:30:00 2024 user.notice ping_reboot.sh: Ping successful.
Sat Mar 23 21:30:56 2024 daemon.info dnsmasq[3805]: 10053 192.168.2.152/5678 query[A] cloud.mikrotik.com from 192.168.2.152
Sat Mar 23 21:30:56 2024 daemon.info dnsmasq[3805]: 10053 192.168.2.152/5678 cached cloud.mikrotik.com is 159.148.147.229
Sat Mar 23 21:31:00 2024 cron.err crond[3698]: USER root pid 23349 cmd /usr/sbin/ping_reboot.sh cfg02c21d
Sat Mar 23 21:31:00 2024 user.notice ping_reboot.sh: Ping successful.
--- cut ---
no replies anymore after 21:31:00
--- cut ---
Sat Mar 23 21:32:00 2024 cron.err crond[3698]: USER root pid 23457 cmd /usr/sbin/ping_reboot.sh cfg02c21d
--- cut ---
Sat Mar 23 21:32:10 2024 user.notice ping_reboot.sh: Host 8.8.8.8 unreachable
Sat Mar 23 21:32:10 2024 user.notice ping_reboot.sh: 1 min. until next ping retry
--- cut ---
Sat Mar 23 21:33:00 2024 cron.err crond[3698]: USER root pid 23564 cmd /usr/sbin/ping_reboot.sh cfg02c21d
--- cut ---
Sat Mar 23 21:33:10 2024 user.notice ping_reboot.sh: Host 8.8.8.8 unreachable
Sat Mar 23 21:33:10 2024 user.notice ping_reboot.sh: Restarting modem after 2 unsuccessful tries
Sat Mar 23 21:33:10 2024 kern.info kernel: Restarting modem after 2 unsuccessful tries
Sat Mar 23 21:33:10 2024 kern.info kernel: mctl: writting 'y' to /sys/modem/reboot
Sat Mar 23 21:33:10 2024 kern.info kernel: [63168.361917] subsys-restart: subsystem_restart_dev(): Restart sequence requested for modem, tako restart_level = RELATED.
Sat Mar 23 21:33:10 2024 kern.warn kernel: [63168.362006] [harden ]: restart modem,subsystem_restart_wq_func
Sat Mar 23 21:33:10 2024 kern.warn kernel: [63168.372376] [harden] : Quectel_ModemFatalErrProcess ,init cnt :1
Sat Mar 23 21:33:10 2024 kern.info kernel: [63168.377552] ipa-wan ipa3_lcl_mdm_ssr_notifier_cb:3130 IPA received MPSS BEFORE_SHUTDOWN
Sat Mar 23 21:33:10 2024 kern.err kernel: [63168.384483] ipa ipa3_send_msg:622 wlan_msg_process failed
Sat Mar 23 21:33:10 2024 kern.err kernel: [63168.391878] ipa ipa3_send_msg:625 lan_msg_process failed
Sat Mar 23 21:33:10 2024 kern.info kernel: [63168.399514] ipa-wan ipa3_wwan_remove:2914 rmnet_ipa started deinitialization
Sat Mar 23 21:33:10 2024 daemon.notice netifd: Network device 'rmnet_ipa0' link is down
Sat Mar 23 21:33:10 2024 kern.err kernel: [63168.439249] ipa-wan __ipa_wwan_close:1184 [rmnet_ipa0]: ipa3_deregister_intf failed -22
Sat Mar 23 21:33:10 2024 daemon.notice netifd: Network device 'rmnet_data0' link is down
Sat Mar 23 21:33:10 2024 daemon.notice netifd: Interface 'mob1s1a1_4' has link connectivity loss
Sat Mar 23 21:33:10 2024 kern.err kernel: [63168.569269] ipa-wan ipa3_wan_ioctl:96 Modem is in SSR, ignoring ioctl (-1073452789)
Sat Mar 23 21:33:10 2024 kern.err kernel: [63168.589355] ipa-wan ipa3_del_dflt_wan_rt_tables:472 Deleting Route hdl:(0x59) with ip type: 0
Sat Mar 23 21:33:10 2024 kern.err kernel: [63168.589886] ipa-wan ipa3_del_dflt_wan_rt_tables:481 Deleting Route hdl:(0x5b) with ip type: 1
Sat Mar 23 21:33:10 2024 kern.info kernel: [63168.598092] ipa-wan ipa3_wwan_remove:2956 rmnet_ipa completed deinitialization
Sat Mar 23 21:33:10 2024 kern.err kernel: [63168.606760] ipa ipa3_odl_pipe_cleanup:475 adpl pipe not configured
Sat Mar 23 21:33:10 2024 kern.info kernel: [63168.612546] ipa-wan ipa3_lcl_mdm_ssr_notifier_cb:3148 IPA BEFORE_SHUTDOWN handling is complete
Sat Mar 23 21:33:10 2024 kern.info kernel: [63168.618675] subsys-restart: subsystem_shutdown(): [kworker/u3:8:572]: Shutting down modem
Sat Mar 23 21:33:11 2024 daemon.notice netifd: mob1s1a1 (23602): Stopping network mob1s1a1
Sat Mar 23 21:33:11 2024 daemon.notice netifd: mob1s1a1_4 (4772): udhcpc: SIOCGIFINDEX: No such device
Sat Mar 23 21:33:11 2024 daemon.notice netifd: mob1s1a1_4 (4772): udhcpc: received SIGTERM
Sat Mar 23 21:33:11 2024 daemon.notice netifd: Interface 'mob1s1a1_4' is now down
Sat Mar 23 21:33:11 2024 daemon.warn dnsmasq[3805]: no servers found in /tmp/resolv.conf.d/resolv.conf.auto, will retry
Sat Mar 23 21:33:11 2024 daemon.notice netifd: mob1s1a1 (23602): Network cancelled... releasing resources
Sat Mar 23 21:33:11 2024 kern.warn kernel: [63169.699055] Timed out on stop ack from modem.
Sat Mar 23 21:33:11 2024 kern.info kernel: [63169.705471] subsys-restart: subsys_set_state(): [c9e505c0]: set quectel_restart_modem: 1
Sat Mar 23 21:33:11 2024 kern.info kernel: [63169.712195] sysmon-qmi: ssctl_del_server: Connection lost between QMI handle and modem's SSCTL service
Sat Mar 23 21:33:11 2024 kern.err kernel: [63169.716043] [glink_pkt_poll]: DATA40_CNTL channel in reset
Sat Mar 23 21:33:11 2024 kern.info kernel: [63169.722277] ipa-wan ipa3_lcl_mdm_ssr_notifier_cb:3151 IPA Received MPSS AFTER_SHUTDOWN
Sat Mar 23 21:33:11 2024 kern.info kernel: [63169.727381] ipa-wan ipa3_lcl_mdm_ssr_notifier_cb:3159 IPA AFTER_SHUTDOWN handling is complete
Sat Mar 23 21:33:11 2024 kern.err kernel: [63169.736219] Ramdump(ramdump_microdump_modem): No consumers. Aborting..
Sat Mar 23 21:33:11 2024 kern.info kernel: [63169.743761] microdump_modem_notifier_nb: do_ramdump() failed
Sat Mar 23 21:33:11 2024 kern.info kernel: [63169.751915] ipa-wan ipa3_lcl_mdm_ssr_notifier_cb:3162 IPA received MPSS BEFORE_POWERUP
Sat Mar 23 21:33:11 2024 kern.info kernel: [63169.756348] ipa-wan ipa3_lcl_mdm_ssr_notifier_cb:3174 IPA BEFORE_POWERUP handling is complete
Sat Mar 23 21:33:11 2024 kern.info kernel: [63169.763751] subsys-restart: subsystem_powerup(): [kworker/u3:8:572]: Powering up modem
Sat Mar 23 21:33:12 2024 kern.info kernel: [63169.777475] subsys-pil-tz 4080000.qcom,mss: modem: loading from 0x90800000 to 0xa0000000
Sat Mar 23 21:33:13 2024 user.warn kernel: [63170.901167] QTI: Modem Not In Service
Sat Mar 23 21:33:13 2024 kern.err kernel: [63171.369097] [glink_pkt_read]: DATA40_CNTL channel in reset
Sat Mar 23 21:33:13 2024 daemon.info dnsmasq[3805]: 10336 192.168.2.152/53047 query[AAAA] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:13 2024 daemon.info dnsmasq[3805]: 10336 192.168.2.152/53047 config error is REFUSED
Sat Mar 23 21:33:15 2024 daemon.notice netifd: mob1s1a1 (23602): [23 Mar 2024, 21:33:15] -Warning ** Cannot read from istream: connection broken
Sat Mar 23 21:33:15 2024 daemon.notice netifd: mob1s1a1 (23602): error: operation failed: endpoint hangup
Sat Mar 23 21:33:15 2024 daemon.notice netifd: mob1s1a1 (23602): error: couldn't release client: Cannot write message: Error sending data: Broken pipe
Sat Mar 23 21:33:15 2024 daemon.info dnsmasq[3805]: 10337 192.168.2.152/60200 query[AAAA] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:15 2024 daemon.info dnsmasq[3805]: 10337 192.168.2.152/60200 config error is REFUSED
Sat Mar 23 21:33:17 2024 daemon.info dnsmasq[3805]: 10338 192.168.2.152/47667 query[AAAA] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:17 2024 daemon.info dnsmasq[3805]: 10338 192.168.2.152/47667 config error is REFUSED
Sat Mar 23 21:33:19 2024 daemon.info dnsmasq[3805]: 10339 192.168.2.152/46822 query[AAAA] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:19 2024 daemon.info dnsmasq[3805]: 10339 192.168.2.152/46822 config error is REFUSED
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.820136] subsys-pil-tz 4080000.qcom,mss: modem: Brought out of reset
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.869804] subsys-pil-tz 4080000.qcom,mss: Subsystem error monitoring/handling services are up
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.869869] subsys-pil-tz 4080000.qcom,mss: modem: Power/Clock ready interrupt received
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.899122] ipa-wan ipa3_lcl_mdm_ssr_notifier_cb:3177 IPA received MPSS AFTER_POWERUP
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.899391] rmnet_ipa3 started initialization
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.905929] IPA SSR support = True
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.911680] IPA is_platform_type_msm = False
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.913562] IPA SG support = False
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.917995] IPA Napi Enable = False
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.921434] using default for wan-rx-desc-size = 256
Sat Mar 23 21:33:20 2024 kern.err kernel: [63177.925393] ipa-wan ipa3_wwan_probe:2880 rmnet_ipa completed initialization
Sat Mar 23 21:33:20 2024 kern.err kernel: [63177.930336] ipa ipa3_odl_pipe_open:371 adpl pipe not configured
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.936484] ipa-wan ipa3_lcl_mdm_ssr_notifier_cb:3183 IPA AFTER_POWERUP handling is complete
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.944648] subsys-restart: subsystem_restart_wq_func(): [kworker/u3:8:572]: Restart sequence for modem completed.
Sat Mar 23 21:33:20 2024 kern.err kernel: [63177.978505] qrtr: Modem QMI Readiness RX cmd:0x2 node[0x3]
Sat Mar 23 21:33:20 2024 kern.info kernel: [63177.985976] sysmon-qmi: ssctl_new_server: Connection established between QMI handle and modem's SSCTL service
Sat Mar 23 21:33:20 2024 kern.err kernel: [63178.308352] diag: In diag_send_peripheral_buffering_mode, buffering flag not set for 0
Sat Mar 23 21:33:20 2024 kern.info kernel: [63178.641230] Sending QMI_IPA_INIT_MODEM_DRIVER_REQ_V01
Sat Mar 23 21:33:20 2024 kern.err kernel: [63178.641896] ipa-wan ipa3_handle_indication_req:152 not send indication
Sat Mar 23 21:33:20 2024 kern.info kernel: [63178.668821] QMI_IPA_INIT_MODEM_DRIVER_REQ_V01 response received
Sat Mar 23 21:33:20 2024 kern.err kernel: [63178.668879] ipa ipa3_send_msg:622 wlan_msg_process failed
Sat Mar 23 21:33:20 2024 kern.err kernel: [63178.679099] ipa ipa3_send_msg:625 lan_msg_process failed
Sat Mar 23 21:33:20 2024 kern.err kernel: [63178.679467] ipa-wan ipa3_check_qmi_response:468 Got bad response 49 from request id 1 (error 48)
Sat Mar 23 21:33:20 2024 kern.err kernel: [63178.722054] ipa-wan rmnet_ipa_get_network_stats_and_update:3341 ipa3_qmi_get_network_stats failed: 1
Sat Mar 23 21:33:20 2024 user.err tftp_server[410]: ERR:pid=410 tid=23905 tftp-server : ERR :[tftp_os_la.c, 70] open failed: [2] [No such file or directory]
Sat Mar 23 21:33:20 2024 user.err tftp_server[410]: ERR:pid=410 tid=23905 tftp-server : ERR :[tftp_server.c, 1708] open failed : [-2] [No error information]
Sat Mar 23 21:33:20 2024 user.err tftp_server[410]: ERR:pid=410 tid=23905 tftp-server : ERR :[tftp_protocol.c, 1231] sending error-pkt. Code = 1, Msg = Err=2 String=No such file or directory
Sat Mar 23 21:33:20 2024 user.err tftp_server[410]: ERR:pid=410 tid=23906 tftp-server : ERR :[tftp_os_la.c, 70] open failed: [2] [No such file or directory]
Sat Mar 23 21:33:20 2024 user.err tftp_server[410]: ERR:pid=410 tid=23906 tftp-server : ERR :[tftp_server.c, 1708] open failed : [-2] [No error information]
Sat Mar 23 21:33:20 2024 user.err tftp_server[410]: ERR:pid=410 tid=23906 tftp-server : ERR :[tftp_protocol.c, 1231] sending error-pkt. Code = 1, Msg = Err=2 String=No such file or directory
Sat Mar 23 21:33:21 2024 daemon.info dnsmasq[3805]: 10340 192.168.2.152/59390 query[AAAA] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:21 2024 daemon.info dnsmasq[3805]: 10340 192.168.2.152/59390 config error is REFUSED
Sat Mar 23 21:33:22 2024 user.warn kernel: [63180.504170] QTI not In MBIM mode.
Sat Mar 23 21:33:22 2024 user.err : QMUXD: WARNING qmi_qmux_if_pwr_up_init failed! rc=-6
Sat Mar 23 21:33:22 2024 user.warn kernel: [63180.582685] QTI: DPM Init cb
Sat Mar 23 21:33:22 2024 user.warn kernel: [63180.589605] QTI: DPM Init cb
Sat Mar 23 21:33:22 2024 user.warn kernel: [63180.596595] QTI:USB peripheral iface enabled
Sat Mar 23 21:33:22 2024 user.warn kernel: [63180.621169] QTI:DPM port closed
Sat Mar 23 21:33:22 2024 daemon.notice netifd: Network device 'rmnet_ipa0' link is up
Sat Mar 23 21:33:23 2024 daemon.info dnsmasq[3805]: read /etc/hosts - 4 addresses
Sat Mar 23 21:33:23 2024 daemon.info dnsmasq[3805]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses
Sat Mar 23 21:33:23 2024 daemon.info dnsmasq-dhcp[3805]: read /etc/ethers - 0 addresses
Sat Mar 23 21:33:23 2024 kern.info Mobile data disconnected (internal modem)
Sat Mar 23 21:33:23 2024 user.warn kernel: [63181.631848] QTI:USB tethered modem SMD port opened
Sat Mar 23 21:33:23 2024 user.warn kernel: [63181.642186] QTI: Modem In Service
Sat Mar 23 21:33:23 2024 daemon.info dnsmasq[3805]: 10341 192.168.2.152/59485 query[A] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:23 2024 daemon.info dnsmasq[3805]: 10341 192.168.2.152/59485 config error is REFUSED
Sat Mar 23 21:33:23 2024 daemon.info dnsmasq[3805]: 10342 192.168.2.152/47380 query[AAAA] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:23 2024 daemon.info dnsmasq[3805]: 10342 192.168.2.152/47380 config error is REFUSED
Sat Mar 23 21:33:24 2024 user.err : QMUXD: WARNING qmi_qmux_if_pwr_up_init failed! rc=-6
Sat Mar 23 21:33:24 2024 kern.notice Password auth succeeded for admin on HTTP from 192.168.2.152
Sat Mar 23 21:33:24 2024 daemon.err uhttpd[2091]: vuci: accepted login for admin from 192.168.2.152
Sat Mar 23 21:33:24 2024 daemon.info gsmd[1674]: [ubus_notify_event:299] error: [MODEM_UBUS] Failed to send ubus notify for `3-1 [2c7c:0800]` modem!
Sat Mar 23 21:33:25 2024 daemon.info mobifd: [gsm.modem0] Starting modem settings init
Sat Mar 23 21:33:25 2024 daemon.info mobifd: [gsm.modem0] SIM is ready
Sat Mar 23 21:33:25 2024 daemon.notice netifd: Teardown timeout was reached. Killing scripts
Sat Mar 23 21:33:25 2024 daemon.notice netifd: Interface 'mob1s1a1' is now down
Sat Mar 23 21:33:25 2024 kern.err kernel: [63183.361322] ipa-wan __ipa_wwan_close:1184 [rmnet_ipa0]: ipa3_deregister_intf failed -22
Sat Mar 23 21:33:25 2024 daemon.info dnsmasq[3805]: 10343 192.168.2.152/47848 query[A] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:25 2024 daemon.info dnsmasq[3805]: 10343 192.168.2.152/47848 config error is REFUSED
Sat Mar 23 21:33:25 2024 daemon.info dnsmasq[3805]: 10344 192.168.2.152/52750 query[AAAA] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:25 2024 daemon.info dnsmasq[3805]: 10344 192.168.2.152/52750 config error is REFUSED
Sat Mar 23 21:33:25 2024 kern.warn kernel: [63183.741013] ipa3_wwan_ioctl: 5 callbacks suppressed
Sat Mar 23 21:33:25 2024 kern.err kernel: [63183.741018] ipa-wan ipa3_wwan_ioctl:2045 dev(rmnet_data0) register to IPA
Sat Mar 23 21:33:26 2024 user.notice sysctl: net.ipv6.conf.rmnet_data0.accept_ra = 0
Sat Mar 23 21:33:27 2024 daemon.info mobifd: [gsm.modem0] Modem settings init completed
Sat Mar 23 21:33:27 2024 daemon.info mobifd: [gsm.modem0] Starting connection setup
Sat Mar 23 21:33:27 2024 daemon.info mobifd: [gsm.modem0] Attempting to establish connection to operator with "15s" of timeout
Sat Mar 23 21:33:27 2024 daemon.info mobifd: [gsm.modem0] Connected to operator "DNA MOI"
Sat Mar 23 21:33:27 2024 daemon.info mobifd: [gsm.modem0] -CFUN- Functionality: "Full"
Sat Mar 23 21:33:27 2024 daemon.info mobifd: [gsm.modem0] -COPS- Mode: "Auto", operator: "DNA MOI"
Sat Mar 23 21:33:27 2024 daemon.info mobifd: [gsm.modem0] -CREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "213", cell ID: "2848023", technology: "E-UTRAN-NR"
Sat Mar 23 21:33:27 2024 daemon.info mobifd: [gsm.modem0] -CGREG- Mode: "Enabled (with location information)", status: "Not registered", LAC: "", cell ID: "", technology: "Unknown"
Sat Mar 23 21:33:27 2024 daemon.info mobifd: [gsm.modem0] -CEREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "213", cell ID: "2848023", technology: "E-UTRAN-NR"
Sat Mar 23 21:33:27 2024 daemon.info mobifd: [gsm.modem0] -C5GREG- Mode: "Enabled (with location information)", status: "Not registered", LAC: "", cell ID: "", technology: "Unknown"
Sat Mar 23 21:33:27 2024 daemon.info dnsmasq[3805]: 10345 192.168.2.152/35549 query[AAAA] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:27 2024 daemon.info dnsmasq[3805]: 10345 192.168.2.152/35549 config error is REFUSED
Sat Mar 23 21:33:27 2024 daemon.info dnsmasq[3805]: 10346 192.168.2.152/41218 query[A] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:27 2024 daemon.info dnsmasq[3805]: 10346 192.168.2.152/41218 config error is REFUSED
Sat Mar 23 21:33:28 2024 daemon.info mobifd: [gsm.modem0] IP address on PDP context "1": "10.88.236.142"
Sat Mar 23 21:33:28 2024 daemon.notice netifd: Interface 'mob1s1a1' is setting up now
Sat Mar 23 21:33:28 2024 daemon.notice netifd: mob1s1a1 (24580): wwan[24580] Searching for a valid wwan usb device...
Sat Mar 23 21:33:28 2024 daemon.notice netifd: mob1s1a1 (24580): wwan[24580] Using wwan usb device on bus
Sat Mar 23 21:33:28 2024 daemon.info dnsmasq[3805]: read /etc/hosts - 4 addresses
Sat Mar 23 21:33:28 2024 daemon.info dnsmasq[3805]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses
Sat Mar 23 21:33:28 2024 daemon.info dnsmasq-dhcp[3805]: read /etc/ethers - 0 addresses
Sat Mar 23 21:33:29 2024 kern.info Mobile data disconnected (internal modem)
Sat Mar 23 21:33:29 2024 daemon.info dnsmasq[3805]: 10347 192.168.2.152/48338 query[A] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:29 2024 daemon.info dnsmasq[3805]: 10347 192.168.2.152/48338 config error is REFUSED
Sat Mar 23 21:33:30 2024 user.err tftp_server[410]: ERR:pid=410 tid=24704 tftp-server : ERR :[tftp_os_la.c, 70] open failed: [2] [No such file or directory]
Sat Mar 23 21:33:30 2024 user.err tftp_server[410]: ERR:pid=410 tid=24704 tftp-server : ERR :[tftp_server.c, 1708] open failed : [-2] [No error information]
Sat Mar 23 21:33:30 2024 user.err tftp_server[410]: ERR:pid=410 tid=24704 tftp-server : ERR :[tftp_protocol.c, 1231] sending error-pkt. Code = 1, Msg = Err=2 String=No such file or directory
Sat Mar 23 21:33:31 2024 daemon.info dnsmasq[3805]: 10348 192.168.2.152/49023 query[A] checkonline.home-assistant.io from 192.168.2.152
Sat Mar 23 21:33:31 2024 daemon.info dnsmasq[3805]: 10348 192.168.2.152/49023 config error is REFUSED
--- ends here  ---

Daumantas · March 25, 2024, 10:41am

Hello,

A full troubleshoot file would be useful to diagnose the issue further. I have sent you a form to fill out, so we can get in contact with you privately. Please fill out the form and use ticket ID 4609. Thanks!

Best regards,

Daumantas · April 1, 2024, 5:00am

This topic was automatically closed after 10 days. New replies are no longer allowed.