Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

LoRaWAN ESP32 (Heltec WSL V3) reboot with stack error #1049

Closed
michapr opened this issue Apr 2, 2024 · 14 comments
Closed

LoRaWAN ESP32 (Heltec WSL V3) reboot with stack error #1049

michapr opened this issue Apr 2, 2024 · 14 comments
Assignees
Labels
bug Something isn't working resolved Issue was resolved (e.g. bug fixed, or feature implemented)

Comments

@michapr
Copy link

michapr commented Apr 2, 2024

Describe the bug
I'm using for test the "LoRaWAN_Reference.ino" with the only modification node.setADR(true);
When call these two command requests: link_check and device_time the ESP32 will reboot after delay()

  if(fcntUp % 64 == 0) {
    Serial.println(F("[LoRaWAN] Requesting LinkCheck and DeviceTime"));
    node.sendMacCommandReq(RADIOLIB_LORAWAN_MAC_LINK_CHECK);
    node.sendMacCommandReq(RADIOLIB_LORAWAN_MAC_DEVICE_TIME);
    state = node.sendReceive(uplinkPayload, sizeof(uplinkPayload), Port, downlinkPayload, &downlinkSize, true, &uplinkDetails, &downlinkDetails);

Debug log:

09:29:46.961 -> 
09:29:46.961 -> Stack smashing protect failure!
09:29:46.961 -> 
09:29:46.961 -> 
09:29:46.961 -> abort() was called at PC 0x4201422b on core 1
09:29:46.961 -> 
09:29:46.961 -> 
09:29:46.961 -> Backtrace:0x403777be:0x3fce2ed00x4037a5ad:0x3fce2ef0 0x4037fba5:0x3fce2f10 0x4201422b:0x3fce2f90 0x42001fa9:0x3fce2fb0 0x4200abf1:0x3fce3030 
09:29:46.961 -> 
09:29:46.961 -> 
09:29:46.961 -> 
09:29:46.961 -> 
09:29:46.961 -> ELF file SHA256: 0000000000000000
09:29:46.961 -> 
09:29:46.961 -> Rebooting...
09:29:46.961 -> �ESP-ROM:esp32s3-20210327
09:29:47.012 -> Build:Mar 27 2021
09:29:47.012 -> rst:0xc (RTC_SW_CPU_RST),boot:0x28 (SPI_FAST_FLASH_BOOT)
09:29:47.012 -> Saved PC:0x4202b7b2
09:29:47.012 -> SPIWP:0xee
09:29:47.012 -> mode:DIO, clock div:1
09:29:47.012 -> load:0x3fce3808,len:0x43c
09:29:47.012 -> load:0x403c9700,len:0xbec
09:29:47.012 -> load:0x403cc700,len:0x2a3c
09:29:47.012 -> SHA-256 comparison failed:
09:29:47.012 -> Calculated: dcde8d8a4817d9bf5d5d69a7247667264e4e10ac7493514868b61f5aa6146539
09:29:47.012 -> Expected: ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
09:29:47.039 -> Attempting to boot anyway...
09:29:52.113 -> 
09:29:52.113 -> Setup
09:29:52.113 -> Initalise the radio
09:29:52.113 -> RLB_DBG: RadioLib Debug Info
09:29:52.113 -> RLB_DBG: Version:  6.5.0.0
09:29:52.113 -> RLB_DBG: Platform: ESP32
09:29:52.113 -> RLB_DBG: Compiled: Mar 29 2024 18:40:51
09:29:52.113 -> 
09:29:52.113 -> RLB_DBG: Found SX126x: RADIOLIB_SX126X_REG_VERSION_STRING:
09:29:52.161 -> RLB_DBG: 0000320 53 58 31 32 36 31 20 56 32 44 20 32 44 30 32 00 | SX1261 V2D 2D02.
09:29:52.161 -> RLB_DBG: 
09:29:52.161 -> RLB_DBG: M	SX126x
09:29:52.192 -> Join ('login') to the LoRaWAN Network
09:29:52.192 -> RLB_PRO: Configuration mismatch (checksum: 0, mode: 0, class: 0, plan: 1)
09:29:52.192 -> RLB_PRO: Nonces buffer:
09:29:52.192 -> RLB_PRO: 0000000 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................
09:29:52.192 -> RLB_PRO: Clearing buffer and starting fresh
09:29:52.192 -> RLB_PRO: Setting up dynamic channels
09:29:52.192 -> RLB_PRO: UL:   0 1 868.100 (0 - 5) | DL:   0 1 868.100 (0 - 5)
09:29:52.224 -> RLB_PRO: UL:   1 1 868.300 (0 - 5) | DL:   1 1 868.300 (0 - 5)
09:29:52.224 -> RLB_PRO: UL:   2 1 868.500 (0 - 5) | DL:   2 1 868.500 (0 - 5)
09:29:52.224 -> RLB_PRO: [MAC] 0x03
09:29:52.224 -> RLB_PRO: 0000000 20 00 00 80                                     |  ...              
09:29:52.224 -> RLB_PRO: LinkADRReq: dataRate = 2, txPower = 0, chMask = 0x0000, chMaskCntl = 0, nbTrans = 0
09:29:52.224 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
09:29:52.256 -> RLB_PRO: PHY: TX = 16 dBm
09:29:52.256 -> RLB_PRO: LinkADRAns: status = 0x07
09:29:52.256 -> RLB_PRO: [MAC] 0x04
09:29:52.256 -> RLB_PRO: 0000000 07                                              | .                 
09:29:52.256 -> RLB_PRO: DutyCycleReq: max duty cycle = 1/2^7
09:29:52.256 -> RLB_PRO: [MAC] 0x05
09:29:52.256 -> RLB_PRO: 0000000 00 d2 ad 84                                     | ....              
09:29:52.256 -> RLB_PRO: RXParamSetupReq: rx1DrOffset = 0, rx2DataRate = 0, freq = 869.525024
09:29:52.288 -> RLB_PRO: RXParamSetupAns: status = 0x07
09:29:52.288 -> RLB_PRO: [MAC] 0x08
09:29:52.288 -> RLB_PRO: 0000000 01                                              | .                 
09:29:52.288 -> RLB_PRO: RXTimingSetupReq: delay = 1 sec
09:29:52.288 -> RLB_PRO: [MAC] 0x09
09:29:52.288 -> RLB_PRO: 0000000 05                                              | .                 
09:29:52.288 -> RLB_PRO: TxParamSetupReq: dlDwell = 0, ulDwell = 0, maxEirp = 16 dBm
09:29:52.320 -> RLB_PRO: [MAC] 0x0C
09:29:52.320 -> RLB_PRO: 0000000 65                                              | e                 
09:29:52.320 -> RLB_PRO: ADRParamSetupReq: limitExp = 6, delayExp = 5
09:29:52.320 -> RLB_PRO: [MAC] 0x0F
09:29:52.320 -> RLB_PRO: 0000000 fa                                              | .                 
09:29:52.320 -> RLB_PRO: RejoinParamSetupReq: maxTime = 15, maxCount = 10
09:29:52.353 -> RLB_PRO: RejoinParamSetupAns: status = 0x03
09:29:52.434 -> RLB_PRO: 
09:29:52.434 -> RLB_PRO: PHY: Frequency UL = 868.300 MHz
09:29:52.434 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
09:29:52.434 -> RLB_DBG: Timeout in 1853 ms
09:29:52.811 -> RLB_PRO: Join-request sent <-- Rx Delay start
09:29:52.811 -> RLB_PRO: 
09:29:52.811 -> RLB_PRO: PHY: Frequency DL = 868.300 MHz
09:29:52.811 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
09:29:57.792 -> RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end 
09:29:58.025 -> RLB_PRO: Closing Rx1 window
09:29:58.267 -> RLB_PRO: joinAcceptMsg:
09:29:58.267 -> RLB_PRO: 0000000 20 25 00 00 13 00 00 74 5e 0b 26 83 05 18 4f 84 |  ^.&...O.
09:29:58.267 -> RLB_PRO: 0000010 e8 56 84 b8 5e 84 88 66 84 58 6e 84 00 b0 42 0d | .V..^..f.Xn...B.
09:29:58.267 -> RLB_PRO: 0000020 a6                                              | .                 
09:29:58.267 -> RLB_PRO: JoinNoncePrev: 0, JoinNonce: 37
09:29:58.299 -> RLB_PRO: LoRaWAN revision: 1.1
09:29:58.299 -> RLB_PRO: [MAC] 0x05
09:29:58.299 -> RLB_PRO: 0000000 03 d2 ad 84                                     | ....              
09:29:58.299 -> RLB_PRO: RXParamSetupReq: rx1DrOffset = 0, rx2DataRate = 3, freq = 869.525024
09:29:58.299 -> RLB_PRO: RXParamSetupAns: status = 0x07
09:29:58.331 -> RLB_PRO: [MAC] 0x08
09:29:58.331 -> RLB_PRO: 0000000 05                 
09:29:58.331 -> RLB_PRO: RXTimingSetupReq: delay = 5 sec
09:29:58.331 -> RLB_PRO: Setting up dynamic channels
09:29:58.331 -> RLB_PRO: UL:   0 1 868.100 (0 - 5) | DL:   0 1 868.100 (0 - 5)
09:29:58.331 -> RLB_PRO: UL:   1 1 868.300 (0 - 5) | DL:   1 1 868.300 (0 - 5)
09:29:58.331 -> RLB_PRO: UL:   2 1 868.500 (0 - 5) | DL:   2 1 868.500 (0 - 5)
09:29:58.363 -> RLB_PRO: Processing CFList
09:29:58.363 -> RLB_PRO: [MAC] 0x07
09:29:58.363 -> RLB_PRO: 0000000 03 18 4f 84 50                                  | ..O.P             
09:29:58.363 -> RLB_PRO: NewChannelReq:
09:29:58.363 -> RLB_PRO: UL:   3 1 867.100 (0 - 5) | DL:   3 1 867.100 (0 - 5)
09:29:58.363 -> RLB_PRO: NewChannelAns: status = 0x03
09:29:58.395 -> RLB_PRO: [MAC] 0x07
09:29:58.395 -> RLB_PRO: 0000000 04 e8 56 84 50                                  | ..V.P             
09:29:58.395 -> RLB_PRO: NewChannelReq:
09:29:58.395 -> RLB_PRO: UL:   4 1 867.300 (0 - 5) | DL:   4 1 867.300 (0 - 5)
09:29:58.395 -> RLB_PRO: NewChannelAns: status = 0x03
09:29:58.395 -> RLB_PRO: [MAC] 0x07
09:29:58.395 -> RLB_PRO: 0000000 05 b8 5e 84 50                                  | ..^.P             
09:29:58.427 -> RLB_PRO: NewChannelReq:
09:29:58.427 -> RLB_PRO: UL:   5 1 867.500 (0 - 5) | DL:   5 1 867.500 (0 - 5)
09:29:58.427 -> RLB_PRO: NewChannelAns: status = 0x03
09:29:58.427 -> RLB_PRO: [MAC] 0x07
09:29:58.427 -> RLB_PRO: 0000000 06 88 66 84 50                                  | ..f.P             
09:29:58.427 -> RLB_PRO: NewChannelReq:
09:29:58.459 -> RLB_PRO: UL:   6 1 867.700 (0 - 5) | DL:   6 1 867.700 (0 - 5)
09:29:58.459 -> RLB_PRO: NewChannelAns: status = 0x03
09:29:58.459 -> RLB_PRO: [MAC] 0x07
09:29:58.459 -> RLB_PRO: 0000000 07 58 6e 84 50                                  | .Xn.P             
09:29:58.459 -> RLB_PRO: NewChannelReq:
09:29:58.459 -> RLB_PRO: UL:   7 1 867.900 (0 - 5) | DL:   7 1 867.900 (0 - 5)
09:29:58.459 -> RLB_PRO: NewChannelAns: status = 0x03
09:29:58.500 -> [LoRaWAN] DevAddr: 260B5E74
09:29:58.500 -> Ready!
09:29:58.500 -> 
09:29:58.500 -> [LoRaWAN] Requesting LinkCheck and DeviceTime
09:29:58.532 -> RLB_PRO: 
09:29:58.532 -> RLB_PRO: PHY: Frequency UL = 867.100 MHz
09:29:58.564 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
09:29:58.564 -> RLB_PRO: Uplink MAC payload (3 commands):
09:29:58.564 -> RLB_PRO: 0000000 0b 01 02 0d                                     | ....              
09:29:58.564 -> RLB_PRO: Uplink (FcntUp = 0) decoded:
09:29:58.564 -> RLB_PRO: 0000000 c0 00 78 56 5c 3e ce 3f 13 00 00 00 34 12 ba ab | ..xV\>.?....4...
09:29:58.606 -> RLB_PRO: 0000010 80 74 5e 0b 26 84 00 00 f6 28 60 f7 0a a1 70 b2 | .t^.&....(`...p.
09:29:58.606 -> RLB_PRO: 0000020 14 00 ce 3f                                     | ...?              
09:29:58.870 -> RLB_DBG: Timeout in 1853 ms
09:29:59.249 -> RLB_PRO: Uplink sent <-- Rx Delay start
09:29:59.249 -> RLB_PRO: 
09:29:59.249 -> RLB_PRO: PHY: Frequency DL = 867.100 MHz
09:29:59.249 -> RLB_PRO: PHY: SF = 10, BW = 125.000 kHz, CR = 4/5
09:30:04.244 -> RLB_PRO: Opening Rx1 window (226848 us timeout)... <-- Rx Delay end 
09:30:04.476 -> RLB_PRO: Closing Rx1 window
09:30:04.630 -> RLB_PRO: Downlink (NFcntDown = 0) encoded:
09:30:04.662 -> RLB_PRO: 0000000 49 00 00 00 00 01 74 5e 0b 26 00 00 00 00 00 19 | I.....t^.&......
09:30:04.662 -> RLB_PRO: 0000010 60 74 5e 0b 26 a0 00 00 00 42 01 4f be 7d 1b d4 | `t^.&....B.O.}..
09:30:04.662 -> RLB_PRO: 0000020 6b c1 7e 96 6a d4 bd 5d 47 29 79 b2 48          | k.~.j..]G)y.H     
09:30:04.662 -> RLB_PRO: [MAC] 0x0B
09:30:04.662 -> RLB_PRO: 0000000 01                                              | .                 
09:30:04.694 -> RLB_PRO: RekeyConf: server version = 1.1
09:30:04.694 -> RLB_PRO: [MAC] 0x0D
09:30:04.694 -> RLB_PRO: 0000000 19 90 32 53 33                                  | ..2S3             
09:30:04.694 -> RLB_PRO: DeviceTimeAns: [user]
09:30:04.694 -> RLB_PRO: [MAC] 0x02
09:30:04.694 -> RLB_PRO: 0000000 19 01          
09:30:04.694 -> RLB_PRO: LinkCheckAns: [user]
09:30:04.694 -> RLB_PRO: [MAC] 0x03
09:30:04.694 -> RLB_PRO: 0000000 5f ff 00 01                                     | _...              
09:30:04.726 -> RLB_PRO: LinkADRReq: dataRate = 5, txPower = 15, chMask = 0x00ff, chMaskCntl = 0, nbTrans = 1
09:30:04.726 -> RLB_PRO: PHY: SF = 7, BW = 125.000 kHz, CR = 4/5
09:30:04.726 -> RLB_PRO: UL:   0 1 868.100 (0 - 5) | DL:   0 1 868.100 (0 - 5)
09:30:04.726 -> RLB_PRO: UL:   1 1 868.300 (0 - 5) | DL:   1 1 868.300 (0 - 5)
09:30:04.726 -> RLB_PRO: UL:   2 1 868.500 (0 - 5) | DL:   2 1 868.500 (0 - 5)
09:30:04.758 -> RLB_PRO: UL:   3 1 867.100 (0 - 5) | DL:   3 1 867.100 (0 - 5)
09:30:04.758 -> RLB_PRO: UL:   4 1 867.300 (0 - 5) | DL:   4 1 867.300 (0 - 5)
09:30:04.758 -> RLB_PRO: UL:   5 1 867.500 (0 - 5) | DL:   5 1 867.500 (0 - 5)
09:30:04.758 -> RLB_PRO: UL:   6 1 867.700 (0 - 5) | DL:   6 1 867.700 (0 - 5)
09:30:04.758 -> RLB_PRO: UL:   7 1 867.900 (0 - 5) | DL:   7 1 867.900 (0 - 5)
09:30:04.758 -> RLB_PRO: LinkADRAns: status = 0x07
09:30:04.758 -> Downlink data: 
09:30:04.790 -> 0E28602808FD0DA6FF7DC83C03541000
09:30:04.790 -> [LoRaWAN] RSSI:		-49.00 dBm
09:30:04.790 -> [LoRaWAN] SNR:		8.25 dB
09:30:04.790 -> [LoRaWAN] Frequency error:	1913.28 Hz
09:30:04.790 -> [LoRaWAN] Event information:
09:30:04.790 -> [LoRaWAN] Confirmed:	0
09:30:04.790 -> [LoRaWAN] Confirming:	1
09:30:04.790 -> [LoRaWAN] Datarate:	5
09:30:04.790 -> [LoRaWAN] Frequency:	867.100 MHz
09:30:04.790 -> [LoRaWAN] Output power:	16 dBm
09:30:04.790 -> [LoRaWAN] Frame count:	0
09:30:04.790 -> [LoRaWAN] Port:		0
09:30:04.790 -> [LoRaWAN] LinkCheck margin:	25
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

To Reproduce
use for test the "LoRaWAN_Reference.ino" with the only modification node.setADR(true);

Additional info (please complete):

  • MCU: ESP32S3 - Heltes WirelessStick lite V3
  • Wireless module type SX1262
  • Arduino IDE version 2.2.1
  • Library version 6.5.0
@HeadBoffin
Copy link
Collaborator

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.

@michapr
Copy link
Author

michapr commented Apr 2, 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

@HeadBoffin
Copy link
Collaborator

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 -> [ ]

@michapr
Copy link
Author

michapr commented Apr 2, 2024

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
...

@michapr
Copy link
Author

michapr commented Apr 2, 2024

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 ... ;)

@StevenCellist
Copy link
Collaborator

Thanks for the troubleshooting! Behaviour replicated, got some logs - I like big logs, I cannot lie. To be continued.

@StevenCellist StevenCellist self-assigned this Apr 2, 2024
@StevenCellist StevenCellist added bug Something isn't working more information needed Further investigation is needed, not necessarily by the author and removed more information needed Further investigation is needed, not necessarily by the author labels Apr 2, 2024
@StevenCellist
Copy link
Collaborator

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.

@StevenCellist StevenCellist added the resolved Issue was resolved (e.g. bug fixed, or feature implemented) label Apr 2, 2024
@michapr
Copy link
Author

michapr commented Apr 6, 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: 

@StevenCellist
Copy link
Collaborator

@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...

@michapr
Copy link
Author

michapr commented Apr 7, 2024

Lots of other deadlines upcoming...

Yes, of course. This has no priority, I think so.
Will check the memory, thanks for the hint.

@HeadBoffin
Copy link
Collaborator

@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.

@michapr
Copy link
Author

michapr commented Apr 8, 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

@HeadBoffin
Copy link
Collaborator

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?

@michapr
Copy link
Author

michapr commented Apr 9, 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...

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working resolved Issue was resolved (e.g. bug fixed, or feature implemented)
Projects
None yet
Development

No branches or pull requests

3 participants