Comments (14)
michapr
commented on June 14, 2024
1
I had to reflash my test device - it was running with your sleeping example (btw: running fine and stable .. ;) ...)
I wouldn't normally do a LinkCheck just after a join
yes, right, of course. But time request make sense...
Here is a "fresh" log (after reset) with the requested detail:
12:22:38.440 -> ESP-ROM:esp32s3-20210327
12:22:38.440 -> Build:Mar 27 2021
12:22:38.462 -> rst:0x1 (POWERON),boot:0x28 (SPI_FAST_FLASH_BOOT)
12:22:38.462 -> SPIWP:0xee
12:22:38.462 -> mode:DIO, clock div:1
12:22:38.462 -> load:0x3fce3808,len:0x43c
12:22:38.462 -> load:0x403c9700,len:0xbec
12:22:38.462 -> load:0x403cc700,len:0x2a3c
12:22:38.462 -> SHA-256 comparison failed:
12:22:38.462 -> Calculated: dcde8d8a4817d9bf5d5d69a7247667264e4e10ac7493514868b61f5aa6146539
12:22:38.462 -> Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
12:22:38.462 -> Attempting to boot anyway...
12:22:38.462 -> entry 0x403c98d8
12:22:43.497 ->
12:22:43.497 -> Setup
12:22:43.497 -> Initalise the radio
12:22:43.497 -> RLB_DBG: RadioLib Debug Info
12:22:43.497 -> RLB_DBG: Version: 6.5.0.0
12:22:43.497 -> RLB_DBG: Platform: ESP32
12:22:43.497 -> RLB_DBG: Compiled: Apr 2 2024 12:21:45
12:22:43.543 ->
12:22:43.543 -> RLB_DBG: Found SX126x: RADIOLIB_SX126X_REG_VERSION_STRING:
12:22:43.543 -> RLB_DBG: 0000320 53 58 31 32 36 31 20 56 32 44 20 32 44 30 32 00 | SX1261 V2D 2D02.
12:22:43.543 -> RLB_DBG:
12:22:43.543 -> RLB_DBG: M SX126x
12:22:43.575 -> Join ('login') to the LoRaWAN Network
12:22:43.575 -> RLB_PRO: Configuration mismatch (checksum: 0, mode: 0, class: 0, plan: 1)
12:22:43.575 -> RLB_PRO: Nonces buffer:
12:22:43.575 -> RLB_PRO: 0000000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................
12:22:43.607 -> RLB_PRO: Clearing buffer and starting fresh
12:22:43.607 -> RLB_PRO: Setting up dynamic channels
12:22:43.607 -> RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
12:22:43.607 -> RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
12:22:43.607 -> RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
12:22:43.607 -> RLB_PRO: [MAC] 0x03
12:22:43.607 -> RLB_PRO: 0000000 20 00 00 80 | ...
12:22:43.639 -> RLB_PRO: LinkADRReq: dataRate = 2, txPower = 0, chMask = 0x0000, chMaskCntl = 0, nbTrans = 0
12:22:43.639 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
12:22:43.639 -> RLB_PRO: PHY: TX = 16 dBm
12:22:43.639 -> RLB_PRO: LinkADRAns: status = 0x07
12:22:43.639 -> RLB_PRO: [MAC] 0x04
12:22:43.639 -> RLB_PRO: 0000000 07 | .
12:22:43.640 -> RLB_PRO: DutyCycleReq: max duty cycle = 1/2^7
12:22:43.671 -> RLB_PRO: [MAC] 0x05
12:22:43.671 -> RLB_PRO: 0000000 00 d2 ad 84 | ....
12:22:43.671 -> RLB_PRO: RXParamSetupReq: rx1DrOffset = 0, rx2DataRate = 0, freq = 869.525024
12:22:43.671 -> RLB_PRO: RXParamSetupAns: status = 0x07
12:22:43.671 -> RLB_PRO: [MAC] 0x08
12:22:43.671 -> RLB_PRO: 0000000 01 | .
12:22:43.671 -> RLB_PRO: RXTimingSetupReq: delay = 1 sec
12:22:43.704 -> RLB_PRO: [MAC] 0x09
12:22:43.704 -> RLB_PRO: 0000000 05 | .
12:22:43.704 -> RLB_PRO: TxParamSetupReq: dlDwell = 0, ulDwell = 0, maxEirp = 16 dBm
12:22:43.704 -> RLB_PRO: [MAC] 0x0C
12:22:43.704 -> RLB_PRO: 0000000 65 | e
12:22:43.704 -> RLB_PRO: ADRParamSetupReq: limitExp = 6, delayExp = 5
12:22:43.740 -> RLB_PRO: [MAC] 0x0F
12:22:43.740 -> RLB_PRO: 0000000 fa | .
12:22:43.740 -> RLB_PRO: RejoinParamSetupReq: maxTime = 15, maxCount = 10
12:22:43.740 -> RLB_PRO: RejoinParamSetupAns: status = 0x03
12:22:43.806 -> RLB_PRO:
12:22:43.806 -> RLB_PRO: PHY: Frequency UL = 868.300 MHz
12:22:43.806 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
12:22:43.806 -> RLB_DBG: Timeout in 1853 ms
12:22:44.195 -> RLB_PRO: Join-request sent <-- Rx Delay start
12:22:44.195 -> RLB_PRO:
12:22:44.195 -> RLB_PRO: PHY: Frequency DL = 868.300 MHz
12:22:44.195 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
12:22:49.174 -> RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end
12:22:49.441 -> RLB_PRO: Closing Rx1 window
12:22:49.637 -> RLB_PRO: joinAcceptMsg:
12:22:49.668 -> RLB_PRO: 0000000 20 57 00 00 13 00 00 9e 6d 0b 26 83 05 18 4f 84 | W......m.&...O.
12:22:49.668 -> RLB_PRO: 0000010 e8 56 84 b8 5e 84 88 66 84 58 6e 84 00 e0 7f 5b | .V..^..f.Xn....[
12:22:49.668 -> RLB_PRO: 0000020 12 | .
12:22:49.668 -> RLB_PRO: JoinNoncePrev: 0, JoinNonce: 87
12:22:49.668 -> RLB_PRO: LoRaWAN revision: 1.1
12:22:49.668 -> RLB_PRO: [MAC] 0x05
12:22:49.701 -> RLB_PRO: 0000000 03 d2 ad 84 | ....
12:22:49.701 -> RLB_PRO: RXParamSetupReq: rx1DrOffset = 0, rx2DataRate = 3, freq = 869.525024
12:22:49.701 -> RLB_PRO: RXParamSetupAns: status = 0x07
12:22:49.701 -> RLB_PRO: [MAC] 0x08
12:22:49.701 -> RLB_PRO: 0000000 05 | .
12:22:49.732 -> RLB_PRO: RXTimingSetupReq: delay = 5 sec
12:22:49.732 -> RLB_PRO: Setting up dynamic channels
12:22:49.732 -> RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
12:22:49.732 -> RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
12:22:49.732 -> RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
12:22:49.732 -> RLB_PRO: Processing CFList
12:22:49.732 -> RLB_PRO: [MAC] 0x07
12:22:49.764 -> RLB_PRO: 0000000 03 18 4f 84 50 | ..O.P
12:22:49.764 -> RLB_PRO: NewChannelReq:
12:22:49.764 -> RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
12:22:49.764 -> RLB_PRO: NewChannelAns: status = 0x03
12:22:49.764 -> RLB_PRO: [MAC] 0x07
12:22:49.764 -> RLB_PRO: 0000000 04 e8 56 84 50 | ..V.P
12:22:49.797 -> RLB_PRO: NewChannelReq:
12:22:49.797 -> RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
12:22:49.797 -> RLB_PRO: NewChannelAns: status = 0x03
12:22:49.797 -> RLB_PRO: [MAC] 0x07
12:22:49.797 -> RLB_PRO: 0000000 05 b8 5e 84 50 | ..^.P
12:22:49.829 -> RLB_PRO: NewChannelReq:
12:22:49.829 -> RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
12:22:49.829 -> RLB_PRO: NewChannelAns: status = 0x03
12:22:49.829 -> RLB_PRO: [MAC] 0x07
12:22:49.829 -> RLB_PRO: 0000000 06 88 66 84 50 | ..f.P
12:22:49.829 -> RLB_PRO: NewChannelReq:
12:22:49.829 -> RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
12:22:49.861 -> RLB_PRO: NewChannelAns: status = 0x03
12:22:49.861 -> RLB_PRO: [MAC] 0x07
12:22:49.861 -> RLB_PRO: 0000000 07 58 6e 84 50 | .Xn.P
12:22:49.861 -> RLB_PRO: NewChannelReq:
12:22:49.861 -> RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
12:22:49.861 -> RLB_PRO: NewChannelAns: status = 0x03
12:22:49.861 -> [LoRaWAN] DevAddr: 260B6D9E
12:22:49.861 -> Ready!
12:22:49.861 ->
12:22:49.861 -> [LoRaWAN] Requesting LinkCheck and DeviceTime
12:22:49.958 -> RLB_PRO:
12:22:49.958 -> RLB_PRO: PHY: Frequency UL = 868.300 MHz
12:22:49.958 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
12:22:49.958 -> RLB_PRO: Uplink MAC payload (3 commands):
12:22:49.958 -> RLB_PRO: 0000000 0b 01 02 0d | ....
12:22:49.958 -> RLB_PRO: Uplink (FcntUp = 0) decoded:
12:22:49.958 -> RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
12:22:50.005 -> RLB_PRO: 0000010 80 9e 6d 0b 26 84 00 00 1f 04 a0 e9 0a 6b 5e 34 | ..m.&........k^4
12:22:50.005 -> RLB_PRO: 0000020 14 00 ce 3f | ...?
12:22:50.258 -> RLB_DBG: Timeout in 1853 ms
12:22:50.636 -> RLB_PRO: Uplink sent <-- Rx Delay start
12:22:50.636 -> RLB_PRO:
12:22:50.636 -> RLB_PRO: PHY: Frequency DL = 868.300 MHz
12:22:50.636 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
12:22:55.643 -> RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end
12:22:55.862 -> RLB_PRO: Closing Rx1 window
12:22:55.862 -> RLB_PRO: PHY: Frequency DL = 869.525 MHz
12:22:55.862 -> RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
12:22:56.653 -> RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
12:22:56.770 -> RLB_PRO: Closing Rx2 window
12:22:56.874 -> RLB_PRO: Downlink (NFcntDown = 0) encoded:
12:22:56.874 -> RLB_PRO: 0000000 49 00 00 00 00 01 9e 6d 0b 26 00 00 00 00 00 1a | I......m.&......
12:22:56.874 -> RLB_PRO: 0000010 60 9e 6d 0b 26 a0 00 00 00 5a 11 63 7a a2 94 c3 | `.m.&....Z.cz...
12:22:56.874 -> RLB_PRO: 0000020 68 5e 3d 75 6d a8 50 e7 2a 1b 99 6e 71 60 | h^=um.P.*..nq`
12:22:56.874 -> RLB_PRO: [MAC] 0x0B
12:22:56.874 -> RLB_PRO: 0000000 01 | .
12:22:56.906 -> RLB_PRO: RekeyConf: server version = 1.1
12:22:56.906 -> RLB_PRO: [MAC] 0x0D
12:22:56.906 -> RLB_PRO: 0000000 0c 9f 36 53 ca | ..6S.
12:22:56.906 -> RLB_PRO: DeviceTimeAns: [user]
12:22:56.906 -> RLB_PRO: [MAC] 0x02
12:22:56.906 -> RLB_PRO: 0000000 1b 01 | ..
12:22:56.906 -> RLB_PRO: LinkCheckAns: [user]
12:22:56.906 -> RLB_PRO: [MAC] 0x03
12:22:56.938 -> RLB_PRO: 0000000 51 ff 00 01 | Q...
12:22:56.938 -> RLB_PRO: LinkADRReq: dataRate = 5, txPower = 1, chMask = 0x00ff, chMaskCntl = 0, nbTrans = 1
12:22:56.938 -> RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
12:22:56.938 -> RLB_PRO: PHY: TX = 14 dBm
12:22:56.938 -> RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
12:22:56.938 -> RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
12:22:56.970 -> RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
12:22:56.970 -> RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
12:22:56.970 -> RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
12:22:56.970 -> RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
12:22:56.970 -> RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
12:22:56.970 -> RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
12:22:57.002 -> RLB_PRO: LinkADRAns: status = 0x07
12:22:57.002 -> RLB_PRO: [MAC] 0x06
12:22:57.002 -> RLB_PRO: DevStatusReq
12:22:57.002 -> RLB_PRO: DevStatusAns: status = 0x0b92
12:22:57.002 -> Downlink data:
12:22:57.002 -> 5DADB4BCB169F9342032D750A30C110000
12:22:57.002 -> [LoRaWAN] RSSI: -37.00 dBm
12:22:57.002 -> [LoRaWAN] SNR: 11.00 dB
12:22:57.002 -> [LoRaWAN] Frequency error: 1881.31 Hz
12:22:57.002 -> [LoRaWAN] Event information:
12:22:57.002 -> [LoRaWAN] Confirmed: 0
12:22:57.002 -> [LoRaWAN] Confirming: 1
12:22:57.002 -> [LoRaWAN] Datarate: 5
12:22:57.042 -> [LoRaWAN] Frequency: 868.300 MHz
12:22:57.042 -> [LoRaWAN] Output power: 14 dBm
12:22:57.042 -> [LoRaWAN] Frame count: 0
12:22:57.042 -> [LoRaWAN] Port: 0
12:22:57.042 -> [LoRaWAN] LinkCheck margin: 27
12:22:57.042 -> [LoRaWAN] LinkCheck count: 1
12:22:57.042 -> [LoRaWAN] DeviceTime Unix: 1712053370
12:22:57.042 -> [LoRaWAN] DeviceTime second: 1/202
12:22:57.042 -> [LoRaWAN] Next uplink in 1193s
12:42:50.661 ->
12:42:50.661 -> Stack smashing protect failure!
12:42:50.661 ->
12:42:50.661 ->
12:42:50.661 -> abort() was called at PC 0x420142bf on core 1
12:42:50.661 ->
12:42:50.661 ->
12:42:50.661 -> Backtrace:0x403777be:0x3fce2f000x4037a5ad:0x3fce2f20 0x4037fba5:0x3fce2f40 0x420142bf:0x3fce2fc0 0x42001f9d:0x3fce2fe0 0x4200ac85:0x3fce3060
12:42:50.662 ->
12:42:50.662 ->
12:42:50.662 ->
12:42:50.662 ->
12:42:50.662 -> ELF file SHA256: 0000000000000000
12:42:50.662 ->
12:42:50.662 -> Rebooting...
12:42:50.662 -> ESP-ROM:esp32s3-20210327
12:42:50.707 -> Build:Mar 27 2021
12:42:50.707 -> rst:0xc (RTC_SW_CPU_RST),boot:0x28 (SPI_FAST_FLASH_BOOT)
12:42:50.707 -> Saved PC:0x4202b846
12:42:50.707 -> SPIWP:0xee
12:42:50.707 -> mode:DIO, clock div:1
12:42:50.707 -> load:0x3fce3808,len:0x43c
12:42:50.707 -> load:0x403c9700,len:0xbec
12:42:50.707 -> load:0x403cc700,len:0x2a3c
12:42:50.707 -> SHA-256 comparison failed:
12:42:50.707 -> Calculated: dcde8d8a4817d9bf5d5d69a7247667264e4e10ac7493514868b61f5aa6146539
12:42:50.707 -> Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
12:42:50.730 -> Attempting to boot anyway...
12:42:50.730 -> entry 0x403c98d8
12:42:55.799 ->
12:42:55.799 -> Setup
12:42:55.799 -> Initalise the radio
12:42:55.799 -> RLB_DBG: RadioLib Debug Info
12:42:55.799 -> RLB_DBG: Version: 6.5.0.0
12:42:55.799 -> RLB_DBG: Platform: ESP32
12:42:55.799 -> RLB_DBG: Compiled: Apr 2 2024 12:21:45
12:42:55.846 ->
12:42:55.846 -> RLB_DBG: Found SX126x: RADIOLIB_SX126X_REG_VERSION_STRING:
12:42:55.846 -> RLB_DBG: 0000320 53 58 31 32 36 31 20 56 32 44 20 32 44 30 32 00 | SX1261 V2D 2D02.
12:42:55.846 -> RLB_DBG:
12:42:55.846 -> RLB_DBG: M SX126x
12:42:55.870 -> Join ('login') to the LoRaWAN Network
12:42:55.870 -> RLB_PRO: Configuration mismatch (checksum: 0, mode: 0, class: 0, plan: 1)
12:42:55.903 -> RLB_PRO: Nonces buffer:
12:42:55.903 -> RLB_PRO: 0000000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................
12:42:55.903 -> RLB_PRO: Clearing buffer and starting fresh
12:42:55.903 -> RLB_PRO: Setting up dynamic channels
12:42:55.903 -> RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
12:42:55.903 -> RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
12:42:55.903 -> RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
12:42:55.935 -> RLB_PRO: [MAC] 0x03
12:42:55.935 -> RLB_PRO: 0000000 20 00 00 80 | ...
12:42:55.935 -> RLB_PRO: LinkADRReq: dataRate = 2, txPower = 0, chMask = 0x0000, chMaskCntl = 0, nbTrans = 0
12:42:55.935 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
12:42:55.935 -> RLB_PRO: PHY: TX = 16 dBm
12:42:55.935 -> RLB_PRO: LinkADRAns: status = 0x07
12:42:55.935 -> RLB_PRO: [MAC] 0x04
12:42:55.935 -> RLB_PRO: 0000000 07 | .
12:42:55.966 -> RLB_PRO: DutyCycleReq: max duty cycle = 1/2^7
12:42:55.966 -> RLB_PRO: [MAC] 0x05
12:42:55.966 -> RLB_PRO: 0000000 00 d2 ad 84 | ....
12:42:55.966 -> RLB_PRO: RXParamSetupReq: rx1DrOffset = 0, rx2DataRate = 0, freq = 869.525024
12:42:55.966 -> RLB_PRO: RXParamSetupAns: status = 0x07
12:42:55.966 -> RLB_PRO: [MAC] 0x08
12:42:55.998 -> RLB_PRO: 0000000 01 | .
12:42:55.998 -> RLB_PRO: RXTimingSetupReq: delay = 1 sec
12:42:55.998 -> RLB_PRO: [MAC] 0x09
12:42:55.998 -> RLB_PRO: 0000000 05 | .
12:42:55.998 -> RLB_PRO: TxParamSetupReq: dlDwell = 0, ulDwell = 0, maxEirp = 16 dBm
12:42:55.998 -> RLB_PRO: [MAC] 0x0C
12:42:55.998 -> RLB_PRO: 0000000 65 | e
12:42:56.038 -> RLB_PRO: ADRParamSetupReq: limitExp = 6, delayExp = 5
12:42:56.038 -> RLB_PRO: [MAC] 0x0F
12:42:56.038 -> RLB_PRO: 0000000 fa | .
12:42:56.038 -> RLB_PRO: RejoinParamSetupReq: maxTime = 15, maxCount = 10
12:42:56.038 -> RLB_PRO: RejoinParamSetupAns: status = 0x03
12:42:56.123 -> RLB_PRO:
12:42:56.123 -> RLB_PRO: PHY: Frequency UL = 868.100 MHz
12:42:56.123 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
12:42:56.123 -> RLB_DBG: Timeout in 1853 ms
12:42:56.482 -> RLB_PRO: Join-request sent <-- Rx Delay start
12:42:56.517 -> RLB_PRO:
12:42:56.517 -> RLB_PRO: PHY: Frequency DL = 868.100 MHz
12:42:56.517 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
12:43:01.514 -> RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end
12:43:01.711 -> RLB_PRO: Closing Rx1 window
12:43:01.963 -> RLB_PRO: joinAcceptMsg:
12:43:01.963 -> RLB_PRO: 0000000 20 58 00 00 13 00 00 8f ba 0b 26 83 05 18 4f 84 | X........&...O.
12:43:01.963 -> RLB_PRO: 0000010 e8 56 84 b8 5e 84 88 66 84 58 6e 84 00 84 03 b8 | .V..^..f.Xn.....
12:43:01.963 -> RLB_PRO: 0000020 7f | .
12:43:01.997 -> RLB_PRO: JoinNoncePrev: 0, JoinNonce: 88
12:43:01.997 -> RLB_PRO: LoRaWAN revision: 1.1
12:43:01.997 -> RLB_PRO: [MAC] 0x05
12:43:01.997 -> RLB_PRO: 0000000 03 d2 ad 84 | ....
12:43:01.997 -> RLB_PRO: RXParamSetupReq: rx1DrOffset = 0, rx2DataRate = 3, freq = 869.525024
12:43:02.029 -> RLB_PRO: RXParamSetupAns: status = 0x07
12:43:02.029 -> RLB_PRO: [MAC] 0x08
12:43:02.029 -> RLB_PRO: 0000000 05 | .
12:43:02.029 -> RLB_PRO: RXTimingSetupReq: delay = 5 sec
12:43:02.029 -> RLB_PRO: Setting up dynamic channels
12:43:02.029 -> RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
12:43:02.029 -> RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
12:43:02.029 -> RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
12:43:02.061 -> RLB_PRO: Processing CFList
12:43:02.061 -> RLB_PRO: [MAC] 0x07
12:43:02.061 -> RLB_PRO: 0000000 03 18 4f 84 50 | ..O.P
12:43:02.061 -> RLB_PRO: NewChannelReq:
12:43:02.061 -> RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
12:43:02.061 -> RLB_PRO: NewChannelAns: status = 0x03
12:43:02.093 -> RLB_PRO: [MAC] 0x07
12:43:02.093 -> RLB_PRO: 0000000 04 e8 56 84 50 | ..V.P
12:43:02.093 -> RLB_PRO: NewChannelReq:
12:43:02.093 -> RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
12:43:02.093 -> RLB_PRO: NewChannelAns: status = 0x03
12:43:02.093 -> RLB_PRO: [MAC] 0x07
12:43:02.093 -> RLB_PRO: 0000000 05 b8 5e 84 50 | ..^.P
12:43:02.125 -> RLB_PRO: NewChannelReq:
12:43:02.125 -> RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
12:43:02.125 -> RLB_PRO: NewChannelAns: status = 0x03
12:43:02.125 -> RLB_PRO: [MAC] 0x07
12:43:02.125 -> RLB_PRO: 0000000 06 88 66 84 50 | ..f.P
12:43:02.157 -> RLB_PRO: NewChannelReq:
12:43:02.157 -> RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
12:43:02.157 -> RLB_PRO: NewChannelAns: status = 0x03
12:43:02.157 -> RLB_PRO: [MAC] 0x07
12:43:02.157 -> RLB_PRO: 0000000 07 58 6e 84 50 | .Xn.P
12:43:02.157 -> RLB_PRO: NewChannelReq:
12:43:02.157 -> RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
12:43:02.189 -> RLB_PRO: NewChannelAns: status = 0x03
12:43:02.189 -> [LoRaWAN] DevAddr: 260BBA8F
12:43:02.189 -> Ready!
12:43:02.189 ->
12:43:02.189 -> [LoRaWAN] Requesting LinkCheck and DeviceTime
12:43:02.259 -> RLB_PRO:
12:43:02.259 -> RLB_PRO: PHY: Frequency UL = 867.900 MHz
12:43:02.259 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
12:43:02.259 -> RLB_PRO: Uplink MAC payload (3 commands):
12:43:02.259 -> RLB_PRO: 0000000 0b 01 02 0d | ....
12:43:02.259 -> RLB_PRO: Uplink (FcntUp = 0) decoded:
12:43:02.259 -> RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
12:43:02.292 -> RLB_PRO: 0000010 80 8f ba 0b 26 84 00 00 fc 4c 18 d8 0a 9b b8 3a | ....&....L.....:
12:43:02.292 -> RLB_PRO: 0000020 14 00 ce 3f | ...?
12:43:02.469 -> RLB_DBG: Timeout in 1853 ms
12:43:02.842 -> RLB_PRO: Uplink sent <-- Rx Delay start
12:43:02.842 -> RLB_PRO:
12:43:02.842 -> RLB_PRO: PHY: Frequency DL = 867.900 MHz
12:43:02.842 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
12:43:07.826 -> RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end
12:43:08.090 -> RLB_PRO: Closing Rx1 window
12:43:08.261 -> RLB_PRO: Downlink (NFcntDown = 0) encoded:
12:43:08.261 -> RLB_PRO: 0000000 49 00 00 00 00 01 8f ba 0b 26 00 00 00 00 00 1a | I........&......
12:43:08.261 -> RLB_PRO: 0000010 60 8f ba 0b 26 a0 00 00 00 17 53 cb c3 8c 89 0a | `...&.....S.....
12:43:08.293 -> RLB_PRO: 0000020 e9 44 13 b9 f7 de 99 24 cc 71 8c a8 f4 11 | .D.....$.q....
12:43:08.293 -> RLB_PRO: [MAC] 0x0B
12:43:08.293 -> RLB_PRO: 0000000 01 | .
12:43:08.293 -> RLB_PRO: RekeyConf: server version = 1.1
12:43:08.293 -> RLB_PRO: [MAC] 0x0D
12:43:08.293 -> RLB_PRO: 0000000 c9 a3 36 53 03 | ..6S.
12:43:08.293 -> RLB_PRO: DeviceTimeAns: [user]
12:43:08.325 -> RLB_PRO: [MAC] 0x02
12:43:08.325 -> RLB_PRO: 0000000 1b 01 | ..
12:43:08.325 -> RLB_PRO: LinkCheckAns: [user]
12:43:08.325 -> RLB_PRO: [MAC] 0x03
12:43:08.325 -> RLB_PRO: 0000000 51 ff 00 01 | Q...
12:43:08.325 -> RLB_PRO: LinkADRReq: dataRate = 5, txPower = 1, chMask = 0x00ff, chMaskCntl = 0, nbTrans = 1
12:43:08.325 -> RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
12:43:08.357 -> RLB_PRO: PHY: TX = 14 dBm
12:43:08.357 -> RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
12:43:08.357 -> RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
12:43:08.357 -> RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
12:43:08.357 -> RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
12:43:08.357 -> RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
12:43:08.357 -> RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
12:43:08.389 -> RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
12:43:08.389 -> RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
12:43:08.389 -> RLB_PRO: LinkADRAns: status = 0x07
12:43:08.389 -> RLB_PRO: [MAC] 0x06
12:43:08.389 -> RLB_PRO: DevStatusReq
12:43:08.389 -> RLB_PRO: DevStatusAns: status = 0x0792
12:43:08.389 -> Downlink data:
12:43:08.389 -> 9B36AEDB18EEA935217484683834110000
12:43:08.389 -> [LoRaWAN] RSSI: -47.00 dBm
12:43:08.421 -> [LoRaWAN] SNR: 7.50 dB
12:43:08.421 -> [LoRaWAN] Frequency error: 1845.47 Hz
12:43:08.421 -> [LoRaWAN] Event information:
12:43:08.421 -> [LoRaWAN] Confirmed: 0
12:43:08.421 -> [LoRaWAN] Confirming: 1
12:43:08.421 -> [LoRaWAN] Datarate: 5
12:43:08.421 -> [LoRaWAN] Frequency: 867.900 MHz
12:43:08.421 -> [LoRaWAN] Output power: 14 dBm
12:43:08.421 -> [LoRaWAN] Frame count: 0
12:43:08.421 -> [LoRaWAN] Port: 0
12:43:08.421 -> [LoRaWAN] LinkCheck margin: 27
12:43:08.421 -> [LoRaWAN] LinkCheck count: 1
12:43:08.421 -> [LoRaWAN] DeviceTime Unix: 1712054583
12:43:08.460 -> [LoRaWAN] DeviceTime second: 1/3
12:43:08.460 -> [LoRaWAN] Next uplink in 1194s
13:03:02.844 ->
13:03:02.844 -> Stack smashing protect failure!
13:03:02.877 ->
13:03:02.877 ->
13:03:02.877 -> abort() was called at PC 0x420142bf on core 1
...
from radiolib.
michapr
commented on June 14, 2024
1
This is complete log with sendMacCommandReq(RADIOLIB_LORAWAN_MAC_DEVICE_TIME) only...
13:09:05.203 -> ESP-ROM:esp32s3-20210327
13:09:05.203 -> Build:Mar 27 2021
13:09:05.203 -> rst:0x1 (POWERON),boot:0x29 (SPI_FAST_FLASH_BOOT)
13:09:05.203 -> SPIWP:0xee
13:09:05.203 -> mode:DIO, clock div:1
13:09:05.203 -> load:0x3fce3808,len:0x43c
13:09:05.203 -> load:0x403c9700,len:0xbec
13:09:05.203 -> load:0x403cc700,len:0x2a3c
13:09:05.203 -> SHA-256 comparison failed:
13:09:05.203 -> Calculated: dcde8d8a4817d9bf5d5d69a7247667264e4e10ac7493514868b61f5aa6146539
13:09:05.203 -> Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
13:09:05.249 -> Attempting to boot anyway...
13:09:05.249 -> entry 0x403c98d8
13:09:10.322 ->
13:09:10.322 -> Setup
13:09:10.322 -> Initalise the radio
13:09:10.322 -> RLB_DBG: RadioLib Debug Info
13:09:10.322 -> RLB_DBG: Version: 6.5.0.0
13:09:10.322 -> RLB_DBG: Platform: ESP32
13:09:10.322 -> RLB_DBG: Compiled: Apr 2 2024 12:21:45
13:09:10.322 ->
13:09:10.368 -> RLB_DBG: Found SX126x: RADIOLIB_SX126X_REG_VERSION_STRING:
13:09:10.368 -> RLB_DBG: 0000320 53 58 31 32 36 31 20 56 32 44 20 32 44 30 32 00 | SX1261 V2D 2D02.
13:09:10.368 -> RLB_DBG:
13:09:10.368 -> RLB_DBG: M SX126x
13:09:10.391 -> Join ('login') to the LoRaWAN Network
13:09:10.391 -> RLB_PRO: Configuration mismatch (checksum: 0, mode: 0, class: 0, plan: 1)
13:09:10.391 -> RLB_PRO: Nonces buffer:
13:09:10.391 -> RLB_PRO: 0000000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................
13:09:10.423 -> RLB_PRO: Clearing buffer and starting fresh
13:09:10.423 -> RLB_PRO: Setting up dynamic channels
13:09:10.423 -> RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
13:09:10.423 -> RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
13:09:10.423 -> RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
13:09:10.423 -> RLB_PRO: [MAC] 0x03
13:09:10.423 -> RLB_PRO: 0000000 20 00 00 80 | ...
13:09:10.455 -> RLB_PRO: LinkADRReq: dataRate = 2, txPower = 0, chMask = 0x0000, chMaskCntl = 0, nbTrans = 0
13:09:10.455 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
13:09:10.455 -> RLB_PRO: PHY: TX = 16 dBm
13:09:10.455 -> RLB_PRO: LinkADRAns: status = 0x07
13:09:10.455 -> RLB_PRO: [MAC] 0x04
13:09:10.455 -> RLB_PRO: 0000000 07 | .
13:09:10.455 -> RLB_PRO: DutyCycleReq: max duty cycle = 1/2^7
13:09:10.487 -> RLB_PRO: [MAC] 0x05
13:09:10.487 -> RLB_PRO: 0000000 00 d2 ad 84 | ....
13:09:10.487 -> RLB_PRO: RXParamSetupReq: rx1DrOffset = 0, rx2DataRate = 0, freq = 869.525024
13:09:10.487 -> RLB_PRO: RXParamSetupAns: status = 0x07
13:09:10.487 -> RLB_PRO: [MAC] 0x08
13:09:10.487 -> RLB_PRO: 0000000 01 | .
13:09:10.487 -> RLB_PRO: RXTimingSetupReq: delay = 1 sec
13:09:10.519 -> RLB_PRO: [MAC] 0x09
13:09:10.519 -> RLB_PRO: 0000000 05 | .
13:09:10.519 -> RLB_PRO: TxParamSetupReq: dlDwell = 0, ulDwell = 0, maxEirp = 16 dBm
13:09:10.519 -> RLB_PRO: [MAC] 0x0C
13:09:10.519 -> RLB_PRO: 0000000 65 | e
13:09:10.519 -> RLB_PRO: ADRParamSetupReq: limitExp = 6, delayExp = 5
13:09:10.519 -> RLB_PRO: [MAC] 0x0F
13:09:10.562 -> RLB_PRO: 0000000 fa | .
13:09:10.562 -> RLB_PRO: RejoinParamSetupReq: maxTime = 15, maxCount = 10
13:09:10.562 -> RLB_PRO: RejoinParamSetupAns: status = 0x03
13:09:10.640 -> RLB_PRO:
13:09:10.640 -> RLB_PRO: PHY: Frequency UL = 868.300 MHz
13:09:10.640 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
13:09:10.640 -> RLB_DBG: Timeout in 1853 ms
13:09:11.033 -> RLB_PRO: Join-request sent <-- Rx Delay start
13:09:11.033 -> RLB_PRO:
13:09:11.033 -> RLB_PRO: PHY: Frequency DL = 868.300 MHz
13:09:11.033 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
13:09:15.990 -> RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end
13:09:16.245 -> RLB_PRO: Closing Rx1 window
13:09:16.459 -> RLB_PRO: joinAcceptMsg:
13:09:16.459 -> RLB_PRO: 0000000 20 5b 00 00 13 00 00 85 7e 0b 26 83 05 18 4f 84 | [......~.&...O.
13:09:16.491 -> RLB_PRO: 0000010 e8 56 84 b8 5e 84 88 66 84 58 6e 84 00 9f 79 ef | .V..^..f.Xn...y.
13:09:16.491 -> RLB_PRO: 0000020 1d | .
13:09:16.491 -> RLB_PRO: JoinNoncePrev: 0, JoinNonce: 91
13:09:16.491 -> RLB_PRO: LoRaWAN revision: 1.1
13:09:16.491 -> RLB_PRO: [MAC] 0x05
13:09:16.491 -> RLB_PRO: 0000000 03 d2 ad 84 | ....
13:09:16.523 -> RLB_PRO: RXParamSetupReq: rx1DrOffset = 0, rx2DataRate = 3, freq = 869.525024
13:09:16.523 -> RLB_PRO: RXParamSetupAns: status = 0x07
13:09:16.523 -> RLB_PRO: [MAC] 0x08
13:09:16.523 -> RLB_PRO: 0000000 05 | .
13:09:16.523 -> RLB_PRO: RXTimingSetupReq: delay = 5 sec
13:09:16.555 -> RLB_PRO: Setting up dynamic channels
13:09:16.555 -> RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
13:09:16.555 -> RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
13:09:16.555 -> RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
13:09:16.555 -> RLB_PRO: Processing CFList
13:09:16.555 -> RLB_PRO: [MAC] 0x07
13:09:16.555 -> RLB_PRO: 0000000 03 18 4f 84 50 | ..O.P
13:09:16.588 -> RLB_PRO: NewChannelReq:
13:09:16.588 -> RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
13:09:16.588 -> RLB_PRO: NewChannelAns: status = 0x03
13:09:16.588 -> RLB_PRO: [MAC] 0x07
13:09:16.588 -> RLB_PRO: 0000000 04 e8 56 84 50 | ..V.P
13:09:16.620 -> RLB_PRO: NewChannelReq:
13:09:16.620 -> RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
13:09:16.620 -> RLB_PRO: NewChannelAns: status = 0x03
13:09:16.620 -> RLB_PRO: [MAC] 0x07
13:09:16.620 -> RLB_PRO: 0000000 05 b8 5e 84 50 | ..^.P
13:09:16.620 -> RLB_PRO: NewChannelReq:
13:09:16.620 -> RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
13:09:16.652 -> RLB_PRO: NewChannelAns: status = 0x03
13:09:16.652 -> RLB_PRO: [MAC] 0x07
13:09:16.652 -> RLB_PRO: 0000000 06 88 66 84 50 | ..f.P
13:09:16.652 -> RLB_PRO: NewChannelReq:
13:09:16.652 -> RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
13:09:16.652 -> RLB_PRO: NewChannelAns: status = 0x03
13:09:16.652 -> RLB_PRO: [MAC] 0x07
13:09:16.652 -> RLB_PRO: 0000000 07 58 6e 84 50 | .Xn.P
13:09:16.695 -> RLB_PRO: NewChannelReq:
13:09:16.695 -> RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
13:09:16.695 -> RLB_PRO: NewChannelAns: status = 0x03
13:09:16.695 -> [LoRaWAN] DevAddr: 260B7E85
13:09:16.695 -> Ready!
13:09:16.695 ->
13:09:16.695 -> [LoRaWAN] Requesting LinkCheck and DeviceTime
13:09:16.763 -> RLB_PRO:
13:09:16.763 -> RLB_PRO: PHY: Frequency UL = 867.700 MHz
13:09:16.763 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
13:09:16.763 -> RLB_PRO: Uplink MAC payload (2 commands):
13:09:16.763 -> RLB_PRO: 0000000 0b 01 0d | ...
13:09:16.808 -> RLB_PRO: Uplink (FcntUp = 0) decoded:
13:09:16.808 -> RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
13:09:16.808 -> RLB_PRO: 0000010 80 85 7e 0b 26 83 00 00 0f 11 20 0a 9a 25 d8 00 | ..~.&..... ..
13:09:16.808 -> RLB_PRO: 0000020 14 00 ce | ...
13:09:17.062 -> RLB_DBG: Timeout in 1648 ms
13:09:17.423 -> RLB_PRO: Uplink sent <-- Rx Delay start
13:09:17.423 -> RLB_PRO:
13:09:17.423 -> RLB_PRO: PHY: Frequency DL = 867.700 MHz
13:09:17.423 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
13:09:22.410 -> RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end
13:09:22.623 -> RLB_PRO: Closing Rx1 window
13:09:22.789 -> RLB_PRO: Downlink (NFcntDown = 0) encoded:
13:09:22.789 -> RLB_PRO: 0000000 49 00 00 00 00 01 85 7e 0b 26 00 00 00 00 00 16 | I......~.&......
13:09:22.789 -> RLB_PRO: 0000010 60 85 7e 0b 26 ae 00 00 c0 fa f1 4f 4e 13 85 9a | `.~.&......ON...
13:09:22.789 -> RLB_PRO: 0000020 80 98 65 48 81 c5 c3 fd fb 96 | ..eH......
13:09:22.789 -> RLB_PRO: [MAC] 0x0B
13:09:22.789 -> RLB_PRO: 0000000 01 | .
13:09:22.820 -> RLB_PRO: RekeyConf: server version = 1.1
13:09:22.820 -> RLB_PRO: [MAC] 0x0D
13:09:22.820 -> RLB_PRO: 0000000 ef a9 36 53 98 | ..6S.
13:09:22.820 -> RLB_PRO: DeviceTimeAns: [user]
13:09:22.820 -> RLB_PRO: [MAC] 0x03
13:09:22.820 -> RLB_PRO: 0000000 41 ff 00 01 | A...
13:09:22.820 -> RLB_PRO: LinkADRReq: dataRate = 4, txPower = 1, chMask = 0x00ff, chMaskCntl = 0, nbTrans = 1
13:09:22.852 -> RLB_PRO: PHY: SF = 8, BW = 125.000 kHz, CR = 4/5
13:09:22.852 -> RLB_PRO: PHY: TX = 14 dBm
13:09:22.852 -> RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
13:09:22.852 -> RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
13:09:22.852 -> RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
13:09:22.852 -> RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
13:09:22.884 -> RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
13:09:22.884 -> RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
13:09:22.884 -> RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
13:09:22.884 -> RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
13:09:22.884 -> RLB_PRO: LinkADRAns: status = 0x07
13:09:22.884 -> RLB_PRO: [MAC] 0x06
13:09:22.884 -> RLB_PRO: DevStatusReq
13:09:22.884 -> RLB_PRO: DevStatusAns: status = 0x0892
13:09:22.916 -> <MAC commands only>
13:09:22.916 -> [LoRaWAN] RSSI: -50.00 dBm
13:09:22.916 -> [LoRaWAN] SNR: 8.00 dB
13:09:22.916 -> [LoRaWAN] Frequency error: 1845.47 Hz
13:09:22.916 -> [LoRaWAN] Event information:
13:09:22.916 -> [LoRaWAN] Confirmed: 0
13:09:22.916 -> [LoRaWAN] Confirming: 1
13:09:22.916 -> [LoRaWAN] Datarate: 4
13:09:22.916 -> [LoRaWAN] Frequency: 867.700 MHz
13:09:22.916 -> [LoRaWAN] Output power: 14 dBm
13:09:22.916 -> [LoRaWAN] Frame count: 0
13:09:22.916 -> [LoRaWAN] Port: 0
13:09:22.916 -> [LoRaWAN] DeviceTime Unix: 1712056157
13:09:22.952 -> [LoRaWAN] DeviceTime second: 1/152
13:09:22.952 -> [LoRaWAN] Next uplink in 1194s
13:29:17.439 -> [LoRaWAN] Requesting LinkCheck and DeviceTime
13:29:17.472 -> RLB_PRO:
13:29:17.472 -> RLB_PRO: PHY: Frequency UL = 868.300 MHz
13:29:17.472 -> RLB_PRO: PHY: SF = 8, BW = 125.000 kHz, CR = 4/5
13:29:17.504 -> RLB_PRO: Uplink MAC payload (3 commands):
13:29:17.504 -> RLB_PRO: 0000000 03 07 06 08 92 0d | ......
13:29:17.504 -> RLB_PRO: Uplink (FcntUp = 1) decoded:
13:29:17.504 -> RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
13:29:17.504 -> RLB_PRO: 0000010 80 85 7e 0b 26 86 01 00 c0 db 47 c2 09 96 0a 1e | ..~.&.....G.....
13:29:17.504 -> RLB_PRO: 0000020 54 0b ce 3f 14 00 | T..?..
13:29:17.627 -> RLB_DBG: Timeout in 514 ms
13:29:17.752 -> RLB_PRO: Uplink sent <-- Rx Delay start
13:29:17.752 -> RLB_PRO:
13:29:17.752 -> RLB_PRO: PHY: Frequency DL = 868.300 MHz
13:29:17.752 -> RLB_PRO: PHY: SF = 8, BW = 125.000 kHz, CR = 4/5
13:29:22.733 -> RLB_PRO: Opening Rx1 window (71712 us timeout)... <-- Rx Delay end
13:29:22.811 -> RLB_PRO: Closing Rx1 window
13:29:22.843 -> RLB_PRO: Downlink (NFcntDown = 1) encoded:
13:29:22.843 -> RLB_PRO: 0000000 49 01 00 00 00 01 85 7e 0b 26 01 00 00 00 00 13 | I......~.&......
13:29:22.843 -> RLB_PRO: 0000010 60 85 7e 0b 26 ab 01 00 03 34 fa d6 f2 56 bb 22 | `.~.&....4...V."
13:29:22.843 -> RLB_PRO: 0000020 da 07 5c 49 46 94 94 | ..\IF..
13:29:22.875 -> RLB_PRO: [MAC] 0x0D
13:29:22.875 -> RLB_PRO: 0000000 9f ae 36 53 f0 | ..6S.
13:29:22.875 -> RLB_PRO: DeviceTimeAns: [user]
13:29:22.875 -> RLB_PRO: [MAC] 0x03
13:29:22.875 -> RLB_PRO: 0000000 50 ff 00 01 | P...
13:29:22.875 -> RLB_PRO: LinkADRReq: dataRate = 5, txPower = 0, chMask = 0x00ff, chMaskCntl = 0, nbTrans = 1
13:29:22.875 -> RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
13:29:22.908 -> RLB_PRO: PHY: TX = 16 dBm
13:29:22.908 -> RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
13:29:22.908 -> RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
13:29:22.908 -> RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
13:29:22.908 -> RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
13:29:22.908 -> RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
13:29:22.908 -> RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
13:29:22.940 -> RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
13:29:22.940 -> RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
13:29:22.940 -> RLB_PRO: LinkADRAns: status = 0x07
13:29:22.940 -> <MAC commands only>
13:29:22.940 -> [LoRaWAN] RSSI: -49.00 dBm
13:29:22.940 -> [LoRaWAN] SNR: 13.00 dB
13:29:22.940 -> [LoRaWAN] Frequency error: 1902.63 Hz
13:29:22.940 -> [LoRaWAN] Event information:
13:29:22.940 -> [LoRaWAN] Confirmed: 0
13:29:22.987 -> [LoRaWAN] Confirming: 1
13:29:22.987 -> [LoRaWAN] Datarate: 5
13:29:22.987 -> [LoRaWAN] Frequency: 868.300 MHz
13:29:22.987 -> [LoRaWAN] Output power: 16 dBm
13:29:22.987 -> [LoRaWAN] Frame count: 1
13:29:22.987 -> [LoRaWAN] Port: 0
13:29:22.987 -> [LoRaWAN] DeviceTime Unix: 1712057357
13:29:22.987 -> [LoRaWAN] DeviceTime second: 1/240
13:29:22.987 -> [LoRaWAN] Next uplink in 294s
13:34:17.794 -> RLB_PRO:
13:34:17.794 -> RLB_PRO: PHY: Frequency UL = 867.900 MHz
13:34:17.843 -> RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
13:34:17.843 -> RLB_PRO: Uplink MAC payload (1 commands):
13:34:17.843 -> RLB_PRO: 0000000 03 07 | ..
13:34:17.843 -> RLB_PRO: Uplink (FcntUp = 2) decoded:
13:34:17.843 -> RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
13:34:17.843 -> RLB_PRO: 0000010 40 85 7e 0b 26 82 02 00 32 77 0a 15 8d ad 00 00 | @.~.&...2w......
13:34:17.868 -> RLB_PRO: 0000020 14 00 | ..
13:34:17.944 -> RLB_DBG: Timeout in 257 ms
13:34:18.012 -> RLB_PRO: Uplink sent <-- Rx Delay start
13:34:18.012 -> RLB_PRO:
13:34:18.012 -> RLB_PRO: PHY: Frequency DL = 867.900 MHz
13:34:18.012 -> RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
13:34:23.029 -> RLB_PRO: Opening Rx1 window (45856 us timeout)... <-- Rx Delay end
13:34:23.076 -> RLB_PRO: Closing Rx1 window
13:34:23.076 -> RLB_PRO: PHY: Frequency DL = 869.525 MHz
13:34:23.076 -> RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
13:34:24.033 -> RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
13:34:24.160 -> RLB_PRO: Closing Rx2 window
13:34:24.160 -> [LoRaWAN] Next uplink in 180s
13:37:24.211 -> RLB_PRO:
13:37:24.211 -> RLB_PRO: PHY: Frequency UL = 867.500 MHz
13:37:24.211 -> RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
13:37:24.211 -> RLB_PRO: Uplink (FcntUp = 3) decoded:
13:37:24.252 -> RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
13:37:24.252 -> RLB_PRO: 0000010 40 85 7e 0b 26 80 03 00 0a 08 3c b0 7b af 00 00 | @.~.&.....<.{...
13:37:24.351 -> RLB_DBG: Timeout in 257 ms
13:37:24.419 -> RLB_PRO: Uplink sent <-- Rx Delay start
13:37:24.419 -> RLB_PRO:
13:37:24.419 -> RLB_PRO: PHY: Frequency DL = 867.500 MHz
13:37:24.419 -> RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
13:37:29.418 -> RLB_PRO: Opening Rx1 window (45856 us timeout)... <-- Rx Delay end
13:37:29.496 -> RLB_PRO: Closing Rx1 window
13:37:29.496 -> RLB_PRO: PHY: Frequency DL = 869.525 MHz
13:37:29.496 -> RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
13:37:30.431 -> RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
13:37:30.549 -> RLB_PRO: Closing Rx2 window
13:37:30.549 -> [LoRaWAN] Next uplink in 180s
Looks like it works without problem (reboot) - will not touch it for some time ... ;)
from radiolib.
StevenCellist
commented on June 14, 2024
1
Thanks for the troubleshooting! Behaviour replicated, got some logs - I like big logs, I cannot lie. To be continued.
from radiolib.
michapr
commented on June 14, 2024
1
Lots of other deadlines upcoming...
Yes, of course. This has no priority, I think so.
Will check the memory, thanks for the hint.
from radiolib.
HeadBoffin
commented on June 14, 2024
Thanks for this - can you show the log BEFORE the crash, what happens after the reboot is useful but not quite as important as what happens leading up to the stack smash.
from radiolib.
michapr
commented on June 14, 2024
before is the same as in the end of the log - next will be the same reboot (after the delay) ... No more information available...
09:30:04.830 -> [LoRaWAN] LinkCheck count: 1
09:30:04.830 -> [LoRaWAN] DeviceTime Unix: 1711787399
09:30:04.830 -> [LoRaWAN] DeviceTime second: 1/51
09:30:04.830 -> [LoRaWAN] Next uplink in 1194s
from radiolib.
HeadBoffin
commented on June 14, 2024
No more information available...
So it's not happening anymore to recreate the log?
Version numbering at library level is making tracing the test logs rather messy - so we'll improve our process to help with that - I'll create a clean development folder to look at this one - I wouldn't normally do a LinkCheck just after a join as it's a bit redundant and means the gateway has gone deaf twice in short order. Consequently the reference shows the API calls but isn't really best practise. Watch this space -> [ ]
from radiolib.
StevenCellist
commented on June 14, 2024
This issue is fixed with the referenced commit. Thank you for reporting! If you would want to use DeviceTimeReq or LinkCheckReq, make sure to use the latest version on GitHub; be aware that setCSMA() just got disabled, so account for that if you were to update.
from radiolib.
michapr
commented on June 14, 2024
Sorry to say,... but I have got again a reboot after some time...
Same sketch with updated files and disabled setCSMA()
For better tracking I have modified teh payload only
uint8_t uplinkPayload[4];
uplinkPayload[0] = highByte(fcntUp);
uplinkPayload[1] = lowByte(fcntUp); // See notes for high/lowByte functions
uplinkPayload[2] = highByte(fcntDn);
uplinkPayload[3] = lowByte(fcntDn);
Hewre is the "long debug" - maybe you can see here anything or have an idea...
[05/04/24 - 20:06:43.377] RLB_PRO:
[05/04/24 - 20:06:43.379] RLB_PRO: PHY: Frequency UL = 867.100 MHz
[05/04/24 - 20:06:43.385] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:06:43.389] RLB_PRO: Uplink (FcntUp = 504) decoded:
[05/04/24 - 20:06:43.393] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 20:06:43.401] RLB_PRO: 0000010 40 29 f1 0b 26 80 f8 01 0a f1 ae 15 8a af 00 00 | @)..&...........
[05/04/24 - 20:06:43.409] RLB_PRO: 0000020 14 | .
[05/04/24 - 20:06:43.415] RLB_DBG: Timeout in 257 ms
[05/04/24 - 20:06:43.468] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 20:06:43.472] RLB_PRO:
[05/04/24 - 20:06:43.473] RLB_PRO: PHY: Frequency DL = 867.100 MHz
[05/04/24 - 20:06:43.476] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:06:48.465] RLB_PRO: Opening Rx1 window (45856 us timeout)... <-- Rx Delay end
[05/04/24 - 20:06:48.515] RLB_PRO: Closing Rx1 window
[05/04/24 - 20:06:48.517] RLB_PRO: PHY: Frequency DL = 869.525 MHz
[05/04/24 - 20:06:48.522] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:06:49.464] RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
[05/04/24 - 20:06:49.593] RLB_PRO: Closing Rx2 window
[05/04/24 - 20:06:49.595] [LoRaWAN] Next uplink in 180s
[05/04/24 - 20:06:49.598]
[05/04/24 - 20:09:49.665] RLB_PRO:
[05/04/24 - 20:09:49.666] RLB_PRO: PHY: Frequency UL = 867.100 MHz
[05/04/24 - 20:09:49.672] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:09:49.676] RLB_PRO: Uplink (FcntUp = 505) decoded:
[05/04/24 - 20:09:49.681] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 20:09:49.686] RLB_PRO: Guru Meditation Error: Core 1 panic'ed (LoadProhibited). Exception was unhandled.
[05/04/24 - 20:09:49.695]
[05/04/24 - 20:09:49.695]
[05/04/24 - 20:09:49.695]
[05/04/24 - 20:09:49.696] Core 1 register dump:
[05/04/24 - 20:09:49.697]
[05/04/24 - 20:09:49.697] PC : 0x400556b4 PS : 0x00060c30 A0 : 0x8201f9f8 A1 : 0x3fce29f0
[05/04/24 - 20:09:49.709]
[05/04/24 - 20:09:49.709] A2 : 0xa70fe3f9 A3 : 0xa70fe3f5 A4 : 0x000000ff A5 : 0x0000ff00
[05/04/24 - 20:09:49.713]
[05/04/24 - 20:09:49.713] A6 : 0x00ff0000 A7 : 0xff000000 A8 : 0x6b6e5500 A9 : 0x3fce2d30
[05/04/24 - 20:09:49.721]
[05/04/24 - 20:09:49.721] A10 : 0x00060c23 A11 : 0x00000000 A12 : 0x00060c20 A13 : 0x00000000
[05/04/24 - 20:09:49.729]
[05/04/24 - 20:09:49.729] A14 : 0x02ce338c A15 : 0x00ffffff SAR : 0x00000015 EXCCAUSE: 0x0000001c
[05/04/24 - 20:09:49.738]
[05/04/24 - 20:09:49.738] EXCVADDR: 0xa70fe3f9 LBEG : 0x400556d5 LEND : 0x400556e5 LCOUNT : 0xffffffff
[05/04/24 - 20:09:49.744]
[05/04/24 - 20:09:49.744]
[05/04/24 - 20:09:49.744]
[05/04/24 - 20:09:49.744]
[05/04/24 - 20:09:49.744]
[05/04/24 - 20:09:49.745] Backtrace:0x400556b1:0x3fce29f0 |<-CORRUPTED
[05/04/24 - 20:09:49.748]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749] ELF file SHA256: 0000000000000000
[05/04/24 - 20:09:49.752]
[05/04/24 - 20:09:49.752]
[05/04/24 - 20:09:49.752]
[05/04/24 - 20:09:49.752] Rebooting...
[05/04/24 - 20:09:49.754]
[05/04/24 - 20:09:49.754] [05/04/24 - 20:09:49.754] xøESP-ROM:esp32s3-20210327
[05/04/24 - 20:09:49.762]
[05/04/24 - 20:09:49.762] Build:Mar 27 2021
[05/04/24 - 20:09:49.772]
[05/04/24 - 20:09:49.772] rst:0xc (RTC_SW_CPU_RST),boot:0x29 (SPI_FAST_FLASH_BOOT)
[05/04/24 - 20:09:49.773]
[05/04/24 - 20:09:49.774] Saved PC:0x4202b86e
[05/04/24 - 20:09:49.774]
[05/04/24 - 20:09:49.774] SPIWP:0xee
[05/04/24 - 20:09:49.774]
[05/04/24 - 20:09:49.774] mode:DIO, clock div:1
[05/04/24 - 20:09:49.775]
[05/04/24 - 20:09:49.775] load:0x3fce3808,len:0x43c
[05/04/24 - 20:09:49.776]
[05/04/24 - 20:09:49.776] load:0x403c9700,len:0xbec
[05/04/24 - 20:09:49.778]
[05/04/24 - 20:09:49.779] load:0x403cc700,len:0x2a3c
[05/04/24 - 20:09:49.781]
[05/04/24 - 20:09:49.781] SHA-256 comparison failed:
[05/04/24 - 20:09:49.783]
[05/04/24 - 20:09:49.783] Calculated: dcde8d8a4817d9bf5d5d69a7247667264e4e10ac7493514868b61f5aa6146539
[05/04/24 - 20:09:49.790]
[05/04/24 - 20:09:49.790] Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
[05/04/24 - 20:09:49.800]
[05/04/24 - 20:09:49.800] Attempting to boot anyway...
[05/04/24 - 20:09:49.800]
[05/04/24 - 20:09:49.800] entry 0x403c98d8
[05/04/24 - 20:09:49.801]
[05/04/24 - 20:09:54.887]
[05/04/24 - 20:09:54.887] Setup
[05/04/24 - 20:09:54.888]
[05/04/24 - 20:09:54.888] Initalise the radio
[05/04/24 - 20:09:54.889]
[05/04/24 - 20:09:54.889] RLB_DBG: RadioLib Debug Info
[05/04/24 - 20:09:54.891] RLB_DBG: Version: 6.5.0.0
[05/04/24 - 20:09:54.894] RLB_DBG: Platform: ESP32
[05/04/24 - 20:09:54.897] RLB_DBG: Compiled: Apr 3 2024 17:35:11
[05/04/24 - 20:09:54.900]
[05/04/24 - 20:09:54.911] RLB_DBG: Found SX126x: RADIOLIB_SX126X_REG_VERSION_STRING:
[05/04/24 - 20:09:54.916] RLB_DBG: 0000320 53 58 31 32 36 31 20 56 32 44 20 32 44 30 32 00 | SX1261 V2D 2D02.
[05/04/24 - 20:09:54.923] RLB_DBG:
[05/04/24 - 20:09:54.924] RLB_DBG: M SX126x
[05/04/24 - 20:09:54.960] Join ('login') to the LoRaWAN Network
[05/04/24 - 20:09:54.963]
[05/04/24 - 20:09:54.964] RLB_PRO: Configuration mismatch (checksum: 0, mode: 0, class: 0, plan: 1)
[05/04/24 - 20:09:54.969] RLB_PRO: Nonces buffer:
[05/04/24 - 20:09:54.972] RLB_PRO: 0000000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................
[05/04/24 - 20:09:54.979] RLB_PRO: Clearing buffer and starting fresh
[05/04/24 - 20:09:54.984] RLB_PRO: Setting up dynamic channels
[05/04/24 - 20:09:54.986] RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
[05/04/24 - 20:09:54.992] RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
[05/04/24 - 20:09:54.997] RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
[05/04/24 - 20:09:55.003] RLB_PRO: [MAC] 0x03
[05/04/24 - 20:09:55.004] RLB_PRO: 0000000 20 00 00 80 | ...
[05/04/24 - 20:09:55.012] RLB_PRO: LinkADRReq: dataRate = 2, txSteps = 0, chMask = 0x0000, chMaskCntl = 0, nbTrans = 0
[05/04/24 - 20:09:55.022] RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:09:55.024] RLB_PRO: PHY: TX = 16 dBm
[05/04/24 - 20:09:55.027] RLB_PRO: LinkADRAns: status = 0x07
[05/04/24 - 20:09:55.030] RLB_PRO: [MAC] 0x04
[05/04/24 - 20:09:55.031] RLB_PRO: 0000000 07 | .
[05/04/24 - 20:09:55.039] RLB_PRO: DutyCycleReq: max duty cycle = 1/2^7
[05/04/24 - 20:09:55.043] RLB_PRO: [MAC] 0x05
[05/04/24 - 20:09:55.045] RLB_PRO: 0000000 00 d2 ad 84 | ....
[05/04/24 - 20:09:55.052] RLB_PRO: RXParamSetupReq: rx1DrOffset = 0, rx2DataRate = 0, freq = 869.525024
[05/04/24 - 20:09:55.061] RLB_PRO: RXParamSetupAns: status = 0x07
[05/04/24 - 20:09:55.065] RLB_PRO: [MAC] 0x08
[05/04/24 - 20:09:55.066] RLB_PRO: 0000000 01 | .
[05/04/24 - 20:09:55.074] RLB_PRO: RXTimingSetupReq: delay = 1 sec
[05/04/24 - 20:09:55.082] RLB_PRO: [MAC] 0x09
[05/04/24 - 20:09:55.082] RLB_PRO: 0000000 05 | .
[05/04/24 - 20:09:55.087] RLB_PRO: TxParamSetupReq: dlDwell = 0, ulDwell = 0, maxEirp = 16 dBm
[05/04/24 - 20:09:55.094] RLB_PRO: [MAC] 0x0C
[05/04/24 - 20:09:55.094] RLB_PRO: 0000000 65 | e
[05/04/24 - 20:09:55.102] RLB_PRO: ADRParamSetupReq: limitExp = 6, delayExp = 5
[05/04/24 - 20:09:55.107] RLB_PRO: [MAC] 0x0F
[05/04/24 - 20:09:55.112] RLB_PRO: 0000000 fa | .
[05/04/24 - 20:09:55.116] RLB_PRO: RejoinParamSetupReq: maxTime = 15, maxCount = 10
[05/04/24 - 20:09:55.121] RLB_PRO: RejoinParamSetupAns: status = 0x03
[05/04/24 - 20:09:55.192] RLB_PRO:
[05/04/24 - 20:09:55.193] RLB_PRO: PHY: Frequency UL = 868.100 MHz
[05/04/24 - 20:09:55.199] RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:09:55.203] RLB_DBG: Timeout in 1853 ms
[05/04/24 - 20:09:55.579] RLB_PRO: Join-request sent <-- Rx Delay start
[05/04/24 - 20:09:55.583] RLB_PRO:
[05/04/24 - 20:09:55.583] RLB_PRO: PHY: Frequency DL = 868.100 MHz
[05/04/24 - 20:09:55.587] RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:10:00.575] RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end
[05/04/24 - 20:10:00.806] RLB_PRO: Closing Rx1 window
[05/04/24 - 20:10:01.040] RLB_PRO: joinAcceptMsg:
[05/04/24 - 20:10:01.042] RLB_PRO: 0000000 20 6b 00 00 13 00 00 98 eb 0b 26 83 05 18 4f 84 | k........&...O.
[05/04/24 - 20:10:01.049] RLB_PRO: 0000010 e8 56 84 b8 5e 84 88 66 84 58 6e 84 00 38 97 85 | .V..^..f.Xn..8..
[05/04/24 - 20:10:01.056] RLB_PRO: 0000020 cc | .
[05/04/24 - 20:10:01.064] RLB_PRO: JoinNoncePrev: 0, JoinNonce: 107
[05/04/24 - 20:10:01.067] RLB_PRO: LoRaWAN revision: 1.1
[05/04/24 - 20:10:01.070] RLB_PRO: [MAC] 0x05
[05/04/24 - 20:10:01.072] RLB_PRO: 0000000 03 d2 ad 84 | ....
[05/04/24 - 20:10:01.079] RLB_PRO: RXParamSetupReq: rx1DrOffset = 0, rx2DataRate = 3, freq = 869.525024
[05/04/24 - 20:10:01.097] RLB_PRO: RXParamSetupAns: status = 0x07
[05/04/24 - 20:10:01.100] RLB_PRO: [MAC] 0x08
[05/04/24 - 20:10:01.102] RLB_PRO: 0000000 05 | .
[05/04/24 - 20:10:01.109] RLB_PRO: RXTimingSetupReq: delay = 5 sec
[05/04/24 - 20:10:01.113] RLB_PRO: Setting up dynamic channels
[05/04/24 - 20:10:01.116] RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
[05/04/24 - 20:10:01.122] RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
[05/04/24 - 20:10:01.127] RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
[05/04/24 - 20:10:01.133] RLB_PRO: Processing CFList
[05/04/24 - 20:10:01.135] RLB_PRO: [MAC] 0x07
[05/04/24 - 20:10:01.137] RLB_PRO: 0000000 03 18 4f 84 50 | ..O.P
[05/04/24 - 20:10:01.152] RLB_PRO: NewChannelReq:
[05/04/24 - 20:10:01.155] RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
[05/04/24 - 20:10:01.162] RLB_PRO: NewChannelAns: status = 0x03
[05/04/24 - 20:10:01.163] RLB_PRO: [MAC] 0x07
[05/04/24 - 20:10:01.165] RLB_PRO: 0000000 04 e8 56 84 50 | ..V.P
[05/04/24 - 20:10:01.176] RLB_PRO: NewChannelReq:
[05/04/24 - 20:10:01.178] RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
[05/04/24 - 20:10:01.184] RLB_PRO: NewChannelAns: status = 0x03
[05/04/24 - 20:10:01.192] RLB_PRO: [MAC] 0x07
[05/04/24 - 20:10:01.192] RLB_PRO: 0000000 05 b8 5e 84 50 | ..^.P
[05/04/24 - 20:10:01.201] RLB_PRO: NewChannelReq:
[05/04/24 - 20:10:01.203] RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
[05/04/24 - 20:10:01.208] RLB_PRO: NewChannelAns: status = 0x03
[05/04/24 - 20:10:01.211] RLB_PRO: [MAC] 0x07
[05/04/24 - 20:10:01.213] RLB_PRO: 0000000 06 88 66 84 50 | ..f.P
[05/04/24 - 20:10:01.225] RLB_PRO: NewChannelReq:
[05/04/24 - 20:10:01.227] RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
[05/04/24 - 20:10:01.233] RLB_PRO: NewChannelAns: status = 0x03
[05/04/24 - 20:10:01.236] RLB_PRO: [MAC] 0x07
[05/04/24 - 20:10:01.237] RLB_PRO: 0000000 07 58 6e 84 50 | .Xn.P
[05/04/24 - 20:10:01.252] RLB_PRO: NewChannelReq:
[05/04/24 - 20:10:01.253] RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
[05/04/24 - 20:10:01.257] RLB_PRO: NewChannelAns: status = 0x03
[05/04/24 - 20:10:01.260] [LoRaWAN] DevAddr: 260BEB98
[05/04/24 - 20:10:01.262]
[05/04/24 - 20:10:01.263] Ready!
[05/04/24 - 20:10:01.263]
[05/04/24 - 20:10:01.263]
[05/04/24 - 20:10:01.263] [LoRaWAN] Requesting LinkCheck and DeviceTime
[05/04/24 - 20:10:01.268]
[05/04/24 - 20:10:01.329] RLB_PRO:
[05/04/24 - 20:10:01.331] RLB_PRO: PHY: Frequency UL = 868.500 MHz
[05/04/24 - 20:10:01.336] RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:10:01.340] RLB_PRO: Uplink MAC payload (2 commands):
[05/04/24 - 20:10:01.344] RLB_PRO: 0000000 0b 01 0d | ...
[05/04/24 - 20:10:01.351] RLB_PRO: Uplink (FcntUp = 0) decoded:
[05/04/24 - 20:10:01.358] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 20:10:01.363] RLB_PRO: 0000010 80 98 eb 0b 26 83 00 00 6b 70 06 0a b8 53 73 ec | ....&...kp...Ss.
[05/04/24 - 20:10:01.370] RLB_PRO: 0000020 14 00 ce 3f | ...?
[05/04/24 - 20:10:01.376] RLB_DBG: Timeout in 1853 ms
[05/04/24 - 20:10:01.748] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 20:10:01.752] RLB_PRO:
[05/04/24 - 20:10:01.753] RLB_PRO: PHY: Frequency DL = 868.500 MHz
[05/04/24 - 20:10:01.757] RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:10:06.744] RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end
[05/04/24 - 20:10:06.976] RLB_PRO: Closing Rx1 window
[05/04/24 - 20:10:06.980] RLB_PRO: PHY: Frequency DL = 869.525 MHz
[05/04/24 - 20:10:06.983] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:10:07.746] RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
[05/04/24 - 20:10:07.873] RLB_PRO: Closing Rx2 window
[05/04/24 - 20:10:07.876] [LoRaWAN] Next uplink in 1193s
[05/04/24 - 20:10:07.878]
[05/04/24 - 20:30:01.768] [LoRaWAN] Requesting LinkCheck and DeviceTime
[05/04/24 - 20:30:01.773]
[05/04/24 - 20:30:01.836] RLB_PRO:
[05/04/24 - 20:30:01.838] RLB_PRO: PHY: Frequency UL = 867.900 MHz
[05/04/24 - 20:30:01.842] RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:30:01.848] RLB_PRO: Uplink MAC payload (2 commands):
[05/04/24 - 20:30:01.852] RLB_PRO: 0000000 0b 01 0d | ...
[05/04/24 - 20:30:01.859] RLB_PRO: Uplink (FcntUp = 1) decoded:
[05/04/24 - 20:30:01.866] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 20:30:01.870] RLB_PRO: 0000010 80 98 eb 0b 26 83 01 00 66 bf b9 0a ab 80 42 1e | ....&...f.....B.
[05/04/24 - 20:30:01.877] RLB_PRO: 0000020 14 00 ce 3f | ...?
[05/04/24 - 20:30:01.885] RLB_DBG: Timeout in 1853 ms
[05/04/24 - 20:30:02.256] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 20:30:02.260] RLB_PRO:
[05/04/24 - 20:30:02.261] RLB_PRO: PHY: Frequency DL = 867.900 MHz
[05/04/24 - 20:30:02.264] RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:30:07.252] RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end
[05/04/24 - 20:30:07.482] RLB_PRO: Closing Rx1 window
[05/04/24 - 20:30:07.634] RLB_PRO: Downlink (NFcntDown = 0) encoded:
[05/04/24 - 20:30:07.638] RLB_PRO: 0000000 49 01 00 00 00 01 98 eb 0b 26 00 00 00 00 00 15 | I........&......
[05/04/24 - 20:30:07.645] RLB_PRO: 0000010 60 98 eb 0b 26 ad 00 00 47 39 07 98 32 4a 54 b3 | `...&...G9..2JT.
[05/04/24 - 20:30:07.652] RLB_PRO: 0000020 c6 c2 a7 67 c4 c5 04 84 32 | ...g....2
[05/04/24 - 20:30:07.660] RLB_PRO: [MAC] 0x0B
[05/04/24 - 20:30:07.662] RLB_PRO: 0000000 01 | .
[05/04/24 - 20:30:07.669] RLB_PRO: RekeyConf: server version = 1.1
[05/04/24 - 20:30:07.675] RLB_PRO: [MAC] 0x0D
[05/04/24 - 20:30:07.675] RLB_PRO: 0000000 bc 05 3b 53 69 | ..;Si
[05/04/24 - 20:30:07.682] RLB_PRO: DeviceTimeAns: [user]
[05/04/24 - 20:30:07.685] RLB_PRO: [MAC] 0x03
[05/04/24 - 20:30:07.687] RLB_PRO: 0000000 f1 ff 00 01 | ....
[05/04/24 - 20:30:07.694] RLB_PRO: LinkADRReq: dataRate = 15, txSteps = 1, chMask = 0x00ff, chMaskCntl = 0, nbTrans = 1
[05/04/24 - 20:30:07.705] RLB_PRO: PHY: TX = 14 dBm
[05/04/24 - 20:30:07.705] RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
[05/04/24 - 20:30:07.711] RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
[05/04/24 - 20:30:07.716] RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
[05/04/24 - 20:30:07.721] RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
[05/04/24 - 20:30:07.727] RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
[05/04/24 - 20:30:07.735] RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
[05/04/24 - 20:30:07.738] RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
[05/04/24 - 20:30:07.743] RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
[05/04/24 - 20:30:07.749] RLB_PRO: LinkADRAns: status = 0x07
[05/04/24 - 20:30:07.752] <MAC commands only>
[05/04/24 - 20:30:07.754]
[05/04/24 - 20:30:07.754] [LoRaWAN] RSSI: -47.00 dBm
[05/04/24 - 20:30:07.756]
[05/04/24 - 20:30:07.756] [LoRaWAN] SNR: 8.25 dB
[05/04/24 - 20:30:07.758]
[05/04/24 - 20:30:07.758] [LoRaWAN] Frequency error: 1828.03 Hz
[05/04/24 - 20:30:07.765]
[05/04/24 - 20:30:07.765] [LoRaWAN] Event information:
[05/04/24 - 20:30:07.766]
[05/04/24 - 20:30:07.766] [LoRaWAN] Confirmed: 0
[05/04/24 - 20:30:07.766]
[05/04/24 - 20:30:07.766] [LoRaWAN] Confirming: 1
[05/04/24 - 20:30:07.769]
[05/04/24 - 20:30:07.769] [LoRaWAN] Datarate: 2
[05/04/24 - 20:30:07.771]
[05/04/24 - 20:30:07.771] [LoRaWAN] Frequency: 867.900 MHz
[05/04/24 - 20:30:07.773]
[05/04/24 - 20:30:07.774] [LoRaWAN] Output power: 14 dBm
[05/04/24 - 20:30:07.776]
[05/04/24 - 20:30:07.776] [LoRaWAN] Frame count: 0
[05/04/24 - 20:30:07.779]
[05/04/24 - 20:30:07.779] [LoRaWAN] Port: 0
[05/04/24 - 20:30:07.780]
[05/04/24 - 20:30:07.780] [LoRaWAN] DeviceTime Unix: 1712341802
[05/04/24 - 20:30:07.784]
[05/04/24 - 20:30:07.784] [LoRaWAN] DeviceTime second: 1/105
[05/04/24 - 20:30:07.787]
[05/04/24 - 20:30:07.787] [LoRaWAN] Next uplink in 1194s
[05/04/24 - 20:30:07.790]
[05/04/24 - 20:50:02.343] RLB_PRO:
[05/04/24 - 20:50:02.345] RLB_PRO: PHY: Frequency UL = 867.500 MHz
[05/04/24 - 20:50:02.350] RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:50:02.355] RLB_PRO: Uplink MAC payload (1 commands):
[05/04/24 - 20:50:02.359] RLB_PRO: 0000000 03 07 | ..
[05/04/24 - 20:50:02.366] RLB_PRO: Uplink (FcntUp = 2) decoded:
[05/04/24 - 20:50:02.369] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 20:50:02.377] RLB_PRO: 0000010 40 98 eb 0b 26 82 02 00 d7 e6 0a b9 37 ca 7c 00 | @...&.......7.|.
[05/04/24 - 20:50:02.385] RLB_PRO: 0000020 14 00 ce | ...
[05/04/24 - 20:50:02.391] RLB_DBG: Timeout in 1648 ms
[05/04/24 - 20:50:02.722] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 20:50:02.725] RLB_PRO:
[05/04/24 - 20:50:02.726] RLB_PRO: PHY: Frequency DL = 867.500 MHz
[05/04/24 - 20:50:02.730] RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:50:07.718] RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end
[05/04/24 - 20:50:07.948] RLB_PRO: Closing Rx1 window
[05/04/24 - 20:50:08.060] RLB_PRO: Downlink (NFcntDown = 1) encoded:
[05/04/24 - 20:50:08.063] RLB_PRO: 0000000 49 00 00 00 00 01 98 eb 0b 26 01 00 00 00 00 0d | I........&......
[05/04/24 - 20:50:08.071] RLB_PRO: 0000010 60 98 eb 0b 26 85 01 00 02 86 c6 ae 9a cc 93 a2 | `...&...........
[05/04/24 - 20:50:08.078] RLB_PRO: 0000020 5d | ]
[05/04/24 - 20:50:08.085] RLB_PRO: [MAC] 0x03
[05/04/24 - 20:50:08.087] RLB_PRO: 0000000 30 ff 00 01 | 0...
[05/04/24 - 20:50:08.098] RLB_PRO: LinkADRReq: dataRate = 3, txSteps = 0, chMask = 0x00ff, chMaskCntl = 0, nbTrans = 1
[05/04/24 - 20:50:08.104] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:50:08.107] RLB_PRO: PHY: TX = 16 dBm
[05/04/24 - 20:50:08.109] RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
[05/04/24 - 20:50:08.115] RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
[05/04/24 - 20:50:08.120] RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
[05/04/24 - 20:50:08.130] RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
[05/04/24 - 20:50:08.133] RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
[05/04/24 - 20:50:08.137] RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
[05/04/24 - 20:50:08.142] RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
[05/04/24 - 20:50:08.148] RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
[05/04/24 - 20:50:08.153] RLB_PRO: LinkADRAns: status = 0x07
[05/04/24 - 20:50:08.158] <MAC commands only>
[05/04/24 - 20:50:08.158]
[05/04/24 - 20:50:08.158] [LoRaWAN] RSSI: -47.00 dBm
[05/04/24 - 20:50:08.159]
[05/04/24 - 20:50:08.159] [LoRaWAN] SNR: 7.75 dB
[05/04/24 - 20:50:08.162]
[05/04/24 - 20:50:08.163] [LoRaWAN] Frequency error: 1891.97 Hz
[05/04/24 - 20:50:08.165]
[05/04/24 - 20:50:08.165] [LoRaWAN] Event information:
[05/04/24 - 20:50:08.168]
[05/04/24 - 20:50:08.168] [LoRaWAN] Confirmed: 0
[05/04/24 - 20:50:08.170]
[05/04/24 - 20:50:08.170] [LoRaWAN] Confirming: 1
[05/04/24 - 20:50:08.173]
[05/04/24 - 20:50:08.173] [LoRaWAN] Datarate: 2
[05/04/24 - 20:50:08.175]
[05/04/24 - 20:50:08.175] [LoRaWAN] Frequency: 867.900 MHz
[05/04/24 - 20:50:08.178]
[05/04/24 - 20:50:08.178] [LoRaWAN] Output power: 14 dBm
[05/04/24 - 20:50:08.180]
[05/04/24 - 20:50:08.180] [LoRaWAN] Frame count: 0
[05/04/24 - 20:50:08.183]
[05/04/24 - 20:50:08.183] [LoRaWAN] Port: 0
[05/04/24 - 20:50:08.183]
[05/04/24 - 20:50:08.183] [LoRaWAN] Next uplink in 1194s
[05/04/24 - 20:50:08.190]
[05/04/24 - 21:10:02.809] RLB_PRO:
[05/04/24 - 21:10:02.812] RLB_PRO: PHY: Frequency UL = 867.500 MHz
[05/04/24 - 21:10:02.816] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 21:10:02.821] RLB_PRO: Uplink MAC payload (1 commands):
[05/04/24 - 21:10:02.824] RLB_PRO: 0000000 03 07 | ..
[05/04/24 - 21:10:02.832] RLB_PRO: Uplink (FcntUp = 3) decoded:
[05/04/24 - 21:10:02.835] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 21:10:02.845] RLB_PRO: 0000010 40 98 eb 0b 26 82 03 00 54 af 0a 92 af 9b 72 00 | @...&...T.....r.
[05/04/24 - 21:10:02.850] RLB_PRO: 0000020 14 00 ce | ...
[05/04/24 - 21:10:02.857] RLB_DBG: Timeout in 926 ms
[05/04/24 - 21:10:03.044] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 21:10:03.047] RLB_PRO:
[05/04/24 - 21:10:03.048] RLB_PRO: PHY: Frequency DL = 867.500 MHz
[05/04/24 - 21:10:03.052] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 21:10:08.039] RLB_PRO: Opening Rx1 window (123424 us timeout)... <-- Rx Delay end
[05/04/24 - 21:10:08.167] RLB_PRO: Closing Rx1 window
[05/04/24 - 21:10:08.223] RLB_PRO: Downlink (NFcntDown = 2) encoded:
[05/04/24 - 21:10:08.228] RLB_PRO: 0000000 49 00 00 00 00 01 98 eb 0b 26 02 00 00 00 00 0d | I........&......
[05/04/24 - 21:10:08.233] RLB_PRO: 0000010 60 98 eb 0b 26 85 02 00 ec d4 71 84 5c dd a3 6d | `...&.....q.\..m
[05/04/24 - 21:10:08.235] RLB_PRO: 0000020 83 | .
[05/04/24 - 21:10:08.238] RLB_PRO: [MAC] 0x03
[05/04/24 - 21:10:08.239] RLB_PRO: 0000000 4f ff 00 01 | O...
[05/04/24 - 21:10:08.248] RLB_PRO: LinkADRReq: dataRate = 4, txSteps = 15, chMask = 0x00ff, chMaskCntl = 0, nbTrans = 1
[05/04/24 - 21:10:08.261] RLB_PRO: PHY: SF = 8, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 21:10:08.262] RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
[05/04/24 - 21:10:08.266] RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
[05/04/24 - 21:10:08.272] RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
[05/04/24 - 21:10:08.277] RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
[05/04/24 - 21:10:08.282] RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
[05/04/24 - 21:10:08.291] RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
[05/04/24 - 21:10:08.293] RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
[05/04/24 - 21:10:08.297] RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
[05/04/24 - 21:10:08.304] RLB_PRO: LinkADRAns: status = 0x07
[05/04/24 - 21:10:08.307] <MAC commands only>
[05/04/24 - 21:10:08.309]
[05/04/24 - 21:10:08.309] [LoRaWAN] RSSI: -47.00 dBm
[05/04/24 - 21:10:08.310]
[05/04/24 - 21:10:08.311] [LoRaWAN] SNR: 11.00 dB
[05/04/24 - 21:10:08.314]
[05/04/24 - 21:10:08.314] [LoRaWAN] Frequency error: 1900.69 Hz
[05/04/24 - 21:10:08.322]
[05/04/24 - 21:10:08.322] [LoRaWAN] Event information:
[05/04/24 - 21:10:08.322]
[05/04/24 - 21:10:08.322] [LoRaWAN] Confirmed: 0
[05/04/24 - 21:10:08.322]
[05/04/24 - 21:10:08.323] [LoRaWAN] Confirming: 1
[05/04/24 - 21:10:08.324]
[05/04/24 - 21:10:08.324] [LoRaWAN] Datarate: 2
[05/04/24 - 21:10:08.326]
[05/04/24 - 21:10:08.326] [LoRaWAN] Frequency: 867.900 MHz
[05/04/24 - 21:10:08.329]
[05/04/24 - 21:10:08.329] [LoRaWAN] Output power: 14 dBm
[05/04/24 - 21:10:08.331]
[05/04/24 - 21:10:08.332] [LoRaWAN] Frame count: 0
[05/04/24 - 21:10:08.334]
[05/04/24 - 21:10:08.334] [LoRaWAN] Port: 0
[05/04/24 - 21:10:08.335]
[05/04/24 - 21:10:08.336] [LoRaWAN] Next uplink in 594s
[05/04/24 - 21:10:08.338]
[05/04/24 - 21:20:03.122] RLB_PRO:
[05/04/24 - 21:20:03.125] RLB_PRO: PHY: Frequency UL = 867.100 MHz
[05/04/24 - 21:20:03.130] RLB_PRO: PHY: SF = 8, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 21:20:03.134] RLB_PRO: Uplink MAC payload (1 commands):
[05/04/24 - 21:20:03.138] RLB_PRO: 0000000 03 07 | ..
[05/04/24 - 21:20:03.146] RLB_PRO: Uplink (FcntUp = 4) decoded:
[05/04/24 - 21:20:03.149] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 21:20:03.155] RLB_PRO: 0000010 40 98 eb 0b 26 82 04 00 d1 76 0a 10 89 70 39 00 | @...&....v...p9.
[05/04/24 - 21:20:03.168] RLB_PRO: 0000020 14 00 ce | ...
[05/04/24 - 21:20:03.171] RLB_DBG: Timeout in 514 ms
[05/04/24 - 21:20:03.274] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 21:20:03.278] RLB_PRO:
[05/04/24 - 21:20:03.280] RLB_PRO: PHY: Frequency DL = 867.100 MHz
[05/04/24 - 21:20:03.283] RLB_PRO: PHY: SF = 8, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 21:20:08.270] RLB_PRO: Opening Rx1 window (71712 us timeout)... <-- Rx Delay end
[05/04/24 - 21:20:08.346] RLB_PRO: Closing Rx1 window
[05/04/24 - 21:20:08.370] RLB_PRO: Downlink (NFcntDown = 3) encoded:
[05/04/24 - 21:20:08.374] RLB_PRO: 0000000 49 00 00 00 00 01 98 eb 0b 26 03 00 00 00 00 0d | I........&......
[05/04/24 - 21:20:08.381] RLB_PRO: 0000010 60 98 eb 0b 26 85 03 00 8a 46 84 cc 4c f1 75 2f | `...&....F..L.u/
[05/04/24 - 21:20:08.390] RLB_PRO: 0000020 fc | .
[05/04/24 - 21:20:08.395] RLB_PRO: [MAC] 0x03
[05/04/24 - 21:20:08.396] RLB_PRO: 0000000 5f ff 00 01 | _...
[05/04/24 - 21:20:08.404] RLB_PRO: LinkADRReq: dataRate = 5, txSteps = 15, chMask = 0x00ff, chMaskCntl = 0, nbTrans = 1
[05/04/24 - 21:20:08.413] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 21:20:08.417] RLB_PRO: UL: 0 1 868.100 (0 - 5) | DL: 0 1 868.100 (0 - 5)
[05/04/24 - 21:20:08.423] RLB_PRO: UL: 1 1 868.300 (0 - 5) | DL: 1 1 868.300 (0 - 5)
[05/04/24 - 21:20:08.428] RLB_PRO: UL: 2 1 868.500 (0 - 5) | DL: 2 1 868.500 (0 - 5)
[05/04/24 - 21:20:08.434] RLB_PRO: UL: 3 1 867.100 (0 - 5) | DL: 3 1 867.100 (0 - 5)
[05/04/24 - 21:20:08.439] RLB_PRO: UL: 4 1 867.300 (0 - 5) | DL: 4 1 867.300 (0 - 5)
[05/04/24 - 21:20:08.444] RLB_PRO: UL: 5 1 867.500 (0 - 5) | DL: 5 1 867.500 (0 - 5)
[05/04/24 - 21:20:08.450] RLB_PRO: UL: 6 1 867.700 (0 - 5) | DL: 6 1 867.700 (0 - 5)
[05/04/24 - 21:20:08.456] RLB_PRO: UL: 7 1 867.900 (0 - 5) | DL: 7 1 867.900 (0 - 5)
[05/04/24 - 21:20:08.461] RLB_PRO: LinkADRAns: status = 0x07
[05/04/24 - 21:20:08.465] <MAC commands only>
[05/04/24 - 21:20:08.466]
[05/04/24 - 21:20:08.466] [LoRaWAN] RSSI: -46.00 dBm
[05/04/24 - 21:20:08.469]
[05/04/24 - 21:20:08.469] [LoRaWAN] SNR: 13.50 dB
[05/04/24 - 21:20:08.471]
[05/04/24 - 21:20:08.471] [LoRaWAN] Frequency error: 1902.63 Hz
[05/04/24 - 21:20:08.479]
[05/04/24 - 21:20:08.479] [LoRaWAN] Event information:
[05/04/24 - 21:20:08.480]
[05/04/24 - 21:20:08.480] [LoRaWAN] Confirmed: 0
[05/04/24 - 21:20:08.481]
[05/04/24 - 21:20:08.481] [LoRaWAN] Confirming: 1
[05/04/24 - 21:20:08.481]
[05/04/24 - 21:20:08.481] [LoRaWAN] Datarate: 2
[05/04/24 - 21:20:08.483]
[05/04/24 - 21:20:08.483] [LoRaWAN] Frequency: 867.900 MHz
[05/04/24 - 21:20:08.486]
[05/04/24 - 21:20:08.486] [LoRaWAN] Output power: 14 dBm
[05/04/24 - 21:20:08.490]
[05/04/24 - 21:20:08.490] [LoRaWAN] Frame count: 0
[05/04/24 - 21:20:08.491]
[05/04/24 - 21:20:08.491] [LoRaWAN] Port: 0
[05/04/24 - 21:20:08.493]
[05/04/24 - 21:20:08.493] [LoRaWAN] Next uplink in 294s
[05/04/24 - 21:20:08.495]
[05/04/24 - 21:25:03.348] RLB_PRO:
from radiolib.
StevenCellist
commented on June 14, 2024
@michapr I'm not sure what the issue could be; the crash happens during a hexdump of the uplink message which does not appear related to something particular in the LW stack. However it could be the case that a memory leak occurs somewhere. That is however difficult to investigate and definitely no priority for me. You could help by printing the free memory before and after each up/downlink / sendReceive to see if anything strikes the eye, which could help. I will only have time in two or three weeks to investigate further. Lots of other deadlines upcoming...
from radiolib.
HeadBoffin
commented on June 14, 2024
@michapr, the debug log leading up to the crash is far more important than after as after the crash it is a clean slate.
Only 500 uplinks and a crash is less than idea. I've only 1 WSL v3 but I can let it run on a tighter uplink period on TTI to soak test this with reasonably frequent link checks & other pokey bits and full debug because we all know how much @StevenCellist loves big logs, he can not lie.
from radiolib.
michapr
commented on June 14, 2024
the debug log leading up to the crash is far more important than after as after the crash it is a clean slate.
yes, that's why I have added 2 cycles before reboot, I thought it is ok.
I had yesterday about 700 uplinks without reboot - before changing the code and restart.
I do not see any changes in memory in this time after about 400 uplinks - hope I made the right requests...(?)
[08/04/24 - 13:01:32.694]
HeapSize; FreeHeap; MinFreeHeap;MaxAllocHeap
395996; 369708; 364356; 294900
from radiolib.
HeadBoffin
commented on June 14, 2024
the debug log leading up to the crash is far more important than after as after the crash it is a clean slate.
yes, that's why I have added 2 cycles before reboot, I thought it is ok.
To me it looks like a partial log starting at 20:06:43.377 and a crash at 20:09:49.686 followed by a pile of uplinks. Is there another one to see?
from radiolib.
michapr
commented on June 14, 2024
To me it looks like a partial log starting at 20:06:43.377 and a crash at 20:09:49.686 followed by a pile of uplinks. Is there another one to see?
Right, was only ONE full cycle before, sorry.
Can give you more lines before, but I'm not sure, will you see more here...
[05/04/24 - 19:48:05.657] RLB_PRO:
[05/04/24 - 19:48:05.658] RLB_PRO: PHY: Frequency UL = 868.100 MHz
[05/04/24 - 19:48:05.664] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:48:05.669] RLB_PRO: Uplink (FcntUp = 498) decoded:
[05/04/24 - 19:48:05.672] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 19:48:05.680] RLB_PRO: 0000010 40 29 f1 0b 26 80 f2 01 0a bc 5a c3 6f af 00 00 | @)..&.....Z.o...
[05/04/24 - 19:48:05.690] RLB_PRO: 0000020 14 | .
[05/04/24 - 19:48:05.694] RLB_DBG: Timeout in 257 ms
[05/04/24 - 19:48:05.747] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 19:48:05.751] RLB_PRO:
[05/04/24 - 19:48:05.752] RLB_PRO: PHY: Frequency DL = 868.100 MHz
[05/04/24 - 19:48:05.755] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:48:10.744] RLB_PRO: Opening Rx1 window (45856 us timeout)... <-- Rx Delay end
[05/04/24 - 19:48:10.797] RLB_PRO: Closing Rx1 window
[05/04/24 - 19:48:10.800] RLB_PRO: PHY: Frequency DL = 869.525 MHz
[05/04/24 - 19:48:10.801] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:48:11.744] RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
[05/04/24 - 19:48:11.871] RLB_PRO: Closing Rx2 window
[05/04/24 - 19:48:11.875] [LoRaWAN] Next uplink in 180s
[05/04/24 - 19:48:11.877]
[05/04/24 - 19:51:11.944] RLB_PRO:
[05/04/24 - 19:51:11.946] RLB_PRO: PHY: Frequency UL = 868.100 MHz
[05/04/24 - 19:51:11.951] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:51:11.956] RLB_PRO: Uplink (FcntUp = 499) decoded:
[05/04/24 - 19:51:11.960] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 19:51:11.972] RLB_PRO: 0000010 40 29 f1 0b 26 80 f3 01 0a 6c 70 6c ce af 00 00 | @)..&....lpl....
[05/04/24 - 19:51:11.975] RLB_PRO: 0000020 14 | .
[05/04/24 - 19:51:11.981] RLB_DBG: Timeout in 257 ms
[05/04/24 - 19:51:12.033] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 19:51:12.039] RLB_PRO:
[05/04/24 - 19:51:12.039] RLB_PRO: PHY: Frequency DL = 868.100 MHz
[05/04/24 - 19:51:12.042] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:51:17.031] RLB_PRO: Opening Rx1 window (45856 us timeout)... <-- Rx Delay end
[05/04/24 - 19:51:17.081] RLB_PRO: Closing Rx1 window
[05/04/24 - 19:51:17.083] RLB_PRO: PHY: Frequency DL = 869.525 MHz
[05/04/24 - 19:51:17.088] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:51:18.031] RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
[05/04/24 - 19:51:18.159] RLB_PRO: Closing Rx2 window
[05/04/24 - 19:51:18.162] [LoRaWAN] Next uplink in 180s
[05/04/24 - 19:51:18.164]
[05/04/24 - 19:54:18.231] RLB_PRO:
[05/04/24 - 19:54:18.233] RLB_PRO: PHY: Frequency UL = 868.500 MHz
[05/04/24 - 19:54:18.238] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:54:18.242] RLB_PRO: Uplink (FcntUp = 500) decoded:
[05/04/24 - 19:54:18.246] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 19:54:18.253] RLB_PRO: 0000010 40 29 f1 0b 26 80 f4 01 0a b9 6e 51 08 af 00 00 | @)..&.....nQ....
[05/04/24 - 19:54:18.261] RLB_PRO: 0000020 14 | .
[05/04/24 - 19:54:18.268] RLB_DBG: Timeout in 257 ms
[05/04/24 - 19:54:18.320] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 19:54:18.324] RLB_PRO:
[05/04/24 - 19:54:18.325] RLB_PRO: PHY: Frequency DL = 868.500 MHz
[05/04/24 - 19:54:18.329] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:54:23.318] RLB_PRO: Opening Rx1 window (45856 us timeout)... <-- Rx Delay end
[05/04/24 - 19:54:23.368] RLB_PRO: Closing Rx1 window
[05/04/24 - 19:54:23.374] RLB_PRO: PHY: Frequency DL = 869.525 MHz
[05/04/24 - 19:54:23.375] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:54:24.318] RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
[05/04/24 - 19:54:24.446] RLB_PRO: Closing Rx2 window
[05/04/24 - 19:54:24.448] [LoRaWAN] Next uplink in 180s
[05/04/24 - 19:54:24.451]
[05/04/24 - 19:57:24.517] RLB_PRO:
[05/04/24 - 19:57:24.519] RLB_PRO: PHY: Frequency UL = 868.300 MHz
[05/04/24 - 19:57:24.524] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:57:24.529] RLB_PRO: Uplink (FcntUp = 501) decoded:
[05/04/24 - 19:57:24.533] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 19:57:24.540] RLB_PRO: 0000010 40 29 f1 0b 26 80 f5 01 0a 68 65 75 e8 af 00 00 | @)..&....heu....
[05/04/24 - 19:57:24.547] RLB_PRO: 0000020 14 | .
[05/04/24 - 19:57:24.560] RLB_DBG: Timeout in 257 ms
[05/04/24 - 19:57:24.607] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 19:57:24.611] RLB_PRO:
[05/04/24 - 19:57:24.613] RLB_PRO: PHY: Frequency DL = 868.300 MHz
[05/04/24 - 19:57:24.618] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:57:29.604] RLB_PRO: Opening Rx1 window (45856 us timeout)... <-- Rx Delay end
[05/04/24 - 19:57:29.654] RLB_PRO: Closing Rx1 window
[05/04/24 - 19:57:29.657] RLB_PRO: PHY: Frequency DL = 869.525 MHz
[05/04/24 - 19:57:29.661] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 19:57:30.604] RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
[05/04/24 - 19:57:30.733] RLB_PRO: Closing Rx2 window
[05/04/24 - 19:57:30.735] [LoRaWAN] Next uplink in 180s
[05/04/24 - 19:57:30.738]
[05/04/24 - 20:00:30.804] RLB_PRO:
[05/04/24 - 20:00:30.805] RLB_PRO: PHY: Frequency UL = 867.900 MHz
[05/04/24 - 20:00:30.811] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:00:30.816] RLB_PRO: Uplink (FcntUp = 502) decoded:
[05/04/24 - 20:00:30.819] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 20:00:30.827] RLB_PRO: 0000010 40 29 f1 0b 26 80 f6 01 0a 8c aa 07 d1 af 00 00 | @)..&...........
[05/04/24 - 20:00:30.835] RLB_PRO: 0000020 14 | .
[05/04/24 - 20:00:30.841] RLB_DBG: Timeout in 257 ms
[05/04/24 - 20:00:30.894] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 20:00:30.899] RLB_PRO:
[05/04/24 - 20:00:30.899] RLB_PRO: PHY: Frequency DL = 867.900 MHz
[05/04/24 - 20:00:30.903] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:00:35.891] RLB_PRO: Opening Rx1 window (45856 us timeout)... <-- Rx Delay end
[05/04/24 - 20:00:35.941] RLB_PRO: Closing Rx1 window
[05/04/24 - 20:00:35.944] RLB_PRO: PHY: Frequency DL = 869.525 MHz
[05/04/24 - 20:00:35.949] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:00:36.891] RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
[05/04/24 - 20:00:37.019] RLB_PRO: Closing Rx2 window
[05/04/24 - 20:00:37.022] [LoRaWAN] Next uplink in 180s
[05/04/24 - 20:00:37.024]
[05/04/24 - 20:03:37.091] RLB_PRO:
[05/04/24 - 20:03:37.092] RLB_PRO: PHY: Frequency UL = 868.500 MHz
[05/04/24 - 20:03:37.098] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:03:37.103] RLB_PRO: Uplink (FcntUp = 503) decoded:
[05/04/24 - 20:03:37.106] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 20:03:37.113] RLB_PRO: 0000010 40 29 f1 0b 26 80 f7 01 0a 53 19 48 64 af 00 00 | @)..&....S.Hd...
[05/04/24 - 20:03:37.121] RLB_PRO: 0000020 14 | .
[05/04/24 - 20:03:37.129] RLB_DBG: Timeout in 257 ms
[05/04/24 - 20:03:37.181] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 20:03:37.186] RLB_PRO:
[05/04/24 - 20:03:37.186] RLB_PRO: PHY: Frequency DL = 868.500 MHz
[05/04/24 - 20:03:37.190] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:03:42.177] RLB_PRO: Opening Rx1 window (45856 us timeout)... <-- Rx Delay end
[05/04/24 - 20:03:42.228] RLB_PRO: Closing Rx1 window
[05/04/24 - 20:03:42.231] RLB_PRO: PHY: Frequency DL = 869.525 MHz
[05/04/24 - 20:03:42.235] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:03:43.177] RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
[05/04/24 - 20:03:43.306] RLB_PRO: Closing Rx2 window
[05/04/24 - 20:03:43.309] [LoRaWAN] Next uplink in 180s
[05/04/24 - 20:03:43.311]
[05/04/24 - 20:06:43.377] RLB_PRO:
[05/04/24 - 20:06:43.379] RLB_PRO: PHY: Frequency UL = 867.100 MHz
[05/04/24 - 20:06:43.385] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:06:43.389] RLB_PRO: Uplink (FcntUp = 504) decoded:
[05/04/24 - 20:06:43.393] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 20:06:43.401] RLB_PRO: 0000010 40 29 f1 0b 26 80 f8 01 0a f1 ae 15 8a af 00 00 | @)..&...........
[05/04/24 - 20:06:43.409] RLB_PRO: 0000020 14 | .
[05/04/24 - 20:06:43.415] RLB_DBG: Timeout in 257 ms
[05/04/24 - 20:06:43.468] RLB_PRO: Uplink sent <-- Rx Delay start
[05/04/24 - 20:06:43.472] RLB_PRO:
[05/04/24 - 20:06:43.473] RLB_PRO: PHY: Frequency DL = 867.100 MHz
[05/04/24 - 20:06:43.476] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:06:48.465] RLB_PRO: Opening Rx1 window (45856 us timeout)... <-- Rx Delay end
[05/04/24 - 20:06:48.515] RLB_PRO: Closing Rx1 window
[05/04/24 - 20:06:48.517] RLB_PRO: PHY: Frequency DL = 869.525 MHz
[05/04/24 - 20:06:48.522] RLB_PRO: PHY: SF = 9, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:06:49.464] RLB_PRO: Opening Rx2 window (123424 us timeout)... <-- Rx Delay end
[05/04/24 - 20:06:49.593] RLB_PRO: Closing Rx2 window
[05/04/24 - 20:06:49.595] [LoRaWAN] Next uplink in 180s
[05/04/24 - 20:06:49.598]
[05/04/24 - 20:09:49.665] RLB_PRO:
[05/04/24 - 20:09:49.666] RLB_PRO: PHY: Frequency UL = 867.100 MHz
[05/04/24 - 20:09:49.672] RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
[05/04/24 - 20:09:49.676] RLB_PRO: Uplink (FcntUp = 505) decoded:
[05/04/24 - 20:09:49.681] RLB_PRO: 0000000 c0 00 78 56 88 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV.>.?....4...
[05/04/24 - 20:09:49.686] RLB_PRO: Guru Meditation Error: Core 1 panic'ed (LoadProhibited). Exception was unhandled.
[05/04/24 - 20:09:49.695]
[05/04/24 - 20:09:49.695]
[05/04/24 - 20:09:49.695]
[05/04/24 - 20:09:49.696] Core 1 register dump:
[05/04/24 - 20:09:49.697]
[05/04/24 - 20:09:49.697] PC : 0x400556b4 PS : 0x00060c30 A0 : 0x8201f9f8 A1 : 0x3fce29f0
[05/04/24 - 20:09:49.709]
[05/04/24 - 20:09:49.709] A2 : 0xa70fe3f9 A3 : 0xa70fe3f5 A4 : 0x000000ff A5 : 0x0000ff00
[05/04/24 - 20:09:49.713]
[05/04/24 - 20:09:49.713] A6 : 0x00ff0000 A7 : 0xff000000 A8 : 0x6b6e5500 A9 : 0x3fce2d30
[05/04/24 - 20:09:49.721]
[05/04/24 - 20:09:49.721] A10 : 0x00060c23 A11 : 0x00000000 A12 : 0x00060c20 A13 : 0x00000000
[05/04/24 - 20:09:49.729]
[05/04/24 - 20:09:49.729] A14 : 0x02ce338c A15 : 0x00ffffff SAR : 0x00000015 EXCCAUSE: 0x0000001c
[05/04/24 - 20:09:49.738]
[05/04/24 - 20:09:49.738] EXCVADDR: 0xa70fe3f9 LBEG : 0x400556d5 LEND : 0x400556e5 LCOUNT : 0xffffffff
[05/04/24 - 20:09:49.744]
[05/04/24 - 20:09:49.744]
[05/04/24 - 20:09:49.744]
[05/04/24 - 20:09:49.744]
[05/04/24 - 20:09:49.744]
[05/04/24 - 20:09:49.745] Backtrace:0x400556b1:0x3fce29f0 |<-CORRUPTED
[05/04/24 - 20:09:49.748]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749]
[05/04/24 - 20:09:49.749] ELF file SHA256: 0000000000000000
[05/04/24 - 20:09:49.752]
[05/04/24 - 20:09:49.752]
[05/04/24 - 20:09:49.752]
[05/04/24 - 20:09:49.752] Rebooting...
[05/04/24 - 20:09:49.754]
[05/04/24 - 20:09:49.754] [05/04/24 - 20:09:49.754] xøESP-ROM:esp32s3-20210327
[05/04/24 - 20:09:49.762]
[05/04/24 - 20:09:49.762] Build:Mar 27 2021
[05/04/24 - 20:09:49.772]
[05/04/24 - 20:09:49.772] rst:0xc (RTC_SW_CPU_RST),boot:0x29 (SPI_FAST_FLASH_BOOT)
[05/04/24 - 20:09:49.773]
[05/04/24 - 20:09:49.774] Saved PC:0x4202b86e
[05/04/24 - 20:09:49.774]
[05/04/24 - 20:09:49.774] SPIWP:0xee
[05/04/24 - 20:09:49.774]
[05/04/24 - 20:09:49.774] mode:DIO, clock div:1
[05/04/24 - 20:09:49.775]
[05/04/24 - 20:09:49.775] load:0x3fce3808,len:0x43c
[05/04/24 - 20:09:49.776]
[05/04/24 - 20:09:49.776] load:0x403c9700,len:0xbec
[05/04/24 - 20:09:49.778]
[05/04/24 - 20:09:49.779] load:0x403cc700,len:0x2a3c
[05/04/24 - 20:09:49.781]
[05/04/24 - 20:09:49.781] SHA-256 comparison failed:
[05/04/24 - 20:09:49.783]
[05/04/24 - 20:09:49.783] Calculated: dcde8d8a4817d9bf5d5d69a7247667264e4e10ac7493514868b61f5aa6146539
[05/04/24 - 20:09:49.790]
[05/04/24 - 20:09:49.790] Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
[05/04/24 - 20:09:49.800]
[05/04/24 - 20:09:49.800] Attempting to boot anyway...
[05/04/24 - 20:09:49.800]
[05/04/24 - 20:09:49.800] entry 0x403c98d8
[05/04/24 - 20:09:49.801]
[05/04/24 - 20:09:54.887]
[05/04/24 - 20:09:54.887] Setup
[05/04/24 - 20:09:54.888]
[05/04/24 - 20:09:54.888] Initalise the radio
[05/04/24 - 20:09:54.889]
[05/04/24 - 20:09:54.889] RLB_DBG: RadioLib Debug Info
[05/04/24 - 20:09:54.891] RLB_DBG: Version: 6.5.0.0
[05/04/24 - 20:09:54.894] RLB_DBG: Platform: ESP32
[05/04/24 - 20:09:54.897] RLB_DBG: Compiled: Apr 3 2024 17:35:11
[05/04/24 - 20:09:54.900]
[05/04/24 - 20:09:54.911] RLB_DBG: Found SX126x: RADIOLIB_SX126X_REG_VERSION_STRING:
[05/04/24 - 20:09:54.916] RLB_DBG: 0000320 53 58 31 32 36 31 20 56 32 44 20 32 44 30 32 00 | SX1261 V2D 2D02.
[05/04/24 - 20:09:54.923] RLB_DBG:
[05/04/24 - 20:09:54.924] RLB_DBG: M SX126x
[05/04/24 - 20:09:54.960] Join ('login') to the LoRaWAN Network
...
BTW: my FcntUp is now at 965 (without any crash) and memory have still the same values...
WSL stick is powered by LiPo and USB, so don't think about a power issue - so have no idea...
from radiolib.
Related Issues (20)
- CubeCell + POCSAG issues. HOT 1
- RP2040-LoRa Development kit HOT 6
- SPIcheckStream in Module.cpp is ineffective (does nothing) HOT 1
- TTGO LoRa32 (T3S3 v1.2) Error -16 HOT 10
- Sending more than one message in POCSAG HOT 1
- 'virtual float PhysicalLayer::getRSSI()' was hidden ESP-IDF HOT 10
- Feature request: AX.25 parser HOT 4
- Think I introduced a bug with Promiscuous (requireCarrierSense) when used with directMode HOT 2
- Move Raspberry Pi example to another GPIO library HOT 10
- Better error handling in the HAL HOT 3
- CC1101 Direct Mode getRSSI() only ever returns -74.0 HOT 2
- importing Radiolib into VSCODE + Platformio with NRF52840 board does not compile HOT 13
- SX1280 startTransmit after CAD not working HOT 4
- Move examples to a separate repository HOT 14
- Invalid calibrateImage parameters prevent initializing with 915MHz frequency on SX126x-based STM32WLx HOT 2
- #include <Radiolib/SX1262.h> is available to use less space than the hole library? HOT 1
- Minor issue in startReceiveDutyCycleAuto HOT 3
- GFSK support for SX1231/RFM69 and SX127x/RFM9x HOT 2
- Support for MSPM0 Microcontroller HOT 1
Recommend Projects
-
React
A declarative, efficient, and flexible JavaScript library for building user interfaces.
-
Vue.js
🖖 Vue.js is a progressive, incrementally-adoptable JavaScript framework for building UI on the web.
-
Typescript
TypeScript is a superset of JavaScript that compiles to clean JavaScript output.
-
TensorFlow
An Open Source Machine Learning Framework for Everyone
-
Django
The Web framework for perfectionists with deadlines.
-
Laravel
A PHP framework for web artisans
-
D3
Bring data to life with SVG, Canvas and HTML. 📊📈🎉
-
Recommend Topics
-
javascript
JavaScript (JS) is a lightweight interpreted programming language with first-class functions.
-
web
Some thing interesting about web. New door for the world.
-
server
A server is a program made to process requests and deliver data to clients.
-
Machine learning
Machine learning is a way of modeling and interpreting data that allows a piece of software to respond intelligently.
-
Visualization
Some thing interesting about visualization, use data art
-
Game
Some thing interesting about game, make everyone happy.
Recommend Org
-
Facebook
We are working to build community through open source technology. NB: members must have two-factor auth.
-
Microsoft
Open source projects and samples from Microsoft.
-
Google
Google ❤️ Open Source for everyone.
-
Alibaba
Alibaba Open Source for everyone
-
D3
Data-Driven Documents codes.
-
Tencent
China tencent open source team.
from radiolib.