Basicstation - no downlinks to end-node after join accept

ISSUE: No downlink received by the device but device is able to join and is being join accepted

NOTE: I have tried confirmed and unconfirmed downlinks and both have the same behavior.

After the device’s uplink, the scheduled downlink would be gone from the queue but the device still won’t receive a downlink.

pps is also disabled as the gps is also disabled

Here are the logs

root@dragino-1fd03c:/etc/station# station -l XDEBUG -p
   str RADIODEV             = "/dev/spidev?.0" builtin      default radio device
    u4 LOGFILE_SIZE         = "10MB"     builtin      default size of a logfile
    u4 LOGFILE_ROTATE       = 3          builtin      besides current log file keep *.1..N (none if 0)
    u4 TCP_KEEPALIVE_EN     = 1          builtin      TCP keepalive enabled
    u4 TCP_KEEPALIVE_IDLE   = 60         builtin      TCP keepalive TCP_KEEPIDLE [s]
    u4 TCP_KEEPALIVE_INTVL  = 15         builtin      TCP keepalive TCP_KEEPINTVL [s]
    u4 TCP_KEEPALIVE_CNT    = 4          builtin      TCP keepalive TCP_KEEPCNT
    u4 MAX_JOINEUI_RANGES   = 10         builtin      max ranges to suppress unwanted join requests
ustime CUPS_CONN_TIMEOUT    = "60s"      builtin      connection timeout
ustime CUPS_OKSYNC_INTV     = "24h"      builtin      regular check-in with CUPS for updates
ustime CUPS_RESYNC_INTV     = "1s"       station.conf check-in with CUPS for updates after a failure
    u4 CUPS_BUFSZ           = "8KB"      builtin      read from CUPS in chunks of this size
ustime GPS_REPORT_DELAY     = "120s"     builtin      delay GPS reports and consolidate
ustime GPS_REOPEN_TTY_INTV  = "1s"       builtin      recheck TTY open if it failed
ustime GPS_REOPEN_FIFO_INTV = "1s"       builtin      recheck if FIFO writer fake GPS
ustime CMD_REOPEN_FIFO_INTV = "1s"       builtin      recheck if FIFO writer
ustime RX_POLL_INTV         = "20ms"     builtin      interval to poll SX1301 RX FIFO
ustime TC_TIMEOUT           = "60s"      builtin      reconnected to muxs
ustime CLASS_C_BACKOFF_BY   = "100ms"    builtin      retry interval for class C TX attempts
    u4 CLASS_C_BACKOFF_MAX  = 10         builtin      max number of class C TX attempts
ustime RADIO_INIT_WAIT      = "5s"       station.conf max wait for radio init command to finish
ustime PPS_VALID_INTV       = "10m"      builtin      max age of last PPS sync for GPS time conversions
ustime TIMESYNC_RADIO_INTV  = "2100ms"   builtin      interval to resync MCU/SX1301
ustime TIMESYNC_LNS_RETRY   = "71ms"     builtin      resend timesync message to server
ustime TIMESYNC_LNS_PAUSE   = "5s"       builtin      pause after unsuccessful volley of timesync messages
    u4 TIMESYNC_LNS_BURST   = 10         builtin      volley of timesync messages before pausing
ustime TIMESYNC_REPORTS     = "5m"       builtin      report interval for current timesync status
ustime TX_MIN_GAP           = "10ms"     builtin      min distance between two frames being TXed
ustime TX_AIM_GAP           = "20ms"     builtin      aim for this TX lead time, if delayed should not fall under min
ustime TX_MAX_AHEAD         = "600s"     builtin      maximum time message can be scheduled into the future
ustime TXCHECK_FUDGE        = "5ms"      builtin      check radio state this time into ongoing TX
ustime BEACON_INTVL         = "128s"     builtin      beaconing interval
    u4 TLS_SNI              = true       builtin      Set and verify server name of TLS connections
2022-03-04 08:36:08.603 [SYS:INFO] Logging     : stderr (maxsize=10000000, rotate=3)
2022-03-04 08:36:08.605 [SYS:INFO] Station Ver : 2.0.5(mips-openwrt/dragino) 2022-02-03 01:03:02
2022-03-04 08:36:08.606 [SYS:INFO] Package Ver : (null)
2022-03-04 08:36:08.606 [SYS:INFO] proto EUI   : Test    (station.conf file /etc/station/gwid.conf)
2022-03-04 08:36:08.607 [SYS:INFO] prefix EUI  : ::1    (builtin)
2022-03-04 08:36:08.608 [SYS:INFO] Station EUI : Test
2022-03-04 08:36:08.608 [SYS:INFO] Station home: ./     (builtin)
2022-03-04 08:36:08.608 [SYS:INFO] Station temp: /var/tmp/      (builtin)
2022-03-04 08:36:08.609 [SYS:WARN] Station in NO-CUPS mode
2022-03-04 08:36:08.810 [TCE:INFO] Starting TC engine
2022-03-04 08:36:08.814 [any:INFO] ./tc.trust: 
cert. version     : 3
serial number     : Test
issuer name       : CN=Test, C=Test, ST=Test, L=Test, O=Test
subject name      : CN=Test, C=Test, ST=Test, L=Test, O=Test
issued  on        : 2021-07-16 07:49:53
expires on        : 2031-07-14 07:49:53
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=true
2022-03-04 08:36:08.816 [any:INFO] ./tc.crt: 
cert. version     : 3
serial number     : Test
issuer name       : CN=Test, C=US, ST=Test, L=Test, O=Test
subject name      : C=US, ST=Test, L=Test, O=Test, CN=Test
issued  on        : 2022-03-02 12:19:50
expires on        : 2032-02-28 12:19:50
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
key usage2022-03-04 08:36:08.816 [AIO:INFO] 
2022-03-04 08:36:10.021 [AIO:XDEB] [3] ws_connecting state=1
2022-03-04 08:36:10.021 [TCE:INFO] Connecting to INFOS: wss://us.gatewaybridge.chirpstack.helloTest.com:3001
2022-03-04 08:36:11.082 [AIO:XDEB] [3] ws_connecting state=1
2022-03-04 08:36:14.020 [AIO:XDEB] [3] ws_connecting state=1
2022-03-04 08:36:14.021 [AIO:XDEB] [3] ws_connecting state=2
2022-03-04 08:36:14.981 [AIO:XDEB] [3] ws_connecting state=3
2022-03-04 08:36:14.981 [AIO:XDEB] [3|WS] > {"router":"Test"}
2022-03-04 08:36:15.940 [AIO:XDEB] [3|WS] < {"router":"Test","muxs":"Test","uri":"wss://Test.com:3001/gateway/Test"}
2022-03-04 08:36:15.941 [TCE:INFO] Infos: Test Test wss://Test.com:3001/gateway/Test
2022-03-04 08:36:15.941 [AIO:DEBU] [3] ws_close reason=1000
2022-03-04 08:36:15.941 [AIO:ERRO] Recv failed: SSL - The peer notified us that the connection is going to be closed
2022-03-04 08:36:15.941 [AIO:DEBU] [3] WS connection shutdown...
2022-03-04 08:36:15.944 [any:INFO] ./tc.trust: 
cert. version     : 3
serial number     : Test
issuer name       : CN=Test, C=Test, ST=Test, L=Test, O=Test
subject name      : CN=Test, C=Test, ST=Test, L=Test, O=Test
issued  on        : 2021-07-16 07:49:53
expires on        : 2031-07-14 07:49:53
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=true
2022-03-04 08:36:15.946 [any:INFO] ./tc.crt: 
cert. version     : 3
serial number     : 3D:AB:7E:C7:0F:0E:55:2E:D0:7B:C5:1C:87:20:50:DD:2E:9A:0E:A5
issuer name       : CN=Test, C=Test, ST=Test, L=Test, O=Test
subject name      : C=Test, ST=Test, L=Test, O=Test, CN=Test
issued  on        : 2022-03-02 12:19:50
expires on        : 2032-02-28 12:19:50
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=false
key usage2022-03-04 08:36:15.946 [AIO:INFO] 
2022-03-04 08:36:16.625 [AIO:XDEB] [3] ws_connecting state=1
2022-03-04 08:36:16.626 [TCE:VERB] Connecting to MUXS...
2022-03-04 08:36:17.545 [AIO:XDEB] [3] ws_connecting state=1
2022-03-04 08:36:21.379 [AIO:XDEB] [3] ws_connecting state=1
2022-03-04 08:36:21.380 [AIO:XDEB] [3] ws_connecting state=2
2022-03-04 08:36:22.341 [AIO:XDEB] [3] ws_connecting state=3
2022-03-04 08:36:22.341 [TCE:VERB] Connected to MUXS.
2022-03-04 08:36:22.342 [AIO:XDEB] [3|WS] > {"msgtype":"version","station":"2.0.5(mips-openwrt/dragino)","firmware":null,"package":null,"model":"mips-openwrt","protocol":2,"features":"rmtsh"}
2022-03-04 08:36:23.310 [AIO:XDEB] [3|WS] < {"msgtype":"router_config","NetID":null,"JoinEui":null,"region":"US902","hwspec":"sx1301/1","freq_range":[902000000,928000000],"DRs":[[10,125,0],[9,125,0],[8,125,0],[7,125,0],[8,500,0],[-1,0,0],[-1,0,0],[-1,0,0],[12,500,1],[11,500,1],[10,500,1],[9,500,1],[8,500,0],[7,500,1],[-1,0,0],[-1,0,0]],"sx1301_conf":[{"radio_0":{"enable":true,"freq":902700000},"radio_1":{"enable":true,"freq":903700000},"chan_FSK":{"enable":false},"chan_Lora_std":{"enable":true,"radio"
2022-03-04 08:36:23.310 [AIO:XDEB] [3|WS] . :0,"if":300000,"bandwidth":500000,"spread_factor":8},"chan_multiSF_0":{"enable":true,"radio":0,"if":-400000},"chan_multiSF_1":{"enable":true,"radio":0,"if":-200000},"chan_multiSF_2":{"enable":true,"radio":0,"if":0},"chan_multiSF_3":{"enable":true,"radio":0,"if":200000},"chan_multiSF_4":{"enable":true,"radio":0,"if":400000},"chan_multiSF_5":{"enable":true,"radio":1,"if":-400000},"chan_multiSF_6":{"enable":true,"radio":1,"if":-200000},"chan_multiSF_7":{"enable
2022-03-04 08:36:23.310 [AIO:XDEB] [3|WS] . ":true,"radio":1,"if":0}}]}
2022-03-04 08:36:23.314 [SYS:VERB] rinit.sh: Forked, waiting...
CoreCell reset through GPIO23...
2022-03-04 08:36:28.411 [SYS:INFO] Process rinit.sh (pid=9574) completed
lgw_board_setconf:425: Note: board configuration; lorawan_public:1, clksrc:1
lgw_rxrf_setconf:474: WARNING: NOT A VALID TX NOTCH FILTER FREQUENCY [126000..250000]Hz
lgw_rxrf_setconf:486: Note: rf_chain 0 configuration; en:1 freq:902700000 rssi_offset:-166.000000 radio_type:2 tx_enable:1 tx_notch_freq:0
lgw_rxrf_setconf:486: Note: rf_chain 1 configuration; en:1 freq:903700000 rssi_offset:-166.000000 radio_type:2 tx_enable:0 tx_notch_freq:0
lgw_rxif_setconf:605: Note: LoRa 'multi' if_chain 0 configuration; en:1 freq:-400000 SF_mask:0x7e
lgw_rxif_setconf:605: Note: LoRa 'multi' if_chain 1 configuration; en:1 freq:-200000 SF_mask:0x7e
lgw_rxif_setconf:605: Note: LoRa 'multi' if_chain 2 configuration; en:1 freq:0 SF_mask:0x7e
lgw_rxif_setconf:605: Note: LoRa 'multi' if_chain 3 configuration; en:1 freq:200000 SF_mask:0x7e
lgw_rxif_setconf:605: Note: LoRa 'multi' if_chain 4 configuration; en:1 freq:400000 SF_mask:0x7e
lgw_rxif_setconf:605: Note: LoRa 'multi' if_chain 5 configuration; en:1 freq:-400000 SF_mask:0x7e
lgw_rxif_setconf:605: Note: LoRa 'multi' if_chain 6 configuration; en:1 freq:-200000 SF_mask:0x7e
lgw_rxif_setconf:605: Note: LoRa 'multi' if_chain 7 configuration; en:1 freq:0 SF_mask:0x7e
lgw_rxif_setconf:579: Note: LoRa 'std' if_chain 8 configuration; en:1 freq:300000 bw:1 dr:4
lgw_rxif_setconf:513: Note: if_chain 9 disabled
2022-03-04 08:36:28.412 [RAL:INFO] Lora gateway library version: Version: 2.0.5;
2022-03-04 08:36:28.426 [RAL:VERB] Connecting to device: /dev/spidev1.0
2022-03-04 08:36:28.426 [RAL:DEBU] SX130x txlut table (0 entries)
2022-03-04 08:36:28.426 [RAL:VERB] SX1301 rxrfchain 0: enable=1 freq=902.7MHz rssi_offset=-166.000000 type=2 tx_enable=1 tx_notch_freq=0
2022-03-04 08:36:28.426 [RAL:VERB] SX1301 rxrfchain 1: enable=1 freq=903.7MHz rssi_offset=-166.000000 type=2 tx_enable=0 tx_notch_freq=0
2022-03-04 08:36:28.427 [RAL:VERB] SX1301 ifchain  0: enable=1 rf_chain=0 freq=-400000 bandwidth=0 datarate=0 sync_word=0/0
2022-03-04 08:36:28.427 [RAL:VERB] SX1301 ifchain  1: enable=1 rf_chain=0 freq=-200000 bandwidth=0 datarate=0 sync_word=0/0
2022-03-04 08:36:28.427 [RAL:VERB] SX1301 ifchain  2: enable=1 rf_chain=0 freq=0 bandwidth=0 datarate=0 sync_word=0/0
2022-03-04 08:36:28.427 [RAL:VERB] SX1301 ifchain  3: enable=1 rf_chain=0 freq=200000 bandwidth=0 datarate=0 sync_word=0/0
2022-03-04 08:36:28.427 [RAL:VERB] SX1301 ifchain  4: enable=1 rf_chain=0 freq=400000 bandwidth=0 datarate=0 sync_word=0/0
2022-03-04 08:36:28.427 [RAL:VERB] SX1301 ifchain  5: enable=1 rf_chain=1 freq=-400000 bandwidth=0 datarate=0 sync_word=0/0
2022-03-04 08:36:28.427 [RAL:VERB] SX1301 ifchain  6: enable=1 rf_chain=1 freq=-200000 bandwidth=0 datarate=0 sync_word=0/0
2022-03-04 08:36:28.427 [RAL:VERB] SX1301 ifchain  7: enable=1 rf_chain=1 freq=0 bandwidth=0 datarate=0 sync_word=0/0
2022-03-04 08:36:28.427 [RAL:VERB] SX1301 ifchain  8: enable=1 rf_chain=0 freq=300000 bandwidth=1 datarate=4 sync_word=0/0
2022-03-04 08:36:28.428 [RAL:VERB] SX1301 ifchain  9: enable=0 rf_chain=0 freq=0 bandwidth=0 datarate=0 sync_word=0/0
2022-03-04 08:36:28.428 [RAL:VERB] SX130x LBT not enabled
2022-03-04 08:36:28.435 [RAL:VERB] Station device: /dev/spidev1.0 (PPS capture disabled)
lgw_start:823: Note: calibration started (time: 2300 ms)
lgw_start:844: Note: calibration finished (status = 183)
WARNING: problem in calibration of radio A for image rejection
Info: Initialising AGC firmware...
Info: putting back original RADIO_SELECT value
2022-03-04 08:36:31.791 [RAL:VERB] Concentrator started (3s354ms)
2022-03-04 08:36:31.791 [S2E:INFO] Configuring for region: US902 -- 902.0MHz..928.0MHz
2022-03-04 08:36:31.791 [S2E:VERB]   DR0  SF10/BW125 
2022-03-04 08:36:31.791 [S2E:VERB]   DR1  SF9/BW125 
2022-03-04 08:36:31.792 [S2E:VERB]   DR2  SF8/BW125 
2022-03-04 08:36:31.792 [S2E:VERB]   DR3  SF7/BW125 
2022-03-04 08:36:31.792 [S2E:VERB]   DR4  SF8/BW500 
2022-03-04 08:36:31.792 [S2E:VERB]   DR5  undefined
2022-03-04 08:36:31.792 [S2E:VERB]   DR6  undefined
2022-03-04 08:36:31.792 [S2E:VERB]   DR7  undefined
2022-03-04 08:36:31.792 [S2E:VERB]   DR8  SF12/BW500 (DN only)
2022-03-04 08:36:31.792 [S2E:VERB]   DR9  SF11/BW500 (DN only)
2022-03-04 08:36:31.792 [S2E:VERB]   DR10 SF10/BW500 (DN only)
2022-03-04 08:36:31.792 [S2E:VERB]   DR11 SF9/BW500 (DN only)
2022-03-04 08:36:31.792 [S2E:VERB]   DR12 SF8/BW500 
2022-03-04 08:36:31.792 [S2E:VERB]   DR13 SF7/BW500 (DN only)
2022-03-04 08:36:31.792 [S2E:VERB]   DR14 undefined
2022-03-04 08:36:31.792 [S2E:VERB]   DR15 undefined
2022-03-04 08:36:31.793 [S2E:VERB]   TX power: 30.0 dBm EIRP
2022-03-04 08:36:31.793 [S2E:VERB]   JoinEui list: 0 entries
2022-03-04 08:36:31.793 [S2E:VERB]   NetID filter: FFFFFFFF-FFFFFFFF-FFFFFFFF-FFFFFFFF
2022-03-04 08:36:31.793 [S2E:VERB]   Dev/test settings: nocca=0 nodc=0 nodwell=0
2022-03-04 08:37:13.800 [SYN:INFO] MCU/SX130X drift stats: min: -0.5ppm  q50: -4.3ppm  q80: -8.1ppm  max: -19.5ppm - threshold q90: +11.4ppm
2022-03-04 08:37:13.800 [SYN:INFO] Mean MCU drift vs SX130X#0: -4.3ppm
lgw_receive:1155: FIFO content: 5 10 0 5 18
lgw_receive:1174: [2 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 4 38 0 5 18
lgw_receive:1174: [1 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 3 60 0 5 18
lgw_receive:1174: [0 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 2 88 0 7 18
lgw_receive:1174: [3 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 1 b0 0 7 18
lgw_receive:1174: [5 17]
Note: LoRa packet
2022-03-04 08:37:14.394 [any:XDEB] RX mod=LORA f=902700000 bw=125 sz=24 dr=16 40581101FC80050002086E0184FD1B4C35D2C00E5CF47D25
2022-03-04 08:37:14.398 [any:XDEB] RX mod=LORA f=902500000 bw=125 sz=24 dr=16 40581101FC80050002086E0184FD1B4C35D2C00E5CF47D25
2022-03-04 08:37:14.398 [S2E:DEBU] Dropped mirror frame freq=902.7MHz snr= -4.5 rssi=-82 (vs. freq=902.5MHz snr= 13.8 rssi=-36) - DR0 mic=629011548 (24 byes)
2022-03-04 08:37:14.402 [any:XDEB] RX mod=LORA f=902300000 bw=125 sz=24 dr=16 40581101FC80050002086E0184FD1B4C35D2C00E5CF47D25
2022-03-04 08:37:14.402 [S2E:DEBU] Dropped mirror frame freq=902.3MHz snr= -4.2 rssi=-83 (vs. freq=902.5MHz snr= 13.8 rssi=-36) - DR0 mic=629011548 (24 byes)
2022-03-04 08:37:14.405 [any:XDEB] RX mod=LORA f=902900000 bw=125 sz=24 dr=16 40581B0DFC80A55A82686601C2FD13E025D24C0FDCFC2F25
2022-03-04 08:37:14.406 [any:XDEB] Dropped frame without CRC or with broken CRC
2022-03-04 08:37:14.409 [any:XDEB] RX mod=LORA f=903300000 bw=125 sz=24 dr=16 40581101FC80A5638A686E0184B7134C75D14C6EDAFC7D29
2022-03-04 08:37:14.409 [any:XDEB] Dropped frame without CRC or with broken CRC
2022-03-04 08:37:14.410 [S2E:VERB] RX 902.5MHz DR0 SF10/BW125 snr=13.8 rssi=-36 xtime=0xB7000002B50B2C - updf mhdr=40 DevAddr=FC011158 FCtrl=80 FCnt=5 FOpts=[] 02086E01..C00E mic=629011548 (24 bytes)
2022-03-04 08:37:14.410 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":-67038888,"FCtrl":128,"FCnt":5,"FOpts":"","FPort":2,"FRMPayload":"086E0184FD1B4C35D2C00E","MIC":629011548,"RefTime":0.000000,"DR":0,"Freq":902500000,"upinfo":{"rctx":0,"xtime":51509920783469356,"gpstime":0,"fts":-1,"rssi":-36,"snr":13.75,"rxtime":1646383034.410465}}
2022-03-04 08:37:15.921 [AIO:XDEB] [3|WS] < {"msgtype":"dnmsg","DevEui":"01-01-01-01-01-01-01-01","dC":0,"diid":45673,"pdu":"60581101fc8a030003000100700342ff000169576f7e","priority":1,"RxDelay":1,"RX1DR":10,"RX1Freq":923900000,"xtime":51509920783469356,"rctx":0}
2022-03-04 08:37:15.921 [S2E:VERB] 101:101:101:101 diid=45673 [ant#0] - class A has no more alternate TX time
2022-03-04 08:37:32.703 [SYN:INFO] Time sync qualities: min=83 q90=136 max=164 (previous q90=2147483647)
2022-03-04 08:37:51.606 [SYN:VERB] Time sync rejected: quality=137 threshold=136
2022-03-04 08:37:57.907 [SYN:INFO] MCU/SX130X drift stats: min: +1.0ppm  q50: -4.3ppm  q80: -4.8ppm  max: -10.0ppm - threshold q90: -7.1ppm
2022-03-04 08:37:57.907 [SYN:INFO] Mean MCU drift vs SX130X#0: -4.3ppm
2022-03-04 08:38:16.809 [SYN:VERB] Time sync rejected: quality=139 threshold=136
2022-03-04 08:38:18.910 [SYN:VERB] Time sync rejected: quality=142 threshold=136
2022-03-04 08:38:22.332 [AIO:XDEB] [3|WS] < PING ()
2022-03-04 08:38:22.332 [AIO:XDEB] [3|WS] > PONG
2022-03-04 08:38:35.712 [SYN:INFO] Time sync qualities: min=98 q90=137 max=142 (previous q90=136)

-------------------------------------------------------------------------------------------------------------------
`device is currently about to join`
-------------------------------------------------------------------------------------------------------------------

2022-03-04 08:38:44.113 [SYN:INFO] MCU/SX130X drift stats: min: +0.5ppm  q50: -4.3ppm  q80: -5.2ppm  max: -7.6ppm - threshold q90: -5.9ppm
2022-03-04 08:38:44.113 [SYN:INFO] Mean MCU drift vs SX130X#0: -4.3ppm
 2022-03-04 08:38:49.365 [SYN:ERRO] Repeated excessive clock drifts between MCU/SX130X#0 (3 retries): -6.9ppm (threshold 5.9ppm)
lgw_receive:1155: FIFO content: 5 d8 0 5 17
lgw_receive:1174: [3 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 4 ff 0 5 17
lgw_receive:1174: [2 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 3 26 1 7 17
lgw_receive:1174: [1 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 2 4d 1 5 17
lgw_receive:1174: [5 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 1 74 1 5 17
lgw_receive:1174: [4 17]
Note: LoRa packet
2022-03-04 08:38:53.307 [any:XDEB] RX mod=LORA f=902900000 bw=125 sz=23 dr=16 0000010000000000A0FBB78281654140A8DF3171C29881
2022-03-04 08:38:53.311 [any:XDEB] RX mod=LORA f=902700000 bw=125 sz=23 dr=16 0000010000000000A0FBB78281654140A8DF3171C29881
2022-03-04 08:38:53.311 [S2E:DEBU] Dropped mirror frame freq=902.7MHz snr= -5.5 rssi=-83 (vs. freq=902.9MHz snr= 11.5 rssi=-33) - DR0 mic=-2120695183 (23 byes)
2022-03-04 08:38:53.315 [any:XDEB] RX mod=LORA f=902500000 bw=125 sz=23 dr=16 9000010000000000A0FBB78281654140A8DF3171C29881
2022-03-04 08:38:53.315 [any:XDEB] Dropped frame without CRC or with broken CRC
2022-03-04 08:38:53.318 [any:XDEB] RX mod=LORA f=903300000 bw=125 sz=23 dr=16 0000010000000000A0FBB78281654140A8DF3171C29881
2022-03-04 08:38:53.319 [S2E:DEBU] Dropped mirror frame freq=903.3MHz snr=-11.8 rssi=-101 (vs. freq=902.9MHz snr= 11.5 rssi=-33) - DR0 mic=-2120695183 (23 byes)
2022-03-04 08:38:53.322 [any:XDEB] RX mod=LORA f=903100000 bw=125 sz=23 dr=16 0000010000000000A0FBB78281654140A8DF3171C29881
2022-03-04 08:38:53.323 [S2E:DEBU] Dropped mirror frame freq=903.1MHz snr= -4.5 rssi=-85 (vs. freq=902.9MHz snr= 11.5 rssi=-33) - DR0 mic=-2120695183 (23 byes)
2022-03-04 08:38:53.323 [S2E:VERB] RX 902.9MHz DR0 SF10/BW125 snr=11.5 rssi=-33 xtime=0xB70000089A7C04 - jreq MHdr=00 JoinEui=a000::100 DevEui=a840:4165:8182:b7fb DevNonce=12767 MIC=-2120695183
2022-03-04 08:38:53.323 [AIO:XDEB] [3|WS] > {"msgtype":"jreq","MHdr":0,"JoinEui":"A0-00-00-00-00-00-01-00","DevEui":"A8-40-41-65-81-82-B7-FB","DevNonce":12767,"MIC":-2120695183,"RefTime":0.000000,"DR":0,"Freq":902900000,"upinfo":{"rctx":0,"xtime":51509920882392068,"gpstime":0,"fts":-1,"rssi":-33,"snr":11.5,"rxtime":1646383133.323824}}
lgw_receive:1155: FIFO content: 1 9b 1 7 d5
lgw_receive:1174: [6 17]
Note: LoRa packet
2022-03-04 08:38:53.632 [any:XDEB] RX mod=LORA f=903500000 bw=125 sz=213 dr=2 F5975FEF03B3C3B561566BBC781232F26B88022D7DCB600C62E23ECCB6591FCDA2EB69512FC9C974C50D934DF917E697CA78AB5BA4AF655E5B335159934B855E2ECEA9217717CC872141E26C06A3EE5C8AC67AF5C394995F7E589E4898AF0BB36878CB6C67D9E0C1A3FA1CFE916DF8BE26EF2A544F7534208C9156254E5953E2902225C5AEBEB4E5C58D5E28C095DB1C938D3726A51714E1BF653C499A2B095A91491F2F147142E85B6350939ACAFD13A7CA61C695C86A4577E4941D3CDF1C97EB8FE1B566DD487CC63477961A94832DAFB1C02B8C
2022-03-04 08:38:53.632 [any:XDEB] Dropped frame without CRC or with broken CRC
2022-03-04 08:38:54.602 [AIO:XDEB] [3|WS] < {"msgtype":"dnmsg","DevEui":"01-01-01-01-01-01-01-01","dC":0,"diid":21603,"pdu":"20976f7da1114fd624fae42e24c28b0b770f56f269ced68a7ff9513227a4d6e9c4","priority":1,"RxDelay":5,"RX1DR":10,"RX1Freq":925100000,"xtime":51509920882392068,"rctx":0}
2022-03-04 08:38:54.603 [S2E:DEBU] 101:101:101:101 diid=21603 [ant#0] - next TX start ahead by 3s695ms
INFO: tx_start_delay=1497 (1497.000000) - (1497, bw_delay=0.000000, notch_delay=0.000000)
Info: packet will be sent without CRC
a7.46.66.8.e6.c1.6b.1.0.1a.21.12.0.8.0.0.20.97.6f.7d.a1.11.4f.d6.24.fa.e4.2e.24.c2.8b.b.77.f.56.f2.69.ce.d6.8a.7f.f9.51.32.27.a4.d6.e9.c4.end
2022-03-04 08:38:58.278 [S2E:VERB] 101:101:101:101 diid=21603 [ant#0] - starting TX in 19ms910us
2022-03-04 08:38:58.303 [AIO:XDEB] [3|WS] > {"msgtype":"dntxed","seqno":21603,"diid":21603,"DR":10,"Freq":925100000,"DevEui":"01-01-01-01-01-01-01-01","rctx":0,"xtime":51509920887392068,"txtime":1057.558640,"gpstime":0}
2022-03-04 08:38:58.304 [S2E:INFO] TX 101:101:101:101 diid=21603 [ant#0] - dntxed: 925.1MHz 30.0dBm ant#0(0) DR10 SF10/BW500 frame=20976F7DA1114FD624FAE42E..A4D6E9C4
2022-03-04 08:38:58.411 [S2E:DEBU] Tx done diid=21603
lgw_receive:1155: FIFO content: 4 9f 2 7 f
lgw_receive:1174: [3 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 3 be 2 5 f
lgw_receive:1174: [2 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 2 80 2 5 f
lgw_receive:1174: [1 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 1 dd 2 7 f
lgw_receive:1174: [0 17]
Note: LoRa packet
2022-03-04 08:38:58.778 [any:XDEB] RX mod=LORA f=902900000 bw=125 sz=15 dr=16 403D1001FC8100000D029395373554
2022-03-04 08:38:58.778 [any:XDEB] Dropped frame without CRC or with broken CRC
2022-03-04 08:38:58.782 [any:XDEB] RX mod=LORA f=902700000 bw=125 sz=15 dr=16 403D1001FC8100000D029395372554
2022-03-04 08:38:58.785 [any:XDEB] RX mod=LORA f=902500000 bw=125 sz=15 dr=16 403D1001FC8100000D029395372554
2022-03-04 08:38:58.786 [S2E:DEBU] Dropped mirror frame freq=902.5MHz snr= -5.8 rssi=-82 (vs. freq=902.7MHz snr=  7.8 rssi=-33) - DR0 mic=1411725205 (15 byes)
2022-03-04 08:38:58.789 [any:XDEB] RX mod=LORA f=902300000 bw=125 sz=15 dr=16 403D1001FC8100000D029395372555
2022-03-04 08:38:58.789 [any:XDEB] Dropped frame without CRC or with broken CRC
2022-03-04 08:38:58.790 [S2E:VERB] RX 902.7MHz DR0 SF10/BW125 snr=7.8 rssi=-33 xtime=0xB7000008EDC774 - updf mhdr=40 DevAddr=FC01103D FCtrl=81 FCnt=0 FOpts=[0D] 0293 mic=1411725205 (15 bytes)
2022-03-04 08:38:58.790 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":-67039171,"FCtrl":129,"FCnt":0,"FOpts":"0D","FPort":2,"FRMPayload":"93","MIC":1411725205,"RefTime":0.000000,"DR":0,"Freq":902700000,"upinfo":{"rctx":0,"xtime":51509920887850868,"gpstime":0,"fts":-1,"rssi":-33,"snr":7.75,"rxtime":1646383138.790409}}
2022-03-04 08:38:58.816 [SYN:VERB] Time sync rejected: quality=140 threshold=137
2022-03-04 08:39:00.921 [AIO:XDEB] [3|WS] < {"msgtype":"dnmsg","DevEui":"01-01-01-01-01-01-01-01","dC":0,"diid":18289,"pdu":"603d1001fc800000001ef77a5455082f98149cbd5a2cdd64ff000ade45","priority":1,"RxDelay":1,"RX1DR":10,"RX1Freq":924500000,"xtime":51509920887850868,"rctx":0}
2022-03-04 08:39:00.921 [S2E:VERB] 101:101:101:101 diid=18289 [ant#0] - class A has no more alternate TX time
lgw_receive:1155: FIFO content: 3 4c 3 5 18
lgw_receive:1174: [1 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 2 fc 2 5 18
lgw_receive:1174: [2 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 1 24 3 5 18
lgw_receive:1174: [0 17]
Note: LoRa packet
2022-03-04 08:39:01.930 [any:XDEB] RX mod=LORA f=902500000 bw=125 sz=24 dr=16 403D1001FC80010002369F9BAE4523B9D5540B99B2D002A2
2022-03-04 08:39:01.933 [any:XDEB] RX mod=LORA f=902700000 bw=125 sz=24 dr=16 403D1001FC80010002369F9BAE4523B9D5540B99B2D002A2
2022-03-04 08:39:01.933 [S2E:DEBU] Dropped mirror frame freq=902.7MHz snr=-10.5 rssi=-99 (vs. freq=902.5MHz snr= -4.5 rssi=-85) - DR0 mic=-1576873806 (24 byes)
2022-03-04 08:39:01.937 [any:XDEB] RX mod=LORA f=902300000 bw=125 sz=24 dr=16 403D1001FC80010002369F9BAE4523B9D5540B99B2D002A2
2022-03-04 08:39:01.937 [S2E:DEBU] Dropped mirror frame freq=902.5MHz snr= -4.5 rssi=-85 (vs. freq=902.3MHz snr= 12.0 rssi=-34) - DR0 mic=-1576873806 (24 byes)
2022-03-04 08:39:01.938 [S2E:VERB] RX 902.3MHz DR0 SF10/BW125 snr=12.0 rssi=-34 xtime=0xB70000091E038C - updf mhdr=40 DevAddr=FC01103D FCtrl=80 FCnt=1 FOpts=[] 02369F9B..0B99 mic=-1576873806 (24 bytes)
2022-03-04 08:39:01.938 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":-67039171,"FCtrl":128,"FCnt":1,"FOpts":"","FPort":2,"FRMPayload":"369F9BAE4523B9D5540B99","MIC":-1576873806,"RefTime":0.000000,"DR":0,"Freq":902300000,"upinfo":{"rctx":0,"xtime":51509920891011980,"gpstime":0,"fts":-1,"rssi":-34,"snr":12,"rxtime":1646383141.938302}}
2022-03-04 08:39:03.887 [AIO:XDEB] [3|WS] < {"msgtype":"dnmsg","DevEui":"01-01-01-01-01-01-01-01","dC":0,"diid":64405,"pdu":"a03d1001fc8a010003000100700341ff00010277fb16cfa36fc706","priority":1,"RxDelay":1,"RX1DR":10,"RX1Freq":923300000,"xtime":51509920891011980,"rctx":0}
2022-03-04 08:39:03.888 [S2E:VERB] 101:101:101:101 diid=64405 [ant#0] - class A has no more alternate TX time

-------------------------------------------------------------------------------------------------------------------
`Currently waiting for the next uplink which would also consume the queued downlink`
-------------------------------------------------------------------------------------------------------------------

2022-03-04 08:39:16.669 [SYN:VERB] Time sync rejected: quality=138 threshold=137]
2022-03-04 08:39:25.070 [SYN:INFO] MCU/SX130X drift stats: min: -3.8ppm  q50: -4.3ppm  q80: -6.9ppm  max: -10.5ppm - threshold q90: -7.6ppm
2022-03-04 08:39:25.070 [SYN:INFO] Mean MCU drift vs SX130X#0: -5.0ppm
2022-03-04 08:39:33.472 [SYN:INFO] Time sync qualities: min=84 q90=137 max=140 (previous q90=137)
2022-03-04 08:40:06.026 [SYN:INFO] MCU/SX130X drift stats: min: +1.0ppm  q50: -4.3ppm  q80: -4.8ppm  max: -9.0ppm - threshold q90: -7.6ppm
2022-03-04 08:40:06.026 [SYN:INFO] Mean MCU drift vs SX130X#0: -4.4ppm
2022-03-04 08:40:14.427 [SYN:VERB] Time sync rejected: quality=139 threshold=137
2022-03-04 08:40:29.884 [AIO:XDEB] [3|WS] < PING ()
2022-03-04 08:40:29.884 [AIO:XDEB] [3|WS] > PONG
2022-03-04 08:40:35.430 [SYN:INFO] Time sync qualities: min=84 q90=137 max=160 (previous q90=137)
2022-03-04 08:40:35.430 [SYN:VERB] Time sync rejected: quality=160 threshold=137
lgw_receive:1155: FIFO content: 4 9c 3 5 18
lgw_receive:1174: [6 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 3 74 3 5 18
lgw_receive:1174: [7 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 2 ec 3 5 18
lgw_receive:1174: [4 17]
Note: LoRa packet
lgw_receive:1155: FIFO content: 1 c4 3 7 18
lgw_receive:1174: [5 17]
Note: LoRa packet
2022-03-04 08:40:38.930 [any:XDEB] RX mod=LORA f=903500000 bw=125 sz=24 dr=16 403D1001FC800200020CD8A9A8977A54E3D59BADD22D57B4
2022-03-04 08:40:38.931 [any:XDEB] RX mod=LORA f=903700000 bw=125 sz=24 dr=16 403D1001FC800200020CD8A9A8977A54E3D59BADD22D57B4
2022-03-04 08:40:38.931 [S2E:DEBU] Dropped mirror frame freq=903.5MHz snr= -6.0 rssi=-87 (vs. freq=903.7MHz snr=  8.2 rssi=-37) - DR0 mic=-1269355054 (24 byes)
2022-03-04 08:40:38.932 [any:XDEB] RX mod=LORA f=903100000 bw=125 sz=24 dr=16 403D1001FC800200020CD8A9A8977A54E3D59BADD22D57B4
2022-03-04 08:40:38.932 [S2E:DEBU] Dropped mirror frame freq=903.1MHz snr=-12.0 rssi=-103 (vs. freq=903.7MHz snr=  8.2 rssi=-37) - DR0 mic=-1269355054 (24 byes)
2022-03-04 08:40:38.933 [any:XDEB] RX mod=LORA f=903300000 bw=125 sz=24 dr=16 403D1001FC800A00020CD8A9A8977B54E3D59BADD22D57BD
2022-03-04 08:40:38.933 [any:XDEB] Dropped frame without CRC or with broken CRC
2022-03-04 08:40:38.933 [S2E:VERB] RX 903.7MHz DR0 SF10/BW125 snr=8.2 rssi=-37 xtime=0xB700000EE60114 - updf mhdr=40 DevAddr=FC01103D FCtrl=80 FCnt=2 FOpts=[] 020CD8A9..9BAD mic=-1269355054 (24 bytes)
2022-03-04 08:40:38.934 [AIO:XDEB] [3|WS] > {"msgtype":"updf","MHdr":64,"DevAddr":-67039171,"FCtrl":128,"FCnt":2,"FOpts":"","FPort":2,"FRMPayload":"0CD8A9A8977A54E3D59BAD","MIC":-1269355054,"RefTime":0.000000,"DR":0,"Freq":903700000,"upinfo":{"rctx":0,"xtime":51509920988004628,"gpstime":0,"fts":-1,"rssi":-37,"snr":8.25,"rxtime":1646383238.934113}}
2022-03-04 08:40:40.440 [AIO:XDEB] [3|WS] < {"msgtype":"dnmsg","DevEui":"01-01-01-01-01-01-01-01","dC":0,"diid":34093,"pdu":"603d1001fc8a020003000100700341ff00014e42e015","priority":1,"RxDelay":1,"RX1DR":10,"RX1Freq":927500000,"xtime":51509920988004628,"rctx":0}
2022-03-04 08:40:40.441 [S2E:VERB] 101:101:101:101 diid=34093 [ant#0] - class A has no more alternate TX time
2022-03-04 08:40:44.882 [SYN:VERB] Time sync rejected: quality=146 threshold=137
2022-03-04 08:40:53.283 [SYN:INFO] MCU/SX130X drift stats: min: +1.0ppm  q50: -4.3ppm  q80: -5.2ppm  max: -9.0ppm - threshold q90: -6.4ppm
2022-03-04 08:40:53.283 [SYN:INFO] Mean MCU drift vs SX130X#0: -4.4ppm

-------------------------------------------------------------------------------------------------------------------
`Downlink is now gone from the queue but no downlink was received by the end node`
-------------------------------------------------------------------------------------------------------------------

Do I need to make configuration changes to station and/or the other Chirpstack packages and is there anything I could do to fix this?

looks like the issue doesn’t exist when the gateway has a faster internet connection speed