RX1 window timeout when using RAK MQTT Forwarder for ChirpStack v4

Viewed 32

Hello folks.

I am trying to connect LoRa device using RAK7268CV2 gateway as forwarder but get 95 error code (Time out reached while waiting for a packet in the LoRa RX1 window: https://docs.rakwireless.com/product-categories/wisduo/rak4270-module/at-command-manual/#error-code-table ) using chirpstack v4. However, this device connects successfully with chirpstack v3.

Here is the logs:
Chirpstack:

Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.691818Z  INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_id="eu868" topic=eu868/gateway/ac1f09fffe18503d/event/up qos=AtMostOnce json=false
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.893807Z  INFO up{deduplication_id=7701524b-6637-42fc-b348-229294c51901}: chirpstack::uplink: Uplink received f_type=JoinRequest
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.893860Z DEBUG up{deduplication_id=7701524b-6637-42fc-b348-229294c51901}: chirpstack::uplink: Updating gateway meta-data for uplink frame-set
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.894437Z DEBUG up{deduplication_id=7701524b-6637-42fc-b348-229294c51901}: chirpstack::uplink: Logging uplink frame to Redis Stream
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.902187Z  INFO up{deduplication_id=7701524b-6637-42fc-b348-229294c51901}:join_request{dev_eui="bcfcc644f909e65c"}: chirpstack::storage::device_keys: Device-nonce validated, join-nonce incremented and stored dev_eui=bcfcc644f909e65c dev_nonce=60908
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.902744Z  INFO up{deduplication_id=7701524b-6637-42fc-b348-229294c51901}:join_request{dev_eui="bcfcc644f909e65c"}: chirpstack::storage::device_queue: Device queue flushed dev_eui=bcfcc644f909e65c count=0
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.908433Z  INFO up{deduplication_id=7701524b-6637-42fc-b348-229294c51901}:join_request{dev_eui="bcfcc644f909e65c"}: chirpstack::storage::device: Device partially updated dev_eui=bcfcc644f909e65c
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.908775Z  INFO up{deduplication_id=7701524b-6637-42fc-b348-229294c51901}:join_request{dev_eui="bcfcc644f909e65c"}:join_accept{downlink_id=2055986882}: chirpstack::storage::downlink_frame: Downlink-frame saved downlink_id=2055986882
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.908982Z  INFO up{deduplication_id=7701524b-6637-42fc-b348-229294c51901}:join_request{dev_eui="bcfcc644f909e65c"}:join_accept{downlink_id=2055986882}: chirpstack::gateway::backend::mqtt: Sending downlink frame region_id=eu868 gateway_id=ac1f09fffe18503d topic=eu868/gateway/ac1f09fffe18503d/command/down json=false
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.909707Z  INFO chirpstack::integration::mqtt: Publishing event topic=application/00000000-0000-0000-0000-000000000001/device/bcfcc644f909e65c/event/join
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.953472Z  INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_id="eu868" topic=eu868/gateway/ac1f09fffe18503d/event/ack qos=AtMostOnce json=false
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.953943Z  INFO tx_ack{downlink_id=2055986882}: chirpstack::downlink::tx_ack: Log downlink-frame for gateway gateway_id=ac1f09fffe18503d
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.954432Z  INFO tx_ack{downlink_id=2055986882}: chirpstack::downlink::tx_ack: Log downlink-frame for device device_eui=bcfcc644f909e65c
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.970579Z  INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_id="eu868" topic=eu868/gateway/ac1f09fffe18503d/event/ack qos=AtMostOnce json=false
Dec 11 09:51:20 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:20.970848Z ERROR chirpstack::downlink::tx_ack: Handling tx ack error error=Object does not exist (id: 2055986882)
Dec 11 09:51:25 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:25.927762Z  INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_id="eu868" topic=eu868/gateway/ac1f09fffe18503d/event/up qos=AtMostOnce json=false
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.130162Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}: chirpstack::uplink: Uplink received f_type=ConfirmedDataUp
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.130207Z DEBUG up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}: chirpstack::uplink: Updating gateway meta-data for uplink frame-set
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.130643Z DEBUG up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}: chirpstack::uplink: Logging uplink frame to Redis Stream
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.141232Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}: chirpstack::storage::device_gateway: Gateway rx-info saved dev_eui=bcfcc644f909e65c
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.166295Z  INFO chirpstack::integration::mqtt: Publishing event topic=application/00000000-0000-0000-0000-000000000001/device/bcfcc644f909e65c/event/up
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.175966Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}: chirpstack::storage::device: Device partially updated dev_eui=bcfcc644f909e65c
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.176089Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}: chirpstack::storage::metrics: Metrics saved name=device:bcfcc644f909e65c aggregation=HOUR
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.176143Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}: chirpstack::storage::metrics: Metrics saved name=device:bcfcc644f909e65c aggregation=DAY
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.176192Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}: chirpstack::storage::metrics: Metrics saved name=device:bcfcc644f909e65c aggregation=MONTH
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.278299Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}:data_down{downlink_id=1664739085}: chirpstack::storage::mac_command: Pending mac-command block set cid=DevStatusReq
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.278318Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}:data_down{downlink_id=1664739085}: chirpstack::storage::mac_command: Pending mac-command block set cid=PingSlotChannelReq
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.278336Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}:data_down{downlink_id=1664739085}: chirpstack::storage::mac_command: Pending mac-command block set cid=DevStatusReq
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.278342Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}:data_down{downlink_id=1664739085}: chirpstack::storage::mac_command: Pending mac-command block set cid=PingSlotChannelReq
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.278544Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}:data_down{downlink_id=1664739085}: chirpstack::storage::downlink_frame: Downlink-frame saved downlink_id=1664739085
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.286709Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}:data_down{downlink_id=1664739085}: chirpstack::storage::device: Device partially updated dev_eui=bcfcc644f909e65c
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.286887Z  INFO up{deduplication_id=b7a2d230-38a1-438a-b8d1-4898e33b6311}:data_up{dev_eui="bcfcc644f909e65c"}:data_down{downlink_id=1664739085}: chirpstack::gateway::backend::mqtt: Sending downlink frame region_id=eu868 gateway_id=ac1f09fffe18503d topic=eu868/gateway/ac1f09fffe18503d/command/down json=false
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.328328Z  INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_id="eu868" topic=eu868/gateway/ac1f09fffe18503d/event/ack qos=AtMostOnce json=false
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.330361Z  INFO chirpstack::integration::mqtt: Publishing event topic=application/00000000-0000-0000-0000-000000000001/device/bcfcc644f909e65c/event/log
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.345809Z  INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_id="eu868" topic=eu868/gateway/ac1f09fffe18503d/event/ack qos=AtMostOnce json=false
Dec 11 09:51:26 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:26.346067Z ERROR chirpstack::downlink::tx_ack: Handling tx ack error error=Object does not exist (id: 1664739085)
Dec 11 09:51:33 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:33.847968Z  INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_id="eu868" topic=eu868/gateway/ac1f09fffe18503d/event/stats qos=AtMostOnce json=false
Dec 11 09:51:33 devtankhq-trixie chirpstack[1302]: 2025-12-11T09:51:33.854111Z  INFO stats{gateway_id=ac1f09fffe18503d}: chirpstack::storage::gateway: Gateway partially updated gateway_id=ac1f09fffe18503d

Gateway:

Thu Dec 11 09:51:19 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:19 2025 user.info lora_pkt_fwd[8546]: INFO: [down] PULL_ACK received in 2 ms
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]: src/lora_pkt_fwd.c:2788:fetch_packets():  Current time: 2025-12-11 09:51:20 UTC
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: rrd_statistic_up 376 uplinkTqByAirtime : dr = 4, timeonair = 102, tm = 1765446680
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: rrd_statistic_up 378 uplinkTqByPkt : dr = 4,  tm = 1765446680
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: rrd_statistic_up 380 ChanBusyByAirtime chan 4, timeonair = 102, tm = 1765446680
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]: INFO: Received pkt CRC BAD
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: rrd_statistic_up 376 uplinkTqByAirtime : dr = 4, timeonair = 102, tm = 1765446680
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: rrd_statistic_up 378 uplinkTqByPkt : dr = 4,  tm = 1765446680
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: rrd_statistic_up 380 ChanBusyByAirtime chan 6, timeonair = 102, tm = 1765446680
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: INFO: [filter] white list join filter is not enable
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]:  INFO: Received pkt from mote: F909E65C (fcnt=64710)
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: Uplink Frame :
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	00 5C E6 09 F9 44 C6 FC
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	BC 5C E6 09 F9 44 C6 FC
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	BC EC ED CD 18 D8 04
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]: src/lora_pkt_fwd.c:3369:thread_up(): nb_pkt 1 send_report 0
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]: src/lora_pkt_fwd.c:3243:dgram_push_pkt(): buff_index 225 buff_size 8169
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]:  JSON up: {"rxpk":[{"jver":1,"tmst":3664778052,"chan":6,"rfch":1,"freq":868.300000,"mid": 8,"stat":1,"modu":"LORA","datr":"SF8BW125","codr":"4/5","rssis":-30
,"lsnr":15.0,"foff":-61,"rssi":-29,"size":23,"data":"AFzmCflExvy8XOYJ+UTG/Lzs7c0Y2AQ="}]}
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 248
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 248
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: Uplink - {"rxpk":[{"jver":1,"tmst":3664778052,"chan":6,"rfch":1,"freq":868.300000,"mid": 8,"stat":1,"modu":"LORA","datr":"SF8BW125","codr":"4/5","rssis":-30,"l
snr":15.0,"foff":-61,"rssi":-29,"size":23,"data":"AFzmCflExvy8XOYJ+UTG/Lzs7c0Y2AQ="}]}
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: [Uplink] - Protobuf size 130
Thu Dec 11 09:51:20 2025 daemon.info mqttEv[8423]: mqttEvPublish : [[gwBridgeS Mqtt Client]] publish - [130][ W]
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]: INFO: [up] PUSH_ACK received in 4 ms
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: mosqMsgCB topic : eu868/gateway/ac1f09fffe18503d/command/down
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: gwBridgeParseMosqDownlink mqtt downlink packet - size 174
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]: INFO: [down] PULL_RESP received  - token[194:222] :)
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]:  JSON down: {"txpk":{"imme":false,"tmst":3669778052,"freq":868.300000,"rfch":0,"powe":16,"ncrc":false,"modu":"LORA","codr":"4/5","datr":"SF8BW125","ipol":tru
e,"size":33,"data":"IMBr+hb2VS72gRnVB1X2XJvYtqCYfzKkrNdPRDDgh29x"}}
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: DownLink Frame :
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	20 C0 6B FA 16 F6 55 2E
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	F6 81 19 D5 07 55 F6 5C
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	9B D8 B6 A0 98 7F 32 A4
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	AC D7 4F 44 30 E0 87 6F
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	71
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]: INFO: Packet ENQUENUE SUCCESS on SX1301/SX1302: 0
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 12
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]: INFO: [down] PULL_RESP received  - token[194:222] :)
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]:  JSON down: {"txpk":{"imme":false,"tmst":3670778052,"freq":869.525000,"rfch":0,"powe":29,"ncrc":false,"modu":"LORA","codr":"4/5","datr":"SF12BW125","ipol":tr
ue,"size":33,"data":"IMBr+hb2VS72gRnVB1X2XJvYtqCYfzKkrNdPRDDgh29x"}}
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 12
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: DownLink Frame :
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	20 C0 6B FA 16 F6 55 2E
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	F6 81 19 D5 07 55 F6 5C
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: [TxAck] - Protobuf size 28
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	9B D8 B6 A0 98 7F 32 A4
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	AC D7 4F 44 30 E0 87 6F
Thu Dec 11 09:51:20 2025 daemon.info mqttEv[8423]: mqttEvPublish : [[gwBridgeS Mqtt Client]] publish - [28][P½¯ÔG*A2Pac1f09fffe18503d0àoq]
Thu Dec 11 09:51:20 2025 user.debug lora_pkt_fwd[8546]: 	71
Thu Dec 11 09:51:20 2025 user.warn lora_pkt_fwd[8546]: WARNING: Requested TX power is not supported (29dBm), actual power used: 27dBm
Thu Dec 11 09:51:20 2025 user.info lora_pkt_fwd[8546]: INFO: Packet ENQUENUE SUCCESS on SX1301/SX1302: 0
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 12
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 12
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:20 2025 daemon.info gwBridge[8423]: [TxAck] - Protobuf size 28
Thu Dec 11 09:51:20 2025 daemon.info mqttEv[8423]: mqttEvPublish : [[gwBridgeS Mqtt Client]] publish - [28][P½¯ÔG*A2Pac1f09fffe18503d]
Thu Dec 11 09:51:21 2025 user.debug lora_pkt_fwd[8546]: PPS check: pps_xticks=0, last_pps_xticks=0, pps_nochange_count=59481, local_pps_ok=0
Thu Dec 11 09:51:24 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 12
Thu Dec 11 09:51:24 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 12
Thu Dec 11 09:51:24 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:24 2025 user.info lora_pkt_fwd[8546]: INFO: [down] PULL_ACK received in 2 ms
Thu Dec 11 09:51:25 2025 user.debug lora_pkt_fwd[8546]: DEBUG: peek packet with count_us=3669778052 at index 0
Thu Dec 11 09:51:25 2025 user.info lora_pkt_fwd[8546]: src/lora_pkt_fwd.c:4535:thread_jit(): lgw_send done on rf_chain 0: count_us=3669778052
Thu Dec 11 09:51:25 2025 user.info lora_pkt_fwd[8546]: src/lora_pkt_fwd.c:2788:fetch_packets():  Current time: 2025-12-11 09:51:25 UTC
Thu Dec 11 09:51:25 2025 user.debug lora_pkt_fwd[8546]: rrd_statistic_up 376 uplinkTqByAirtime : dr = 4, timeonair = 72, tm = 1765446685
Thu Dec 11 09:51:25 2025 user.debug lora_pkt_fwd[8546]: rrd_statistic_up 378 uplinkTqByPkt : dr = 4,  tm = 1765446685
Thu Dec 11 09:51:25 2025 user.debug lora_pkt_fwd[8546]: rrd_statistic_up 380 ChanBusyByAirtime chan 6, timeonair = 72, tm = 1765446685
Thu Dec 11 09:51:25 2025 user.info lora_pkt_fwd[8546]:  INFO: Received pkt from mote: 00E09535 (fcnt=0)
Thu Dec 11 09:51:25 2025 user.debug lora_pkt_fwd[8546]: Uplink Frame :
Thu Dec 11 09:51:25 2025 user.debug lora_pkt_fwd[8546]: 	80 35 95 E0 00 00 00 00
Thu Dec 11 09:51:25 2025 user.debug lora_pkt_fwd[8546]: 	7F 9F CA AF
Thu Dec 11 09:51:25 2025 user.info lora_pkt_fwd[8546]: src/lora_pkt_fwd.c:3369:thread_up(): nb_pkt 1 send_report 0
Thu Dec 11 09:51:25 2025 user.info lora_pkt_fwd[8546]: src/lora_pkt_fwd.c:3243:dgram_push_pkt(): buff_index 209 buff_size 8169
Thu Dec 11 09:51:25 2025 user.info lora_pkt_fwd[8546]:  JSON up: {"rxpk":[{"jver":1,"tmst":3670030023,"chan":6,"rfch":1,"freq":868.300000,"mid": 8,"stat":1,"modu":"LORA","datr":"SF8BW125","codr":"4/5","rssis":-30
,"lsnr":14.0,"foff":-61,"rssi":-29,"size":12,"data":"gDWV4AAAAAB/n8qv"}]}
Thu Dec 11 09:51:25 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 232
Thu Dec 11 09:51:25 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 232
Thu Dec 11 09:51:25 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:25 2025 daemon.info gwBridge[8423]: Uplink - {"rxpk":[{"jver":1,"tmst":3670030023,"chan":6,"rfch":1,"freq":868.300000,"mid": 8,"stat":1,"modu":"LORA","datr":"SF8BW125","codr":"4/5","rssis":-30,"l
snr":14.0,"foff":-61,"rssi":-29,"size":12,"data":"gDWV4AAAAAB/n8qv"}]}
Thu Dec 11 09:51:25 2025 daemon.info gwBridge[8423]: [Uplink] - Protobuf size 119
Thu Dec 11 09:51:25 2025 daemon.info mqttEv[8423]: mqttEvPublish : [[gwBridgeS Mqtt Client]] publish - [119][ L5à]
Thu Dec 11 09:51:25 2025 user.info lora_pkt_fwd[8546]: INFO: [up] PUSH_ACK received in 0 ms
Thu Dec 11 09:51:26 2025 daemon.info gwBridge[8423]: mosqMsgCB topic : eu868/gateway/ac1f09fffe18503d/command/down
Thu Dec 11 09:51:26 2025 daemon.info gwBridge[8423]: gwBridgeParseMosqDownlink mqtt downlink packet - size 144
Thu Dec 11 09:51:26 2025 user.info lora_pkt_fwd[8546]: INFO: [down] PULL_RESP received  - token[13:231] :)
Thu Dec 11 09:51:26 2025 user.info lora_pkt_fwd[8546]:  JSON down: {"txpk":{"imme":false,"tmst":3671030023,"freq":868.300000,"rfch":0,"powe":16,"ncrc":false,"modu":"LORA","codr":"4/5","datr":"SF8BW125","ipol":tru
e,"size":18,"data":"YDWV4ACmAAAGEQAAAAOTeNlS"}}
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]: DownLink Frame :
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]: 	60 35 95 E0 00 A6 00 00
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]: 	06 11 00 00 00 03 93 78
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]: 	D9 52
Thu Dec 11 09:51:26 2025 user.info lora_pkt_fwd[8546]: src/jitqueue.c:282:jit_enqueue(): ERROR: Packet (type=0) REJECTED, collision with packet already programmed at 3670778052 (3671030023)
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]: [logger]: pkt type = COLLISION_PACKET, count_us = 3671030023
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]:  JSON tx_ack: {"txpk_ack":{"error":"COLLISION_PACKET"}}
Thu Dec 11 09:51:26 2025 user.info lora_pkt_fwd[8546]: INFO: [down] PULL_RESP received  - token[13:231] :)
Thu Dec 11 09:51:26 2025 user.info lora_pkt_fwd[8546]:  JSON down: {"txpk":{"imme":false,"tmst":3672030023,"freq":869.525000,"rfch":0,"powe":29,"ncrc":false,"modu":"LORA","codr":"4/5","datr":"SF12BW125","ipol":tr
ue,"size":18,"data":"YDWV4ACmAAAGEQAAAAOTeNlS"}}
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]: DownLink Frame :
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]: 	60 35 95 E0 00 A6 00 00
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]: 	06 11 00 00 00 03 93 78
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]: 	D9 52
Thu Dec 11 09:51:26 2025 user.warn lora_pkt_fwd[8546]: WARNING: Requested TX power is not supported (29dBm), actual power used: 27dBm
Thu Dec 11 09:51:26 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 53
Thu Dec 11 09:51:26 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 53
Thu Dec 11 09:51:26 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:26 2025 daemon.info gwBridge[8423]: [TxAck] - Protobuf size 28
Thu Dec 11 09:51:26 2025 user.info lora_pkt_fwd[8546]: src/jitqueue.c:282:jit_enqueue(): ERROR: Packet (type=0) REJECTED, collision with packet already programmed at 3670778052 (3672030023)
Thu Dec 11 09:51:26 2025 daemon.info mqttEv[8423]: mqttEvPublish : [[gwBridgeS Mqtt Client]] publish - [28][PÎçF*D2Pac1f09fffe18503d3d/event/up]
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]: [logger]: pkt type = COLLISION_PACKET, count_us = 3672030023
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]:  JSON tx_ack: {"txpk_ack":{"error":"COLLISION_PACKET"}}
Thu Dec 11 09:51:26 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 53
Thu Dec 11 09:51:26 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 53
Thu Dec 11 09:51:26 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:26 2025 daemon.info gwBridge[8423]: [TxAck] - Protobuf size 28
Thu Dec 11 09:51:26 2025 daemon.info mqttEv[8423]: mqttEvPublish : [[gwBridgeS Mqtt Client]] publish - [28][PÎçF*D2Pac1f09fffe18503d3d/event/up]
Thu Dec 11 09:51:26 2025 user.debug lora_pkt_fwd[8546]: DEBUG: peek packet with count_us=3670778052 at index 0
Thu Dec 11 09:51:26 2025 user.info lora_pkt_fwd[8546]: src/lora_pkt_fwd.c:4535:thread_jit(): lgw_send done on rf_chain 0: count_us=3670778052
Thu Dec 11 09:51:27 2025 user.debug lora_pkt_fwd[8546]: DEBUG: timersync wait result 145
Thu Dec 11 09:51:27 2025 user.info lora_pkt_fwd[8546]: INFO: host/radio[0] time offset=(1765443015 s:867021 us) - drift=1 us
Thu Dec 11 09:51:29 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 12
Thu Dec 11 09:51:29 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 12
Thu Dec 11 09:51:29 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:29 2025 user.info lora_pkt_fwd[8546]: INFO: [down] PULL_ACK received in 2 ms
Thu Dec 11 09:51:31 2025 user.debug lora_pkt_fwd[8546]: PPS check: pps_xticks=0, last_pps_xticks=0, pps_nochange_count=59491, local_pps_ok=0
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]:  ##### 2025-12-11 09:51:33 UTC #####
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: ### [UPSTREAM] ###
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # RF packets received by concentrator: 3
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # CRC_OK: 66.67%, CRC_FAIL: 33.33%, NO_CRC: 0.00%
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # RF packets forwarded: 2 (35 bytes)
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # PUSH_DATA datagrams sent: 3 (605 bytes)
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # PUSH_DATA acknowledged: 100.00%
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: ### [DOWNSTREAM] ###
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # PULL_DATA sent: 6 (100.00% acknowledged)
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # PULL_RESP(onse) datagrams received: 4 (826 bytes)
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # RF packets sent to concentrator: 2 (102 bytes)
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # TX errors: 0
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # TX rejected (collision packet): 100.00% (req:142, rej:142)
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # TX rejected (collision beacon): 0.00% (req:142, rej:0)
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # TX rejected (too late): 0.00% (req:142, rej:0)
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # TX rejected (too early): 0.00% (req:142, rej:0)
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: ### SX1302 Status ###
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # SX130X counter (INST): 3677929659
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # SX130X time (PPS): 0, offset us 0
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # BEACON queued: 0
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # BEACON sent so far: 0
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # BEACON rejected: 0
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: ### [JIT] ###
Thu Dec 11 09:51:33 2025 user.info lora_pkt_fwd[8546]: src/jitqueue.c:453:jit_print_queue(): INFO: [jit] queue is empty
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: #--------
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: ### [GPS] ###
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: # GPS sync is disabled
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: ### Concentrator[0] temperature: 30 C ###
Thu Dec 11 09:51:33 2025 user.notice lora_pkt_fwd[8546]: ##### END #####
Thu Dec 11 09:51:33 2025 user.debug lora_pkt_fwd[8546]: watchdog wait result 0
Thu Dec 11 09:51:33 2025 user.debug lora_pkt_fwd[8546]: watchdog check past/last tstamp[0]: 3647907941 3677929659
Thu Dec 11 09:51:33 2025 user.info lora_pkt_fwd[8546]: src/lora_pkt_fwd.c:3369:thread_up(): nb_pkt 0 send_report 1
Thu Dec 11 09:51:33 2025 user.info lora_pkt_fwd[8546]:  JSON up: {"stat":{"time":"2025-12-11 09:51:33 UTC","rxnb":3,"rxok":2,"rxfw":2,"ackr":100.0,"dwnb":4,"txnb":2,"temp":30.0}}
Thu Dec 11 09:51:33 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 125
Thu Dec 11 09:51:33 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 125
Thu Dec 11 09:51:33 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:33 2025 daemon.info gwBridge[8423]: Uplink - {"stat":{"time":"2025-12-11 09:51:33 UTC","rxnb":3,"rxok":2,"rxfw":2,"ackr":100.0,"dwnb":4,"txnb":2,"temp":30.0}}
Thu Dec 11 09:51:33 2025 daemon.info gwBridge[8423]: Stats : Protobuf size 46
Thu Dec 11 09:51:33 2025 daemon.info mqttEv[8423]: mqttEvPublish : [[gwBridgeS Mqtt Client]] publish - [46]¥¨êÉFP]
Thu Dec 11 09:51:33 2025 user.info lora_pkt_fwd[8546]: INFO: [up] PUSH_ACK received in 21 ms
Thu Dec 11 09:51:35 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 12
Thu Dec 11 09:51:35 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 12
Thu Dec 11 09:51:35 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:35 2025 user.info lora_pkt_fwd[8546]: INFO: [down] PULL_ACK received in 2 ms
Thu Dec 11 09:51:37 2025 user.debug lora_pkt_fwd[8546]: DEBUG: timersync wait result 145
Thu Dec 11 09:51:37 2025 user.info lora_pkt_fwd[8546]: INFO: host/radio[0] time offset=(1765443015 s:867022 us) - drift=1 us
Thu Dec 11 09:51:40 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 12
Thu Dec 11 09:51:40 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 12
Thu Dec 11 09:51:40 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:40 2025 user.info lora_pkt_fwd[8546]: INFO: [down] PULL_ACK received in 2 ms
Thu Dec 11 09:51:41 2025 user.debug lora_pkt_fwd[8546]: PPS check: pps_xticks=0, last_pps_xticks=0, pps_nochange_count=59501, local_pps_ok=0
Thu Dec 11 09:51:42 2025 user.debug lora_pkt_fwd[8546]: INFO: [MQTT] mqttLogger: Client mqtt-logger-1765387182 sending PINGREQ
Thu Dec 11 09:51:42 2025 user.debug lora_pkt_fwd[8546]: INFO: [MQTT] mqttLogger: Client mqtt-logger-1765387182 received PINGRESP
Thu Dec 11 09:51:45 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 12
Thu Dec 11 09:51:45 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 12
Thu Dec 11 09:51:45 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:45 2025 user.info lora_pkt_fwd[8546]: INFO: [down] PULL_ACK received in 2 ms
Thu Dec 11 09:51:47 2025 user.debug lora_pkt_fwd[8546]: DEBUG: timersync wait result 145
Thu Dec 11 09:51:47 2025 user.info lora_pkt_fwd[8546]: INFO: host/radio[0] time offset=(1765443015 s:867022 us) - drift=0 us
Thu Dec 11 09:51:50 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 12
Thu Dec 11 09:51:50 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 12
Thu Dec 11 09:51:50 2025 daemon.info gwBridge[8423]: Uplink Packet from Gateway ac1f09fffe18503d
Thu Dec 11 09:51:50 2025 user.info lora_pkt_fwd[8546]: INFO: [down] PULL_ACK received in 2 ms
Thu Dec 11 09:51:51 2025 user.debug lora_pkt_fwd[8546]: PPS check: pps_xticks=0, last_pps_xticks=0, pps_nochange_count=59511, local_pps_ok=0
Thu Dec 11 09:51:55 2025 daemon.info gwBridge[8423]: gwBridgeReadCB: Frame size 12
Thu Dec 11 09:51:55 2025 daemon.info gwBridge[8423]: Enter gwBridgeFrameRcv, mdCreateEvent 0 Frame Len 12

Device log:

DEBUG:0000089023:COMMS:  >> RAK4270 version:3.3.0.17
DEBUG:0000089175:COMMS:  >> UART1 work mode: RUI_UART_NORMAL, 115200, N81
DEBUG:0000089180:COMMS:  >> UART2 work mode: RUI_UART_NORMAL, 115200, N81
DEBUG:0000089188:COMMS:  >> Current work_mode:LoRaWAN, join_mode:OTAA, Multicast
DEBUG:0000089190:COMMS:  >> Initialization OK
DEBUG:0000089190:COMMS: << at+set_config=lora:default_parameters
DEBUG:0000089416:COMMS:  >> OK 
DEBUG:0000089418:COMMS: << at+set_config=lora:join_mode:0
DEBUG:0000089421:COMMS:  >> OK
DEBUG:0000089423:COMMS: << at+set_config=lora:class:2
DEBUG:0000089436:COMMS:  >> OK
DEBUG:0000089438:COMMS: << at+set_config=lora:region:EU868
DEBUG:0000089442:COMMS:  >> OK
DEBUG:0000089444:COMMS: << at+set_config=lora:confirm:1
DEBUG:0000089447:COMMS:  >> OK
DEBUG:0000089449:COMMS: << at+set_config=lora:adr:0
DEBUG:0000089452:COMMS:  >> OK
DEBUG:0000089454:COMMS: << at+set_config=lora:dr:4
DEBUG:0000089462:COMMS:  >> OK
DEBUG:0000089464:COMMS: << at+set_config=lora:dutycycle_enable:0
DEBUG:0000089468:COMMS:  >> OK 
DEBUG:0000089470:COMMS: << at+set_config=lora:dev_eui:bcfcc644f909e65c
DEBUG:0000089703:COMMS:  >> OK
DEBUG:0000089705:COMMS: << at+set_config=lora:app_eui:bcfcc644f909e65c
DEBUG:0000089931:COMMS:  >> OK
DEBUG:0000089933:COMMS: << at+set_config=lora:app_key:f3fe43d0920ceb7df3d9a3e081
DEBUG:0000090168:COMMS:  >> OK
DEBUG:0000090170:COMMS: << at+set_config=device:restart
DEBUG:0000090204:COMMS:  >> LoRa (R) is a registered trademark or service mark o
DEBUG:0000090207:COMMS:  >> RAK4270 version:3.3.0.17
DEBUG:0000090359:COMMS:  >> UART1 work mode: RUI_UART_NORMAL, 115200, N81
DEBUG:0000090364:COMMS:  >> UART2 work mode: RUI_UART_NORMAL, 115200, N81
DEBUG:0000090372:COMMS:  >> Current work_mode:LoRaWAN, join_mode:OTAA, Multicast
DEBUG:0000090374:COMMS:  >> Initialization OK
DEBUG:0000090376:COMMS: << at+join
DEBUG:0000095738:COMMS:  >> OK Join Success
DEBUG:0000095740:COMMS: Sending an 'is alive' packet.
DEBUG:0000095740:COMMS: << at+send=lora:3:
DEBUG:0000100766:COMMS:  >> ERROR: 95
DEBUG:0000100766:COMMS: Timed out waiting for packet in window RX1.
DEBUG:0000100766:COMMS: Resending 3/5
DEBUG:0000100766:COMMS: Broken backup, cant resend
DEBUG:0000131335:COMMS: LoRa chip timed out, resetting.

Any thoughts?

1 Answers

If you are experiencing issues with the internal MQTT Forwarder for ChirpStack v4 of the RAK WisGateOS2 (2.2.15), but when using the Semtech UDP packet-forwarder (compatible) forwarder + ChirpStack Gateway Bridge (v4) does work, then I expect there could be an issue with the MQTT Forwarder implementation by RAK.

As an alternative to using the ChirpStack Gateway Bridge on a server, you could install the ChirpStack MQTT Forwarder package on your gateway using these instructions: https://www.chirpstack.io/docs/chirpstack-mqtt-forwarder/install/rak.html.