AWS IoT Core - Gateway connected, join requests not forwarded

Hello,

Issue:
I have connected my RAK7268 (FW 1.3.9_RAK b186) to AWS IoT Core and I can see trace logs and a last-up time of the gateway itself in AWS.

Setup:
Gateway is connected via ethernet / basic station setup with AWS.

LoRa® Server:
AWS Iot Core

Details:
Join request from one device (Milesight AM 300, sending every 10 minutes) are shown in the LoRa Packet Logger, but they are not forwarded to the AWS´ LNS. Debug log highlights:

Thu Feb 22 21:32:29 2024 user.notice basicstation[20621]: [LOG:NOTI] Invalid DataRate value : 8
Thu Feb 22 21:32:29 2024 user.notice basicstation[20621]: [LOG:NOTI] Invalid DataRate value : 9
Thu Feb 22 21:32:29 2024 user.notice basicstation[20621]: [LOG:NOTI] Invalid DataRate value : 10
Thu Feb 22 21:32:29 2024 user.notice basicstation[20621]: [LOG:NOTI] Invalid DataRate value : 11
Thu Feb 22 21:32:29 2024 user.notice basicstation[20621]: [LOG:NOTI] Invalid DataRate value : 12
Thu Feb 22 21:32:29 2024 user.notice basicstation[20621]: [LOG:NOTI] Invalid DataRate value : 13
Thu Feb 22 21:32:29 2024 user.notice basicstation[20621]: [LOG:NOTI] Invalid DataRate value : 14
Thu Feb 22 21:32:29 2024 user.notice basicstation[20621]: [LOG:NOTI] Invalid DataRate value : 15

as well as

Thu Feb 22 21:32:29 2024 daemon.err uhttpd[2732]: Device “apcli0” does not exist.

In the time window of receiving a package from the device I see:

Thu Feb 22 21:27:16 2024 user.info basicstation[20621]: [SYN:INFO] MCU/SX130X drift stats: min: +0.0ppm  q50: +1.9ppm  q80: +5.2ppm  max: +9.0ppm - threshold q90: +8.6ppm
Thu Feb 22 21:27:16 2024 user.info basicstation[20621]: [SYN:INFO] Mean MCU drift vs SX130X#0: 2.4ppm
Thu Feb 22 21:27:29 2024 user.debug basicstation[20621]: [LOG:DEBU] execute logger timeout handler.
Thu Feb 22 21:27:43 2024 user.info basicstation[20621]: [RAL:INFO] RX mod=LORA f=868500000 bw=0 sz=23 dr=10 0001002A00C024E1242073427B7024E12404DB16C11D0C
Thu Feb 22 21:27:43 2024 user.debug basicstation[20621]: [LOG:DEBU] rrd_statistic_up-357 uplinkTqByAirtime: dr=2, timeonair=370, tm=1708633663
Thu Feb 22 21:27:43 2024 user.debug basicstation[20621]: [LOG:DEBU] rrd_statistic_up-359 uplinkTqByPkt: dr=2, tm=1708633663
Thu Feb 22 21:27:43 2024 user.debug basicstation[20621]: [LOG:DEBU] rrd_statistic_up-361 ChanBusyByAirtime: chan=7, timeonair=370, tm=1708633663
Thu Feb 22 21:27:45 2024 user.info basicstation[20621]: [SYN:INFO] Time sync qualities: min=133 q90=204 max=217 (previous q90=230)
Thu Feb 22 21:27:49 2024 user.notice data-broker: data-broker timeout, restart it
Thu Feb 22 21:27:54 2024 user.info basicstation[20621]: [SYN:INFO] MCU/SX130X drift stats: min: -0.5ppm  q50: -2.4ppm  q80: +6.7ppm  max: +11.4ppm - threshold q90: +10.5ppm
Thu Feb 22 21:27:54 2024 user.info basicstation[20621]: [SYN:INFO] Mean MCU drift vs SX130X#0: 1.7ppm
Thu Feb 22 21:28:07 2024 user.debug basicstation[20621]: [SYN:VERB] Time sync rejected: quality=228 threshold=204
Thu Feb 22 21:28:09 2024 user.debug basicstation[20621]: [SYN:VERB] Time sync rejected: quality=231 threshold=204
Thu Feb 22 21:28:11 2024 user.debug basicstation[20621]: [SYN:VERB] Time sync rejected: quality=1298 threshold=204
Thu Feb 22 21:28:26 2024 user.debug basicstation[20621]: [SYN:VERB] Time sync rejected: quality=223 threshold=204

After configuring the “Basics Station” mode (which I assume is working as expected), I get the following trace logs:

Thu Feb 22 21:43:32 2024 user.notice breathing_light: set breathing light to ABNORMAL
Thu Feb 22 21:43:33 2024 user.info basicstation[29321]: [SYS:INFO] Logging     : /var/etc/station/station.log (maxsize=10000000, rotate=3)
Thu Feb 22 21:43:33 2024 user.info basicstation[29321]: [SYS:INFO] Station Ver : 2.0.6(linux/std) 2023-06-13 07:03:53
Thu Feb 22 21:43:33 2024 user.info basicstation[29321]: [SYS:INFO] Package Ver : (null)
Thu Feb 22 21:43:33 2024 user.info basicstation[29321]: [SYS:INFO] mbedTLS Ver : 2.28.0
Thu Feb 22 21:43:33 2024 user.info basicstation[29321]: [SYS:INFO] proto EUI   : ac1f:9ff:fe06:43a	(station.conf)
Thu Feb 22 21:43:33 2024 user.info basicstation[29321]: [SYS:INFO] prefix EUI  : ::1	(builtin)
Thu Feb 22 21:43:33 2024 user.info basicstation[29321]: [SYS:INFO] Station EUI : ac1f:9ff:fe06:43a
Thu Feb 22 21:43:33 2024 user.info basicstation[29321]: [SYS:INFO] Station home: /var/etc/station/	(--home)
Thu Feb 22 21:43:33 2024 user.info basicstation[29321]: [SYS:INFO] Station bak: /etc/station/bak/	(--bak)
Thu Feb 22 21:43:33 2024 user.info basicstation[29321]: [SYS:INFO] Station temp: /var/run/	(--temp)
Thu Feb 22 21:43:33 2024 user.warn basicstation[29321]: [SYS:WARN] Station in NO-CUPS mode
Thu Feb 22 21:43:33 2024 user.debug basicstation[29321]: [LOG:VERB] initial logger socket success.
Thu Feb 22 21:43:34 2024 user.info basicstation[29321]: [TCE:INFO] Starting TC engine
Thu Feb 22 21:43:34 2024 user.info basicstation[29321]: [AIO:INFO] /var/etc/station/tc.trust:  cert. version     : 3 serial number     : A7:0E:4A:4C:34:82:B7:7F issuer name       : C=US, O=Starfield Technologies, Inc., OU=Starfield Class 2 Certification Authority subject name      : C=US, ST=Arizona, L=Scottsdale, O=Starfield Technologies, Inc., CN=Starfield Services Root Certificate Authority - G2 issued  on        : 2009-09-02 00:00:00 expires on        : 2034-06-28 17:39:16 signed using      : RSA with SHA-256 RSA key size      : 2048 bits basic constraints
Thu Feb 22 21:43:34 2024 user.info basicstation[29321]: [AIO:INFO] /var/etc/station/tc.crt:  cert. version     : 3 serial number     : 84:E7:9B:9A:20:18:79:AE:B5:D3:79:8A:E6:5D:E9:FF:69:B0:66:5A issuer name       : OU=Amazon Web Services O=Amazon.com Inc. L=Seattle ST=Washington C=US subject name      : CN=AWS IoT Certificate issued  on        : 2024-02-22 19:22:17 expires on        : 2049-12-31 23:59:59 signed using      : RSA with SHA-256 RSA key size      : 2048 bits basic constraints : CA=false key usage         : Digital Signature
Thu Feb 22 21:43:34 2024 user.info basicstation[29321]: [TCE:INFO] Connecting to INFOS: wss://A20SJQ5PGPKQ1V.lns.lorawan.eu-central-1.amazonaws.com:443
Thu Feb 22 21:43:37 2024 user.notice breathing_light: set breathing light to NORMAL
Thu Feb 22 21:43:38 2024 user.info basicstation[29321]: [TCE:INFO] Infos: ac1f:09ff:fe06:043a ac1f:09ff:fe06:043a wss://*** (i have redacted that part)
Thu Feb 22 21:43:38 2024 user.debug basicstation[29321]: [AIO:DEBU] [5] ws_close reason=1000
Thu Feb 22 21:43:38 2024 user.debug basicstation[29321]: [AIO:DEBU] [5|WS] Server sent close: reason=1006
Thu Feb 22 21:43:38 2024 user.debug basicstation[29321]: [AIO:DEBU] [5] WS connection shutdown...
Thu Feb 22 21:43:38 2024 user.info basicstation[29321]: [AIO:INFO] /var/etc/station/tc.trust:  cert. version     : 3 serial number     : A7:0E:4A:4C:34:82:B7:7F issuer name       : C=US, O=Starfield Technologies, Inc., OU=Starfield Class 2 Certification Authority subject name      : C=US, ST=Arizona, L=Scottsdale, O=Starfield Technologies, Inc., CN=Starfield Services Root Certificate Authority - G2 issued  on        : 2009-09-02 00:00:00 expires on        : 2034-06-28 17:39:16 signed using      : RSA with SHA-256 RSA key size      : 2048 bits basic constraints
Thu Feb 22 21:43:38 2024 user.info basicstation[29321]: [AIO:INFO] /var/etc/station/tc.crt:  cert. version     : 3 serial number     : 84:E7:9B:9A:20:18:79:AE:B5:D3:79:8A:E6:5D:E9:FF:69:B0:66:5A issuer name       : OU=Amazon Web Services O=Amazon.com Inc. L=Seattle ST=Washington C=US subject name      : CN=AWS IoT Certificate issued  on        : 2024-02-22 19:22:17 expires on        : 2049-12-31 23:59:59 signed using      : RSA with SHA-256 RSA key size      : 2048 bits basic constraints : CA=false key usage         : Digital Signature
Thu Feb 22 21:43:38 2024 user.debug basicstation[29321]: [TCE:VERB] Connecting to MUXS...
Thu Feb 22 21:43:41 2024 user.debug basicstation[29321]: [TCE:VERB] Connected to MUXS.
Thu Feb 22 21:43:41 2024 user.warn basicstation[29321]: [S2E:WARN] Unknown field in router_config - ignored: protocol (0xFD309030)
Thu Feb 22 21:43:41 2024 user.warn basicstation[29321]: [S2E:WARN] Unknown field in router_config - ignored: regionid (0xE6FFB211)
Thu Feb 22 21:43:41 2024 user.debug basicstation[29321]: [SYS:VERB] /etc/station/radio_init.sh: Forked, waiting...
Thu Feb 22 21:43:41 2024 user.debug basicstation[29732]: [SYS:DEBU] execvp argv[0]: </etc/station/radio_init.sh>
Thu Feb 22 21:43:41 2024 user.debug basicstation[29732]: [SYS:DEBU]        argv[1]: </dev/spidev32766.1>
Thu Feb 22 21:43:41 2024 user.notice aws: startsvc
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [SYS:INFO] Process /etc/station/radio_init.sh (pid=29732) completed
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] Lora gateway library version: Version: 2.1.0;
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] [LGW sx1302] full_duplex=0 clksrc=0 lorawan_public=1
Thu Feb 22 21:43:41 2024 user.debug basicstation[29321]: [RAL:DEBU] SX130x txlut table (16 entries)
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut  0:  rf_power=-6 pa_gain=0 pwr_idx=9
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut  1:  rf_power=-3 pa_gain=0 pwr_idx=12
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut  2:  rf_power=0 pa_gain=0 pwr_idx=17
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut  3:  rf_power=3 pa_gain=0 pwr_idx=19
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut  4:  rf_power=6 pa_gain=0 pwr_idx=21
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut  5:  rf_power=10 pa_gain=1 pwr_idx=1
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut  6:  rf_power=11 pa_gain=1 pwr_idx=3
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut  7:  rf_power=12 pa_gain=1 pwr_idx=4
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut  8:  rf_power=13 pa_gain=1 pwr_idx=5
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut  9:  rf_power=14 pa_gain=1 pwr_idx=6
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut 10:  rf_power=16 pa_gain=1 pwr_idx=8
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut 11:  rf_power=20 pa_gain=1 pwr_idx=13
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut 12:  rf_power=23 pa_gain=1 pwr_idx=16
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut 13:  rf_power=25 pa_gain=1 pwr_idx=18
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut 14:  rf_power=26 pa_gain=1 pwr_idx=19
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] SX1302 txlut 15:  rf_power=27 pa_gain=1 pwr_idx=21
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  RX/TX RF0:    867.5MHz rssi_offset=-215.4 type=5 rssi_tcomp=0.000 0.000 20.410 2162.560 0.000
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  RX    RF1:    868.5MHz rssi_offset=-215.4 type=5 rssi_tcomp=0.000 0.000 20.410 2162.560 0.000
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  [mSF]   0:    867.1MHz rf=0 freq=-400.0 datarate=0
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  [mSF]   1:    867.3MHz rf=0 freq=-200.0 datarate=0
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  [mSF]   2:    867.5MHz rf=0 freq=  +0.0 datarate=0
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  [mSF]   3:    867.7MHz rf=0 freq=+200.0 datarate=0
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  [mSF]   4:    867.9MHz rf=0 freq=+400.0 datarate=0
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  [mSF]   5:    868.1MHz rf=1 freq=-400.0 datarate=0
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  [mSF]   6:    868.3MHz rf=1 freq=-200.0 datarate=0
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  [mSF]   7:    868.5MHz rf=1 freq=  +0.0 datarate=0
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  [STD]   8:    868.3MHz rf=1 freq=-200.0 datarate=7 bw=5 Explicit header
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO]  [FSK]   9:    867.5MHz rf=0 freq=  +0.0 datarate=0 bw=0 sync_word=0/0
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [RAL:INFO] Station device: spi:/dev/spidev32766.1 (PPS capture disabled)
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [HAL:INFO] [lgw_com_open:84] Opening SPI communication interface
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [HAL:INFO] [lgw_spi_open:88] Setting SPI speed to 2000000
Thu Feb 22 21:43:41 2024 user.info basicstation[29321]: [HAL:INFO] [lgw_connect:1192] chip version is 0x10 (v1.0)
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [HAL:INFO] [timestamp_counter_mode:431] using legacy timestamp
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [RAL:INFO] Concentrator started (2s542ms)
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO] Configuring for region: EU868 -- 863.0MHz..870.0MHz
Thu Feb 22 21:43:44 2024 user.debug basicstation[29321]: [LOG:DEBU] Region for EU868
Thu Feb 22 21:43:44 2024 user.debug basicstation[29321]: [LOG:VERB] rrd initial success.
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR0  SF12/BW125
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR1  SF11/BW125
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR2  SF10/BW125
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR3  SF9/BW125
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR4  SF8/BW125
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR5  SF7/BW125
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR6  SF7/BW250
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR7  FSK
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR8  undefined
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR9  undefined
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR10 undefined
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR11 undefined
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR12 undefined
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR13 undefined
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR14 undefined
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   DR15 undefined
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   TX power: 16.0 dBm EIRP
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]             27.0 dBm EIRP for 869.4MHz..869.65MHz
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   JoinEUI list: 10 entries
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   NetID filter: FFFFFFFF-FFFFFFFF-FFFFFFFF-FFFFFFFF
Thu Feb 22 21:43:44 2024 user.info basicstation[29321]: [S2E:INFO]   Dev/test settings: nocca=0 nodc=0 nodwell=0

I am not really sure what I should try next… AWS Configuration (rules / destinations etc. are setup correct, however, I do not think that the issue is really buried there…)

Please help :slight_smile:

BR

Hi @wutzenberger Can you share the full systemlogs?

Hello,

log of the last hour, unfiltered:
https://paste.quest/?ab90271f0185646b#9R8ymPEiCTVtSzJh4Pa71wz3iybTdE1gfuTuzNFTSDqg

Would you need anything else?

Thanks

Hi,

sorry if I caused you any effort in reviewing the log. I found the issue, the app key was not correct.

I saw that the gateway was communicating but no join request message was published on the mqtt topic (even though I did setup the events in AWS’ settings). Not sure where to monitor failed app keys on AWS or maybe on the router.

Thanks again for your efforts

BR

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