Periodical Uplink and LCTT Certif example spamming uplinks

[ThorSporringMii]

I cannot for the life of me get the LBM relay RX example to work properly. The following are my build parameters:

MODEM_APP=PERIODICAL_UPLINK (same issue with LCTT_CERTIF)
ALLOW_RELAY_TX=no
ALLOW_RELAY_RX=yes
APP_TRACE=yes

I am using a SX1261MB2xAS mbed shield on the NUCLEO-L476RG as recommended

After joining the device to ChirpStack, it runs into this infinite loop where it transmits uplinks every 5 seconds, even though the PERIODICAL_UPLINK_DELAY_S is set to 60

What am i missing here? I would expect the device to start the CAD cycle and transmit a service uplink every 60 seconds.

Here is the UART output:

INFO: Modem Initialization
10:20:30:644 -> INFO: Use soft secure element for cryptographic functionalities
10:20:30:661 -> stack_id 0
10:20:30:662 ->  DevNonce = 5542
10:20:30:664 ->  JoinNonce = 0xff 00 00, NetID = 0x00 00 00
10:20:30:667 ->  Region = EU868
10:20:30:669 -> WARN: ***********************************************
10:20:30:674 -> WARN:   LoRaWAN CERTIFICATION is ENABLED on stack 0
10:20:30:680 -> WARN: ***********************************************
10:20:30:687 ->  lorawan_relay_rx_services_init task_id 7, service_id 0, CURRENT_STACK:0 
10:20:30:693 -> Modem event callback
10:20:30:695 -> INFO: Event received: RESET
10:20:30:699 -> INFO: Certification example is starting
10:20:30:704 -> INFO: Push button to enable/disable certification
10:20:30:709 ->  Start a new join sequence in 4 seconds on stack 0
10:20:37:158 -> DevEUI - (8 bytes):
10:20:37:160 ->  FF AA EE BB EE EE EE EE
10:20:37:163 -> JoinEUI - (8 bytes):
10:20:37:164 ->  00 00 00 00 00 00 00 01
10:20:37:167 -> DevNonce 0x15a7, stack_id 0
10:20:37:170 -> 
10:20:37:170 ->   *************************************
10:20:37:173 ->   * Send Payload  for stack_id = 0
10:20:37:176 ->   *************************************
10:20:37:180 ->   Tx  LoRa at 6516 ms: freq:868500000, SF10, BW125, len 23 bytes 14 dBm, fcnt_up 0, toa = 371
10:20:37:569 -> 
10:20:37:569 ->   *************************************
10:20:37:572 ->   *  TX DONE
10:20:37:573 ->   *************************************
10:20:37:603 -> 
10:20:37:603 ->   Open RX1 for Hook Id = 2  RX1 LoRa at 11933 ms: freq:868500000, SF10, BW125, sync word = 0x34
10:20:37:612 ->   Timer will expire in 4975 ms
10:20:43:029 -> 
10:20:43:029 ->   *************************************
10:20:43:032 ->   * Receive a Valid downlink RX1 for stack_id = 0
10:20:43:037 ->   *************************************
10:20:43:040 ->  update join procedure
10:20:43:042 -> MacTxFrequency [0] = 867100000 
10:20:43:045 -> MacDataRateChannel [0] =  1 1 1 1 1 1 0 0 0 0 0 0 0 0 0 0 
10:20:43:051 -> MacChannelIndexEnabled [0] = 1 
10:20:43:053 -> MacTxFrequency [1] = 867300000 
10:20:43:056 -> MacDataRateChannel [1] =  1 1 1 1 1 1 0 0 0 0 0 0 0 0 0 0 
10:20:43:064 -> MacChannelIndexEnabled [1] = 1 
10:20:43:065 -> MacTxFrequency [2] = 867500000 
10:20:43:067 -> MacDataRateChannel [2] =  1 1 1 1 1 1 0 0 0 0 0 0 0 0 0 0 
10:20:43:073 -> MacChannelIndexEnabled [2] = 1 
10:20:43:076 -> MacTxFrequency [3] = 867700000 
10:20:43:078 -> MacDataRateChannel [3] =  1 1 1 1 1 1 0 0 0 0 0 0 0 0 0 0 
10:20:43:084 -> MacChannelIndexEnabled [3] = 1 
10:20:43:087 -> MacTxFrequency [4] = 867900000 
10:20:43:090 -> MacDataRateChannel [4] =  1 1 1 1 1 1 0 0 0 0 0 0 0 0 0 0 
10:20:43:098 -> MacChannelIndexEnabled [4] = 1 
10:20:43:099 ->  DevAddr= 591f62
10:20:43:099 ->  MacRx1DataRateOffset= 0
10:20:43:102 ->  MacRx2DataRate= 0
10:20:43:103 ->  MacRx1Delay= 1
10:20:43:127 -> Modem event callback
10:20:43:130 -> INFO: Event received: JOINED
10:20:43:133 -> INFO: Modem is now joined 
10:20:43:136 -> 
10:20:43:208 ->   *************************************
10:20:43:212 ->   * Send Payload  for stack_id = 0
10:20:43:215 ->   *************************************
10:20:43:218 ->   Tx  LoRa at 12566 ms: freq:868500000, SF10, BW125, len 17 bytes 14 dBm, fcnt_up 1, toa = 330
10:20:43:566 -> 
10:20:43:566 ->   *************************************
10:20:43:570 ->   *  TX DONE
10:20:43:571 ->   *************************************
10:20:43:578 -> 
10:20:43:578 ->   Open RX1 for Hook Id = 2  RX1 LoRa at 13931 ms: freq:868500000, SF10, BW125, sync word = 0x34
10:20:43:587 ->   Timer will expire in 999 ms
10:20:45:026 -> 
10:20:45:026 ->   *************************************
10:20:45:030 ->   * Receive a Valid downlink RX1 for stack_id = 0
10:20:45:034 ->   *************************************
10:20:45:038 -> 1/1 - Cmd link_adr_parser = 30 ff 00 01
10:20:45:041 -> 1 - LINK ADR REQ , channel mask = 0xff , ChMaskCntl = 0x0
10:20:45:046 -> MacTxDataRateAdr = 3
10:20:45:048 -> MacTxPower = 14
10:20:45:049 -> MacNbTrans = 1
10:20:45:051 -> Receive a dev status req
10:20:45:055 -> Relay MAC command : 0x40 - CONFIG_RELAY
10:20:45:073 -> CAD period : 1 s
10:20:45:073 -> Default channel : Index 0 (DR 3 - WOR 865100000 Hz - ACK 865300000 Hz)
10:20:45:073 -> TOA symbol DR3 : 4 ms
10:20:45:073 -> No additionnal channel 
10:20:45:073 -> Start CAD at 15000 ms every 1000 ms at DR3 865100000 Hz
10:20:45:077 -> New config has been accepted and relay has started
10:20:45:082 -> Relay MAC command : 0x42 - JOINREQ_FILTER
10:20:45:085 -> Update rule 0 with FWD_FILTER 
10:20:45:089 -> Relay MAC command : 0x45 - FWD_LIMIT
10:20:45:091 -> Reset : Dont change
10:20:45:093 -> - Overall  Unlimited
10:20:45:095 -> - Global   Unlimited
10:20:45:097 -> - Notify   Unlimited
10:20:45:098 -> - Join Req Unlimited
10:20:45:101 -> Modem event callback
10:20:45:103 -> INFO: Event received: RELAY_RX_RUNNING
10:20:45:108 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:20:45:646 ->  WARN: Relay Task aborted
10:20:45:650 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:20:46:646 ->  WARN: Relay Task aborted
10:20:46:649 -> WARN: Relay Task aborted
10:20:47:779 ->  .
10:20:47:787 ->   *************************************
10:20:47:790 ->   * Send Payload  for stack_id = 0
10:20:47:793 ->   *************************************
10:20:47:797 ->   Tx  LoRa at 17133 ms: freq:868300000, SF9, BW125, len 23 bytes 14 dBm, fcnt_up 2, toa = 206
10:20:48:025 -> 
10:20:48:025 ->   *************************************
10:20:48:029 ->   *  TX DONE
10:20:48:030 ->   *************************************
10:20:48:037 -> 
10:20:48:037 ->   Open RX1 for Hook Id = 2  RX1 LoRa at 18382 ms: freq:868300000, SF9, BW125, sync word = 0x34
10:20:48:045 ->   Timer will expire in 991 ms
10:20:48:640 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:20:48:646 ->  WARN: Relay Task aborted
10:20:48:649 -> 
10:20:49:053 ->   *************************************
10:20:49:056 ->   * RX1 Timeout for stack_id = 0
10:20:49:059 ->   *************************************
10:20:49:066 -> 
10:20:49:066 ->   Open RX2 for Hook Id = 2  RX2 LoRa at 19410 ms: freq:869525000, SF12, BW125, sync word = 0x34
10:20:49:075 ->   Timer will expire in 990 ms
10:20:49:641 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:20:49:647 ->  WARN: Relay Task aborted
10:20:49:650 -> 
10:20:50:252 ->   *************************************
10:20:50:256 ->   * RX2 Timeout for stack_id = 0
10:20:50:258 ->   *************************************
10:20:50:296 -> 
10:20:50:296 ->   *************************************
10:20:50:313 ->   * Send Payload  for stack_id = 0
10:20:50:313 ->   *************************************
10:20:50:313 ->   Tx  LoRa at 19649 ms: freq:867100000, SF9, BW125, len 17 bytes 14 dBm, fcnt_up 3, toa = 165
10:20:50:493 -> 
10:20:50:493 ->   *************************************
10:20:50:496 ->   *  TX DONE
10:20:50:499 ->   *************************************
10:20:50:505 -> 
10:20:50:505 ->   Open RX1 for Hook Id = 2  RX1 LoRa at 20850 ms: freq:867100000, SF9, BW125, sync word = 0x34
10:20:50:513 ->   Timer will expire in 991 ms
10:20:50:641 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:20:50:646 ->  WARN: Relay Task aborted
10:20:50:650 -> 
10:20:51:521 ->   *************************************
10:20:51:524 ->   * RX1 Timeout for stack_id = 0
10:20:51:527 ->   *************************************
10:20:51:534 -> 
10:20:51:534 ->   Open RX2 for Hook Id = 2  RX2 LoRa at 21879 ms: freq:869525000, SF12, BW125, sync word = 0x34
10:20:51:543 ->   Timer will expire in 991 ms
10:20:51:641 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:20:51:646 ->  WARN: Relay Task aborted
10:20:51:650 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:20:52:646 ->  WARN: Relay Task aborted
10:20:52:721 -> 
10:20:52:729 ->   *************************************
10:20:52:732 ->   * RX2 Timeout for stack_id = 0
10:20:52:735 ->   *************************************
10:20:54:676 -> 
10:20:54:676 ->   *************************************
10:20:54:679 ->   * Send Payload  for stack_id = 0
10:20:54:682 ->   *************************************
10:20:54:687 ->   Tx  LoRa at 24029 ms: freq:867500000, SF9, BW125, len 17 bytes 14 dBm, fcnt_up 4, toa = 165
10:20:54:874 -> 
10:20:54:874 ->   *************************************
10:20:54:876 ->   *  TX DONE
10:20:54:877 ->   *************************************
10:20:54:885 -> 
10:20:54:885 ->   Open RX1 for Hook Id = 2  RX1 LoRa at 25230 ms: freq:867500000, SF9, BW125, sync word = 0x34
10:20:54:893 ->   Timer will expire in 991 ms
10:20:55:641 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:20:55:646 ->  WARN: Relay Task aborted
10:20:55:650 -> 
10:20:55:901 ->   *************************************
10:20:55:904 ->   * RX1 Timeout for stack_id = 0
10:20:55:907 ->   *************************************
10:20:55:915 -> 
10:20:55:915 ->   Open RX2 for Hook Id = 2  RX2 LoRa at 26258 ms: freq:869525000, SF12, BW125, sync word = 0x34
10:20:55:923 ->   Timer will expire in 989 ms
10:20:56:640 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:20:56:663 ->  WARN: Relay Task aborted
10:20:56:663 -> 
10:20:57:101 ->   *************************************
10:20:57:104 ->   * RX2 Timeout for stack_id = 0
10:20:57:107 ->   *************************************
10:20:59:650 -> RP: Abort running #7 for priority #4
10:20:59:674 -> WARN: Relay Task aborted
10:20:59:677 -> 
10:20:59:685 ->   *************************************
10:20:59:689 ->   * Send Payload  for stack_id = 0
10:20:59:692 ->   *************************************
10:20:59:695 ->   Tx  LoRa at 29032 ms: freq:867500000, SF9, BW125, len 17 bytes 14 dBm, fcnt_up 5, toa = 165
10:20:59:882 -> 
10:20:59:882 ->   *************************************
10:20:59:885 ->   *  TX DONE
10:20:59:887 ->   *************************************
10:20:59:894 -> 
10:20:59:894 ->   Open RX1 for Hook Id = 2  RX1 LoRa at 30240 ms: freq:867500000, SF9, BW125, sync word = 0x34
10:20:59:902 ->   Timer will expire in 992 ms
10:21:00:640 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:21:00:646 ->  WARN: Relay Task aborted
10:21:00:649 -> 
10:21:00:910 ->   *************************************
10:21:00:914 ->   * RX1 Timeout for stack_id = 0
10:21:00:917 ->   *************************************
10:21:00:924 -> 
10:21:00:924 ->   Open RX2 for Hook Id = 2  RX2 LoRa at 31267 ms: freq:869525000, SF12, BW125, sync word = 0x34
10:21:00:933 ->   Timer will expire in 989 ms
10:21:01:641 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:21:01:647 ->  WARN: Relay Task aborted
10:21:01:650 -> 
10:21:02:110 ->   *************************************
10:21:02:113 ->   * RX2 Timeout for stack_id = 0
10:21:02:116 ->   *************************************
10:21:04:676 -> 
10:21:04:676 ->   *************************************
10:21:04:679 ->   * Send Payload  for stack_id = 0
10:21:04:682 ->   *************************************
10:21:04:686 ->   Tx  LoRa at 34029 ms: freq:867300000, SF9, BW125, len 17 bytes 14 dBm, fcnt_up 6, toa = 165
10:21:04:873 -> 
10:21:04:873 ->   *************************************
10:21:04:876 ->   *  TX DONE
10:21:04:878 ->   *************************************
10:21:04:885 -> 
10:21:04:885 ->   Open RX1 for Hook Id = 2  RX1 LoRa at 35231 ms: freq:867300000, SF9, BW125, sync word = 0x34
10:21:04:893 ->   Timer will expire in 992 ms
10:21:05:641 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:21:05:647 ->  WARN: Relay Task aborted
10:21:05:650 -> 
10:21:05:902 ->   *************************************
10:21:05:905 ->   * RX1 Timeout for stack_id = 0
10:21:05:908 ->   *************************************
10:21:05:916 -> 
10:21:05:916 ->   Open RX2 for Hook Id = 2  RX2 LoRa at 36259 ms: freq:869525000, SF12, BW125, sync word = 0x34
10:21:05:924 ->   Timer will expire in 989 ms
10:21:06:640 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:21:06:646 ->  WARN: Relay Task aborted
10:21:06:649 -> 
10:21:07:102 ->   *************************************
10:21:07:105 ->   * RX2 Timeout for stack_id = 0
10:21:07:108 ->   *************************************
10:21:09:651 -> RP: Abort running #7 for priority #4
10:21:09:675 -> WARN: Relay Task aborted
10:21:09:678 -> 
10:21:09:686 ->   *************************************
10:21:09:689 ->   * Send Payload  for stack_id = 0
10:21:09:692 ->   *************************************
10:21:09:696 ->   Tx  LoRa at 39032 ms: freq:867100000, SF9, BW125, len 17 bytes 14 dBm, fcnt_up 7, toa = 165
10:21:09:883 -> 
10:21:09:883 ->   *************************************
10:21:09:886 ->   *  TX DONE
10:21:09:887 ->   *************************************
10:21:09:895 -> 
10:21:09:895 ->   Open RX1 for Hook Id = 2  RX1 LoRa at 40241 ms: freq:867100000, SF9, BW125, sync word = 0x34
10:21:09:903 ->   Timer will expire in 992 ms
10:21:10:640 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:21:10:646 ->  WARN: Relay Task aborted
10:21:10:649 -> 
10:21:10:911 ->   *************************************
10:21:10:915 ->   * RX1 Timeout for stack_id = 0
10:21:10:921 ->   *************************************
10:21:10:925 -> 
10:21:10:925 ->   Open RX2 for Hook Id = 2  RX2 LoRa at 41268 ms: freq:869525000, SF12, BW125, sync word = 0x34
10:21:10:934 ->   Timer will expire in 989 ms
10:21:11:641 -> WARN:  RP: Aborted task with hook #7 - not a priority task
10:21:11:647 ->  WARN: Relay Task aborted
10:21:11:650 -> 
10:21:12:111 ->   *************************************
10:21:12:114 ->   * RX2 Timeout for stack_id = 0
10:21:12:117 ->   *************************************

[lbm-team]

Hi ThorSporringMii,

It seems you ran the certification mode previously, the certification state is saved in flash.

This log show that the Certification is enabled at boot:

10:20:30:669 -> WARN: ***********************************************
10:20:30:674 -> WARN:   LoRaWAN CERTIFICATION is ENABLED on stack 0
10:20:30:680 -> WARN: ***********************************************

You have to disable to stop certification uplinks every 5s.

Best Regards

[ThorSporringMii]

Hello lbm-team.

Since I'm using the main_periodical_uplink.c example, there isn't an option in the file to enable and disable certification. I assume this is left over from when i built the main_lctt_certif.c example.

The only thing that seemed to work was to do a full flash erase and reprogram. This let certification to reset its state, and be disabled since I'm running periodical uplink example.

It seems counter-intuitive that this is necessary. Would it be possible to change this, so that the certification state is reset every time the device boots?

[lbm-team]

Hello ThorSporringMii,

The certification mode must be stored in flash memory because the LCTT tool may require the device to reboot during the certification process.

You can enable or disable certification mode from your application using the API smtc_modem_get_certification_mode().

When running the main_lctt_certif example, you can also toggle certification mode by pressing the blue button on the Nucleo board.

We do not plan to update the other main examples at this time.

Best