High Beacon Collisions in Class B

Hey guys

I am trying Class B ussing RAK4722C like gateway and ST32WL55J (SX1206) like node class B for EU868.

I can use Class B communicaction correctly but I have a high Collision Beacons rate, always between 33%-35%.
I am only use 1 node in class B with 16sec of pingSlot for testing. But, final application would can use 50-150 nodes in class B (in 128 seg PingSlot). This rate is so high for implement a real application in class B.

How I can reduce this Beacon Collision rate? What I am doing wrong?

If you need mor info of config or whatever, please contact me.

Config:
I am ussing OS Image RAK v4.2.6 (last version of PacketForwarder)

chirpstack-gateway-bridge.toml ← No set. Default config
chirpstack-network-server.tom ← No set. Default config
chirpstack-application-server.toml ← No set. Default config

Device profile:

Class-B confirmed downlink timeout      8
Class-B ping-slot periodicity           every 16 second  
Class-B ping-slot data-rate             3
Class-B ping-slot frequency (Hz)        869525000

Global_config.json (default RAK config + Class B)

{
    "SX1301_conf": {
        "lorawan_public": true,
        "clksrc": 1,
        "antenna_gain": 0,
        "radio_0": {
            "enable": true,
            "type": "SX1257",
            "freq": 867500000,
            "rssi_offset": -166.0,
            "tx_enable": true,
            "tx_freq_min": 863000000,
            "tx_freq_max": 870000000
        },
        "radio_1": {
            "enable": true,
            "type": "SX1257",
            "freq": 868500000,
            "rssi_offset": -166.0,
            "tx_enable": false
        },
        "chan_multiSF_0": {
            "enable": true,
            "radio": 1,
            "if": -400000
        },
        "chan_multiSF_1": {
            "enable": true,
            "radio": 1,
            "if": -200000
        },
        "chan_multiSF_2": {
            "enable": true,
            "radio": 1,
            "if": 0
        },
        "chan_multiSF_3": {
            "enable": true,
            "radio": 0,
            "if": -400000
        },
        "chan_multiSF_4": {
            "enable": true,
            "radio": 0,
            "if": -200000
        },
        "chan_multiSF_5": {
            "enable": true,
            "radio": 0,
            "if": 0
        },
        "chan_multiSF_6": {
            "enable": true,
            "radio": 0,
            "if": 200000
        },
        "chan_multiSF_7": {
            "enable": true,
            "radio": 0,
            "if": 400000
        },
        "chan_Lora_std": {
            "enable": true,
            "radio": 1,
            "if": -200000,
            "bandwidth": 250000,
            "spread_factor": 7
        },
        "chan_FSK": {
            "enable": true,
            "radio": 1,
            "if": 300000,
            "bandwidth": 125000,
            "datarate": 50000
        },
        "tx_lut_0": {
            "pa_gain": 0,
            "mix_gain": 9,
            "rf_power": -6,
            "dig_gain": 0
        },
        "tx_lut_1": {
            "pa_gain": 0,
            "mix_gain": 12,
            "rf_power": -3,
            "dig_gain": 0
        },
        "tx_lut_2": {
            "pa_gain": 0,
            "mix_gain": 15,
            "rf_power": 0,
            "dig_gain": 0
        },
        "tx_lut_3": {
            "pa_gain": 1,
            "mix_gain": 8,
            "rf_power": 3,
            "dig_gain": 1
        },
        "tx_lut_4": {
            "pa_gain": 1,
            "mix_gain": 9,
            "rf_power": 6,
            "dig_gain": 0
        },
        "tx_lut_5": {
            "pa_gain": 1,
            "mix_gain": 11,
            "rf_power": 10,
            "dig_gain": 0
        },
        "tx_lut_6": {
            "pa_gain": 1,
            "mix_gain": 12,
            "rf_power": 11,
            "dig_gain": 1
        },
        "tx_lut_7": {
            "pa_gain": 1,
            "mix_gain": 12,
            "rf_power": 12,
            "dig_gain": 0
        },
        "tx_lut_8": {
            "pa_gain": 2,
            "mix_gain": 11,
            "rf_power": 13,
            "dig_gain": 1
        },
        "tx_lut_9": {
            "pa_gain": 2,
            "mix_gain": 11,
            "rf_power": 14,
            "dig_gain": 0
        },
        "tx_lut_10": {
            "pa_gain": 3,
            "mix_gain": 8,
            "rf_power": 16,
            "dig_gain": 1
        },
        "tx_lut_11": {
            "pa_gain": 3,
            "mix_gain": 8,
            "rf_power": 20,
            "dig_gain": 0
        },
        "tx_lut_12": {
                "desc": "TX gain table, index 12",
                "pa_gain": 3,
                "mix_gain": 12,
                "rf_power": 23,
                "dig_gain": 1
        },
        "tx_lut_13": {
                "desc": "TX gain table, index 13",
                "pa_gain": 3,
                "mix_gain": 13,
                "rf_power": 25,
                "dig_gain": 0
        },
        "tx_lut_14": {
                "desc": "TX gain table, index 14",
                "pa_gain": 3,
                "mix_gain": 13,
                "rf_power": 26,
                "dig_gain": 0
        },
        "tx_lut_15": {
                "desc": "TX gain table, index 15",
                "pa_gain": 3,
                "mix_gain": 13,
                "rf_power": 27,
                "dig_gain": 0
        }
    },
        "gateway_conf": {
                "gateway_ID": "DCA632FFFE0CA79B",
                /* change with default server address/ports, or overwrite in local_conf.json */
        "server_address": "127.0.0.1",
                "serv_port_up": 1700,
                "serv_port_down": 1700,
                /* adjust the following parameters for your network */
                "keepalive_interval": 10,
                "stat_interval": 30,
                "push_timeout_ms": 100,
                /* forward only valid packets */
                "forward_crc_valid": true,
                "forward_crc_error": false,
                "forward_crc_disabled": false,
                /* gps enable */
                "gps_tty_path": "/dev/i2c-1", /*default /dev/i2c-1*/
                "fake_gps": false, 
                "ref_latitude": 0.0,
                "ref_longitude": 0.0,
                "ref_altitude": 1,   
                "autoquit_threshold": 0,
                "beacon_period": 16, /*pingSlot 16s. 0 disable class B beacon */
                "beacon_freq_hz": 869525000,
                "beacon_freq_nb": 8,
                "beacon_freq_step": 0,
                "beacon_datarate": 9, 
                "beacon_bw_hz": 125000,
                "beacon_power": 27     
        }
}

LOG Start-up

*** Beacon Packet Forwarder for Lora Gateway ***
Version: 4.0.1
*** Lora concentrator HAL library version info ***
Version: 5.0.1;
***
INFO: Little endian host
INFO: found global configuration file global_conf.json, parsing it
INFO: global_conf.json does contain a JSON object named SX1301_conf, parsing SX1301 parameters
INFO: lorawan_public 1, clksrc 1
INFO: no configuration for LBT
INFO: antenna_gain 0 dBi
INFO: Configuring TX LUT with 16 indexes
INFO: radio 0 enabled (type SX1257), center frequency 867500000, RSSI offset -166.000000, tx enabled 1, tx_notch_freq 0
INFO: radio 1 enabled (type SX1257), center frequency 868500000, RSSI offset -166.000000, tx enabled 0, tx_notch_freq 0
INFO: Lora multi-SF channel 0>  radio 1, IF -400000 Hz, 125 kHz bw, SF 7 to 12
INFO: Lora multi-SF channel 1>  radio 1, IF -200000 Hz, 125 kHz bw, SF 7 to 12
INFO: Lora multi-SF channel 2>  radio 1, IF 0 Hz, 125 kHz bw, SF 7 to 12
INFO: Lora multi-SF channel 3>  radio 0, IF -400000 Hz, 125 kHz bw, SF 7 to 12
INFO: Lora multi-SF channel 4>  radio 0, IF -200000 Hz, 125 kHz bw, SF 7 to 12
INFO: Lora multi-SF channel 5>  radio 0, IF 0 Hz, 125 kHz bw, SF 7 to 12
INFO: Lora multi-SF channel 6>  radio 0, IF 200000 Hz, 125 kHz bw, SF 7 to 12
INFO: Lora multi-SF channel 7>  radio 0, IF 400000 Hz, 125 kHz bw, SF 7 to 12
INFO: Lora std channel> radio 1, IF -200000 Hz, 250000 Hz bw, SF 7
INFO: FSK channel> radio 1, IF 300000 Hz, 125000 Hz bw, 50000 bps datarate
INFO: global_conf.json does contain a JSON object named gateway_conf, parsing gateway parameters
INFO: gateway MAC address is configured to DCA632FFFE0CA79B
INFO: server hostname or IP address is configured to "127.0.0.1"
INFO: upstream port is configured to "1700"
INFO: downstream port is configured to "1700"
INFO: downstream keep-alive interval is configured to 10 seconds
INFO: statistics display interval is configured to 30 seconds
INFO: upstream PUSH_DATA time-out is configured to 100 ms
INFO: packets received with a valid CRC will be forwarded
INFO: packets received with a CRC error will NOT be forwarded
INFO: packets received with no CRC will NOT be forwarded
INFO: GPS serial port path is configured to "/dev/i2c-1"
INFO: Reference latitude is configured to 40.395072 deg
INFO: Reference longitude is configured to -3.794678 deg
INFO: Reference altitude is configured to 685 meters
INFO: fake GPS is disabled
INFO: Beaconing period is configured to 16 seconds
INFO: Beaconing signal will be emitted at 869525000 Hz
INFO: Beaconing channel number is set to 8
INFO: Beaconing channel frequency step is set to 0Hz
INFO: Beaconing datarate is set to SF9
INFO: Beaconing modulation bandwidth is set to 125000Hz
INFO: Beaconing TX power is set to 27dBm
INFO: Auto-quit after 0 non-acknowledged PULL_DATA
INFO: found local configuration file local_conf.json, parsing it
INFO: redefined parameters will overwrite global parameters
INFO: local_conf.json does not contain a JSON object named SX1301_conf
INFO: local_conf.json does contain a JSON object named gateway_conf, parsing gateway parameters
INFO: gateway MAC address is configured to DCA632FFFE0CA79B
INFO: packets received with a valid CRC will be forwarded
INFO: packets received with a CRC error will NOT be forwarded
INFO: packets received with no CRC will NOT be forwarded
This is i2c for GPS.
INFO: [main] TTY port /dev/i2c-1 open for GPS synchronization
INFO: [main] concentrator started, packet can now be received
INFO: [down] PULL_ACK received in 0 ms

INFO: Disabling GPS mode for concentrator's counter...
INFO: host/sx1301 time offset=(1622711383s:779241µs) - drift=-275093591µs
INFO: Enabling GPS mode for concentrator's counter.

WARNING: [gps] GPS out of sync, keeping previous time reference
WARNING: [gps] GPS out of sync, keeping previous time reference
INFO: [modify_os_time] local_time=1622711389, gps_time=1622711388
INFO: [modify_os_time] The difference between the system time(1622711389) and the GPS time(1622711388) is less than 10 seconds. Use the system time.
INFO: [down] PULL_ACK received in 0 ms
INFO: [down] PULL_RESP received  - token[245:191] :)

JSON down: {"txpk":{"imme":false,"rfch":0,"powe":14,"ant":0,"brd":0,"tmms":1306746641360,"freq":869.525,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":25,"data":"oD1VjQCFnwEDVv8AAWRaRAMDMJOimuFxmw=="}}
INFO: [down] a packet will be sent on timestamp value 39580809 (calculated from GPS time)
INFO: == used txlut index:9
INFO: [down] PULL_ACK received in 0 ms

##### 2021-06-03 09:10:16 GMT #####
### [UPSTREAM] ###
# RF packets received by concentrator: 0
# CRC_OK: 0.00%, CRC_FAIL: 0.00%, NO_CRC: 0.00%
# RF packets forwarded: 0 (0 bytes)
# PUSH_DATA datagrams sent: 0 (0 bytes)
# PUSH_DATA acknowledged: 0.00%
### [DOWNSTREAM] ###
# PULL_DATA sent: 3 (100.00% acknowledged)
# PULL_RESP(onse) datagrams received: 1 (212 bytes)
# RF packets sent to concentrator: 0 (25 bytes)
# TX errors: 0
# TX rejected (collision packet): 0.00% (req:1, rej:0)
# TX rejected (collision beacon): 0.00% (req:1, rej:0)
# TX rejected (too late): 0.00% (req:1, rej:0)
# TX rejected (too early): 0.00% (req:1, rej:0)
# BEACON queued: 0
# BEACON sent so far: 0
# BEACON rejected: 0
### [JIT] ###
# SX1301 time (PPS): 32220686
src/jitqueue.c:452:jit_print_queue(): INFO: [jit] queue contains 1 packets:
src/jitqueue.c:453:jit_print_queue(): INFO: [jit] queue contains 0 beacons:
src/jitqueue.c:459:jit_print_queue():  - node[0]: count_us=39580809 - type=1
### [GPS] ###
# Valid time reference (age: 0 sec)
# GPS coordinates: latitude 40.39540, longitude -3.79552, altitude 684 m
##### END #####

JSON up: {"stat":{"time":"2021-06-03 09:10:16 GMT","lati":40.39540,"long":-3.79552,"alti":684,"rxnb":0,"rxok":0,"rxfw":0,"ackr":0.0,"dwnb":1,"txnb":0}}
INFO: [up] PUSH_ACK received in 0 ms
INFO: [down] PULL_ACK received in 0 ms
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
INFO: [down] PULL_ACK received in 0 ms
INFO: [down] PULL_RESP received  - token[69:85] :)

JSON down: {"txpk":{"imme":false,"rfch":0,"powe":14,"ant":0,"brd":0,"tmms":1306746656720,"freq":869.525,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":25,"data":"oD1VjQCVoAEDVv8AAWR1t3PemSG0awVl9g=="}}
INFO: [down] a packet will be sent on timestamp value 54940809 (calculated from GPS time)
INFO: == used txlut index:9
INFO: [down] PULL_ACK received in 0 ms
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)

INFO: Disabling GPS mode for concentrator's counter...
INFO: host/sx1301 time offset=(1622711383s:779184µs) - drift=-57µs
INFO: Enabling GPS mode for concentrator's counter.


##### 2021-06-03 09:10:46 GMT #####
### [UPSTREAM] ###
# RF packets received by concentrator: 1
# CRC_OK: 0.00%, CRC_FAIL: 100.00%, NO_CRC: 0.00%
# RF packets forwarded: 0 (0 bytes)
# PUSH_DATA datagrams sent: 1 (154 bytes)
# PUSH_DATA acknowledged: 100.00%
### [DOWNSTREAM] ###
# PULL_DATA sent: 3 (100.00% acknowledged)
# PULL_RESP(onse) datagrams received: 1 (212 bytes)
# RF packets sent to concentrator: 2 (25 bytes)
# TX errors: 0
# TX rejected (collision packet): 0.00% (req:2, rej:0)
# TX rejected (collision beacon): 0.00% (req:2, rej:0)
# TX rejected (too late): 0.00% (req:2, rej:0)
# TX rejected (too early): 0.00% (req:2, rej:0)
# BEACON queued: 0
# BEACON sent so far: 0
# BEACON rejected: 0
### [JIT] ###
# SX1301 time (PPS): 62550641
src/jitqueue.c:448:jit_print_queue(): INFO: [jit] queue is empty
### [GPS] ###
# Valid time reference (age: 0 sec)
# GPS coordinates: latitude 40.39538, longitude -3.79553, altitude 682 m
##### END #####

JSON up: {"stat":{"time":"2021-06-03 09:10:46 GMT","lati":40.39538,"long":-3.79553,"alti":682,"rxnb":1,"rxok":0,"rxfw":0,"ackr":100.0,"dwnb":1,"txnb":2}}
INFO: [up] PUSH_ACK received in 0 ms
INFO: [down] PULL_ACK received in 0 ms
INFO: [down] PULL_RESP received  - token[192:125] :)

JSON down: {"txpk":{"imme":false,"rfch":0,"powe":14,"ant":0,"brd":0,"tmms":1306746672080,"freq":869.525,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":25,"data":"oD1VjQCFoQEDVv8AAWTSVDfg0ZJoayD+SA=="}}
INFO: [down] a packet will be sent on timestamp value 70300809 (calculated from GPS time)
INFO: == used txlut index:9
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
INFO: [down] PULL_ACK received in 0 ms
INFO: [down] PULL_RESP received  - token[44:14] :)

JSON down: {"txpk":{"imme":false,"rfch":0,"powe":14,"ant":0,"brd":0,"tmms":1306746687440,"freq":869.525,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":25,"data":"oD1VjQCFogEDVv8AAWQSAKJS7UPgQjuqgA=="}}
INFO: [down] a packet will be sent on timestamp value 85660810 (calculated from GPS time)
INFO: == used txlut index:9
INFO: [down] PULL_ACK received in 0 ms
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)

##### 2021-06-03 09:11:16 GMT #####
### [UPSTREAM] ###
# RF packets received by concentrator: 0
# CRC_OK: 0.00%, CRC_FAIL: 0.00%, NO_CRC: 0.00%
# RF packets forwarded: 0 (0 bytes)
# PUSH_DATA datagrams sent: 1 (156 bytes)
# PUSH_DATA acknowledged: 100.00%
### [DOWNSTREAM] ###
# PULL_DATA sent: 3 (100.00% acknowledged)
# PULL_RESP(onse) datagrams received: 2 (424 bytes)
# RF packets sent to concentrator: 2 (50 bytes)
# TX errors: 0
# TX rejected (collision packet): 0.00% (req:4, rej:0)
# TX rejected (collision beacon): 0.00% (req:4, rej:0)
# TX rejected (too late): 0.00% (req:4, rej:0)
# TX rejected (too early): 0.00% (req:4, rej:0)
# BEACON queued: 0
# BEACON sent so far: 0
# BEACON rejected: 0
### [JIT] ###
# SX1301 time (PPS): 92220692
src/jitqueue.c:448:jit_print_queue(): INFO: [jit] queue is empty
### [GPS] ###
# Valid time reference (age: 0 sec)
# GPS coordinates: latitude 40.39535, longitude -3.79554, altitude 681 m
##### END #####

JSON up: {"stat":{"time":"2021-06-03 09:11:16 GMT","lati":40.39535,"long":-3.79554,"alti":681,"rxnb":0,"rxok":0,"rxfw":0,"ackr":100.0,"dwnb":2,"txnb":2}}
INFO: [up] PUSH_ACK received in 0 ms
INFO: [down] PULL_ACK received in 0 ms
INFO: [down] PULL_RESP received  - token[60:35] :)

JSON down: {"txpk":{"imme":false,"rfch":0,"powe":14,"ant":0,"brd":0,"tmms":1306746702800,"freq":869.525,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":25,"data":"oD1VjQCFowEDVv8AAWTiVmFXm++OglhctA=="}}
INFO: [down] a packet will be sent on timestamp value 101020809 (calculated from GPS time)
INFO: == used txlut index:9
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
INFO: [down] PULL_ACK received in 0 ms
INFO: [down] PULL_RESP received  - token[13:210] :)

JSON down: {"txpk":{"imme":false,"rfch":0,"powe":14,"ant":0,"brd":0,"tmms":1306746718160,"freq":869.525,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":25,"data":"oD1VjQCFpAEDVv8AAWT1KUpYH68lblOcNg=="}}
INFO: [down] a packet will be sent on timestamp value 116380810 (calculated from GPS time)
INFO: == used txlut index:9
INFO: [down] PULL_ACK received in 0 ms
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)

INFO: Disabling GPS mode for concentrator's counter...
INFO: host/sx1301 time offset=(1622711383s:779127µs) - drift=-57µs
INFO: Enabling GPS mode for concentrator's counter.


##### 2021-06-03 09:11:46 GMT #####
### [UPSTREAM] ###
# RF packets received by concentrator: 0
# CRC_OK: 0.00%, CRC_FAIL: 0.00%, NO_CRC: 0.00%
# RF packets forwarded: 0 (0 bytes)
# PUSH_DATA datagrams sent: 1 (156 bytes)
# PUSH_DATA acknowledged: 100.00%
### [DOWNSTREAM] ###
# PULL_DATA sent: 3 (100.00% acknowledged)
# PULL_RESP(onse) datagrams received: 2 (424 bytes)
# RF packets sent to concentrator: 2 (50 bytes)
# TX errors: 0
# TX rejected (collision packet): 0.00% (req:6, rej:0)
# TX rejected (collision beacon): 0.00% (req:6, rej:0)
# TX rejected (too late): 0.00% (req:6, rej:0)
# TX rejected (too early): 0.00% (req:6, rej:0)
# BEACON queued: 0
# BEACON sent so far: 0
# BEACON rejected: 0
### [JIT] ###
# SX1301 time (PPS): 122551274
src/jitqueue.c:448:jit_print_queue(): INFO: [jit] queue is empty
### [GPS] ###
# Valid time reference (age: 0 sec)
# GPS coordinates: latitude 40.39531, longitude -3.79555, altitude 682 m
##### END #####

JSON up: {"stat":{"time":"2021-06-03 09:11:46 GMT","lati":40.39531,"long":-3.79555,"alti":682,"rxnb":0,"rxok":0,"rxfw":0,"ackr":100.0,"dwnb":2,"txnb":2}}
INFO: [up] PUSH_ACK received in 0 ms
INFO: [down] PULL_ACK received in 0 ms
INFO: [down] PULL_RESP received  - token[226:253] :)

JSON down: {"txpk":{"imme":false,"rfch":0,"powe":14,"ant":0,"brd":0,"tmms":1306746733520,"freq":869.525,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":25,"data":"oD1VjQCFpQEDVv8AAWQIPZg4mWnLV2ZMzQ=="}}
INFO: [down] a packet will be sent on timestamp value 131740810 (calculated from GPS time)
INFO: == used txlut index:9
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
INFO: Beacon queued (count_us=134220810, freq_hz=869525000, size=17):
   => 00 00 70 5F E3 4D 02 EC 00 65 73 39 34 4D FD 94 6C
INFO: Beacon queued (count_us=150220810, freq_hz=869525000, size=17):
   => 00 00 80 5F E3 4D 4F 73 00 65 73 39 34 4D FD 94 6C
INFO: Beacon queued (count_us=166220810, freq_hz=869525000, size=17):
   => 00 00 90 5F E3 4D E8 68 00 65 73 39 34 4D FD 94 6C
INFO: [down] PULL_ACK received in 0 ms
INFO: Beacon dequeued (count_us=134220810)
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
INFO: Beacon queued (count_us=182220810, freq_hz=869525000, size=17):
   => 00 00 A0 5F E3 4D 01 44 00 65 73 39 34 4D FD 94 6C
INFO: [down] PULL_RESP received  - token[44:209] :)

JSON down: {"txpk":{"imme":false,"rfch":0,"powe":14,"ant":0,"brd":0,"tmms":1306746748880,"freq":869.525,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":25,"data":"oD1VjQCFpgEDVv8AAWT8JX41Z0Ws9vBeog=="}}
INFO: [down] a packet will be sent on timestamp value 147100811 (calculated from GPS time)
INFO: == used txlut index:9
src/jitqueue.c:286:jit_enqueue(): ERROR: Packet (type=1) REJECTED, collision with beacon already programmed at 150220810 (147100811)
ERROR: Packet REJECTED (jit error=6)
INFO: [down] PULL_ACK received in 0 ms
INFO: Beacon dequeued (count_us=150220810)
INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
INFO: Beacon queued (count_us=198220811, freq_hz=869525000, size=17):
   => 00 00 B0 5F E3 4D A6 5F 00 65 73 39 34 4D FD 94 6C

##### 2021-06-03 09:12:16 GMT #####
### [UPSTREAM] ###
# RF packets received by concentrator: 0
# CRC_OK: 0.00%, CRC_FAIL: 0.00%, NO_CRC: 0.00%
# RF packets forwarded: 0 (0 bytes)
# PUSH_DATA datagrams sent: 1 (156 bytes)
# PUSH_DATA acknowledged: 100.00%
### [DOWNSTREAM] ###
# PULL_DATA sent: 3 (100.00% acknowledged)
# PULL_RESP(onse) datagrams received: 2 (424 bytes)
# RF packets sent to concentrator: 3 (50 bytes)
# TX errors: 0
# TX rejected (collision packet): 0.00% (req:8, rej:0)
# TX rejected (collision beacon): 12.50% (req:8, rej:1)
# TX rejected (too late): 0.00% (req:8, rej:0)
# TX rejected (too early): 0.00% (req:8, rej:0)
# BEACON queued: 5
# BEACON sent so far: 2
# BEACON rejected: 0
### [JIT] ###
# SX1301 time (PPS): 152220698
src/jitqueue.c:452:jit_print_queue(): INFO: [jit] queue contains 3 packets:
src/jitqueue.c:453:jit_print_queue(): INFO: [jit] queue contains 3 beacons:
src/jitqueue.c:459:jit_print_queue():  - node[0]: count_us=166220810 - type=3
src/jitqueue.c:459:jit_print_queue():  - node[1]: count_us=182220810 - type=3
src/jitqueue.c:459:jit_print_queue():  - node[2]: count_us=198220811 - type=3
### [GPS] ###
# Valid time reference (age: 0 sec)
# GPS coordinates: latitude 40.39530, longitude -3.79555, altitude 686 m
##### END #####

JSON up: {"stat":{"time":"2021-06-03 09:12:16 GMT","lati":40.39530,"long":-3.79555,"alti":686,"rxnb":0,"rxok":0,"rxfw":0,"ackr":100.0,"dwnb":2,"txnb":3}}
INFO: [up] PUSH_ACK received in 0 ms
^C
##### 2021-06-03 09:12:16 GMT #####
### [UPSTREAM] ###
# RF packets received by concentrator: 0
# CRC_OK: 0.00%, CRC_FAIL: 0.00%, NO_CRC: 0.00%
# RF packets forwarded: 0 (0 bytes)
# PUSH_DATA datagrams sent: 1 (156 bytes)
# PUSH_DATA acknowledged: 100.00%
### [DOWNSTREAM] ###
# PULL_DATA sent: 0 (0.00% acknowledged)
# PULL_RESP(onse) datagrams received: 0 (0 bytes)
# RF packets sent to concentrator: 0 (0 bytes)
# TX errors: 0
# TX rejected (collision packet): 0.00% (req:8, rej:0)
# TX rejected (collision beacon): 12.50% (req:8, rej:1)
# TX rejected (too late): 0.00% (req:8, rej:0)
# TX rejected (too early): 0.00% (req:8, rej:0)
# BEACON queued: 5
# BEACON sent so far: 2
# BEACON rejected: 0
### [JIT] ###
# SX1301 time (PPS): 152220698
src/jitqueue.c:452:jit_print_queue(): INFO: [jit] queue contains 3 packets:
src/jitqueue.c:453:jit_print_queue(): INFO: [jit] queue contains 3 beacons:
src/jitqueue.c:459:jit_print_queue():  - node[0]: count_us=166220810 - type=3
src/jitqueue.c:459:jit_print_queue():  - node[1]: count_us=182220810 - type=3
src/jitqueue.c:459:jit_print_queue():  - node[2]: count_us=198220811 - type=3
### [GPS] ###
# Valid time reference (age: 0 sec)
# GPS coordinates: latitude 40.39530, longitude -3.79555, altitude 686 m
##### END #####

LOG last lines (after 20h running and generating messages each 16seg for the node class B)
next message

Jun  3 10:49:29 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:29+02:00" level=info msg="finished unary call with code OK" ctx_id=ca9f5d55-943e-4214-8ec8-b5c2b3674bad grpc.code=OK grpc.method=HandleDownlinkACK grpc.service=as.ApplicationServerService grpc.start_time="2021-06-03T10:49:29+02:00" grpc.time_ms=3.456 peer.address="[::1]:44460" span.kind=server system=grpc
Jun  3 10:49:29 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:29+02:00" level=info msg="integration/logger: logging event" ctx_id=ca9f5d55-943e-4214-8ec8-b5c2b3674bad dev_eui=18f1a83486a13fae type=ack
Jun  3 10:49:29 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:29+02:00" level=info msg="integration/mqtt: publishing event" ctx_id=ca9f5d55-943e-4214-8ec8-b5c2b3674bad dev_eui=18f1a83486a13fae qos=0 retain=false topic=application/3/device/18f1a83486a13fae/ack
Jun  3 10:49:29 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:29+02:00" level=info msg="finished client unary call" ctx_id=675eac39-b034-41e2-9223-d36bf4f9cfc8 grpc.code=OK grpc.ctx_id=ca9f5d55-943e-4214-8ec8-b5c2b3674bad grpc.duration=5.279565ms grpc.method=HandleDownlinkACK grpc.service=as.ApplicationServerService span.kind=client system=grpc
Jun  3 10:49:29 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:29+02:00" level=info msg="device-queue item updated" ctx_id=675eac39-b034-41e2-9223-d36bf4f9cfc8 dev_eui=18f1a83486a13fae emit_at_time_since_gps_epoch=362984h49m54.42s f_cnt=338 is_pending=true timeout_after="2021-06-03 09:49:44.42 +0100 BST"
Jun  3 10:49:29 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:29+02:00" level=info msg="adr request added to mac-command queue" ctx_id=675eac39-b034-41e2-9223-d36bf4f9cfc8 dev_eui=18f1a83486a13fae dr=5 nb_trans=1 req_dr=5 req_nb_trans=1 req_tx_power_idx=6 tx_power=4
Jun  3 10:49:29 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:29+02:00" level=info msg="pending mac-command block set" cid=LinkADRReq commands=1 ctx_id=675eac39-b034-41e2-9223-d36bf4f9cfc8 dev_eui=18f1a83486a13fae
Jun  3 10:49:29 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:29+02:00" level=info msg="gateway/mqtt: publishing gateway command" command=down downlink_id=675eac39-b034-41e2-9223-d36bf4f9cfc8 gateway_id=dca632fffe0ca79b qos=0 topic=gateway/dca632fffe0ca79b/command/down
Jun  3 10:49:29 rak-gateway chirpstack-gateway-bridge[474]: time="2021-06-03T10:49:29+02:00" level=info msg="integration/mqtt: downlink frame received" downlink_id=675eac39-b034-41e2-9223-d36bf4f9cfc8 gateway_id=dca632fffe0ca79b
Jun  3 10:49:29 rak-gateway chirpstack-gateway-bridge[474]: time="2021-06-03T10:49:29+02:00" level=info msg="integration/mqtt: publishing event" downlink_id=675eac39-b034-41e2-9223-d36bf4f9cfc8 event=ack qos=0 topic=gateway/dca632fffe0ca79b/event/ack
Jun  3 10:49:29 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:29+02:00" level=info msg="device-session saved" ctx_id=675eac39-b034-41e2-9223-d36bf4f9cfc8 dev_addr=008d553d dev_eui=18f1a83486a13fae
Jun  3 10:49:29 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:29+02:00" level=info msg="storage: downlink-frame saved" ctx_id=675eac39-b034-41e2-9223-d36bf4f9cfc8 token=26462
Jun  3 10:49:29 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:29+02:00" level=info msg="backend/gateway: downlink tx acknowledgement received" downlink_id=675eac39-b034-41e2-9223-d36bf4f9cfc8 gateway_id=dca632fffe0ca79b
Jun  3 10:49:29 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:29+02:00" level=info msg="sent downlink meta-data to network-controller" ctx_id=675eac39-b034-41e2-9223-d36bf4f9cfc8
Jun  3 10:49:29 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:29+02:00" level=info msg="downlink tx acknowledged by gateway" dev_eui=18f1a83486a13fae
Jun  3 10:49:29 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:29+02:00" level=info msg="integration/logger: logging event" ctx_id=bc1478c1-dceb-43c1-b36f-6fc23942e7c7 dev_eui=18f1a83486a13fae type=txack
Jun  3 10:49:29 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:29+02:00" level=info msg="integration/mqtt: publishing event" ctx_id=bc1478c1-dceb-43c1-b36f-6fc23942e7c7 dev_eui=18f1a83486a13fae qos=0 retain=false topic=application/3/device/18f1a83486a13fae/event/txack
Jun  3 10:49:29 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:29+02:00" level=info msg="finished unary call with code OK" ctx_id=bc1478c1-dceb-43c1-b36f-6fc23942e7c7 grpc.code=OK grpc.method=HandleTxAck grpc.service=as.ApplicationServerService grpc.start_time="2021-06-03T10:49:29+02:00" grpc.time_ms=3.337 peer.address="[::1]:44460" span.kind=server system=grpc
Jun  3 10:49:29 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:29+02:00" level=info msg="finished client unary call" ctx_id=675eac39-b034-41e2-9223-d36bf4f9cfc8 grpc.code=OK grpc.ctx_id=bc1478c1-dceb-43c1-b36f-6fc23942e7c7 grpc.duration=4.939424ms grpc.method=HandleTxAck grpc.service=as.ApplicationServerService span.kind=client system=grpc
Jun  3 10:49:29 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:29+02:00" level=info msg="sent tx ack to application-server" ctx_id=675eac39-b034-41e2-9223-d36bf4f9cfc8
Jun  3 10:49:37 rak-gateway chirpstack-gateway-bridge[474]: time="2021-06-03T10:49:37+02:00" level=info msg="integration/mqtt: publishing event" event=stats qos=0 stats_id=7fe89dd2-e5fc-4d84-9ab0-4a67449cb0c2 topic=gateway/dca632fffe0ca79b/event/stats
Jun  3 10:49:37 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:37+02:00" level=info msg="gateway/mqtt: gateway stats packet received" gateway_id=dca632fffe0ca79b stats_id=7fe89dd2-e5fc-4d84-9ab0-4a67449cb0c2
Jun  3 10:49:37 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:37+02:00" level=info msg="gateway updated" ctx_id=7fe89dd2-e5fc-4d84-9ab0-4a67449cb0c2 gateway_id=dca632fffe0ca79b
Jun  3 10:49:37 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:37+02:00" level=info msg="gateway updated" ctx_id=66d071a8-ca06-4d1a-a31b-7aae2c139bcc id=dca632fffe0ca79b name=rak-gateway
Jun  3 10:49:37 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:37+02:00" level=info msg="metrics saved" aggregation="[MINUTE HOUR DAY MONTH]" ctx_id=66d071a8-ca06-4d1a-a31b-7aae2c139bcc name="gw:dca632fffe0ca79b"
Jun  3 10:49:37 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:37+02:00" level=info msg="finished unary call with code OK" ctx_id=66d071a8-ca06-4d1a-a31b-7aae2c139bcc grpc.code=OK grpc.method=HandleGatewayStats grpc.service=as.ApplicationServerService grpc.start_time="2021-06-03T10:49:37+02:00" grpc.time_ms=5.801 peer.address="[::1]:44460" span.kind=server system=grpc
Jun  3 10:49:37 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:37+02:00" level=info msg="finished client unary call" ctx_id=7fe89dd2-e5fc-4d84-9ab0-4a67449cb0c2 grpc.code=OK grpc.ctx_id=66d071a8-ca06-4d1a-a31b-7aae2c139bcc grpc.duration=6.645533ms grpc.method=HandleGatewayStats grpc.service=as.ApplicationServerService span.kind=client system=grpc
Jun  3 10:49:40 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:40+02:00" level=info msg="integration/mqtt: downlink event received" topic=application/3/device/18f1a83486a13fae/tx
Jun  3 10:49:40 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:40+02:00" level=info msg="finished unary call with code OK" ctx_id=4b45294c-ecbd-4e9a-a51b-4befeca6cd52 grpc.code=OK grpc.method=GetNextDownlinkFCntForDevEUI grpc.service=ns.NetworkServerService grpc.start_time="2021-06-03T10:49:40+02:00" grpc.time_ms=1.76 peer.address="[::1]:35100" span.kind=server system=grpc
Jun  3 10:49:40 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:40+02:00" level=info msg="finished client unary call" ctx_id="<nil>" grpc.code=OK grpc.ctx_id=4b45294c-ecbd-4e9a-a51b-4befeca6cd52 grpc.duration=2.807898ms grpc.method=GetNextDownlinkFCntForDevEUI grpc.service=ns.NetworkServerService span.kind=client system=grpc
Jun  3 10:49:40 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:40+02:00" level=info msg="get next ping-slot timestamp" after_beacon_start_time_ms=65780 beacon_start_time_s=10208948 dev_addr=008d553d ping_nb=8 ping_offset_ms=74 ping_slot_n=4
Jun  3 10:49:40 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:40+02:00" level=info msg="device-queue item created" ctx_id=39e89b2a-0548-495d-b6ee-85a3e9c934d8 dev_eui=18f1a83486a13fae f_cnt=339
Jun  3 10:49:40 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:40+02:00" level=info msg="finished unary call with code OK" ctx_id=39e89b2a-0548-495d-b6ee-85a3e9c934d8 grpc.code=OK grpc.method=CreateDeviceQueueItem grpc.service=ns.NetworkServerService grpc.start_time="2021-06-03T10:49:40+02:00" grpc.time_ms=8.304 peer.address="[::1]:35100" span.kind=server system=grpc
Jun  3 10:49:40 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:40+02:00" level=info msg="finished client unary call" ctx_id=120aa753-3aad-4e96-812e-d331e178bbce grpc.code=OK grpc.ctx_id=39e89b2a-0548-495d-b6ee-85a3e9c934d8 grpc.duration=9.38747ms grpc.method=CreateDeviceQueueItem grpc.service=ns.NetworkServerService span.kind=client system=grpc
Jun  3 10:49:40 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:40+02:00" level=info msg="downlink device-queue item handled" confirmed=true dev_eui=18f1a83486a13fae f_cnt=339
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=info msg="device-queue deleted" ctx_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 id=5144
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=warning msg="device-queue item discarded due to timeout" ctx_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 dev_eui=18f1a83486a13fae device_queue_item_fcnt=338
Jun  3 10:49:45 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:45+02:00" level=info msg="downlink device-queue item acknowledged" dev_eui=18f1a83486a13fae
Jun  3 10:49:45 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:45+02:00" level=info msg="finished unary call with code OK" ctx_id=308593cb-6e06-4793-accd-e7b91a0d450e grpc.code=OK grpc.method=HandleDownlinkACK grpc.service=as.ApplicationServerService grpc.start_time="2021-06-03T10:49:45+02:00" grpc.time_ms=3.487 peer.address="[::1]:44460" span.kind=server system=grpc
Jun  3 10:49:45 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:45+02:00" level=info msg="integration/logger: logging event" ctx_id=308593cb-6e06-4793-accd-e7b91a0d450e dev_eui=18f1a83486a13fae type=ack
Jun  3 10:49:45 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:45+02:00" level=info msg="integration/mqtt: publishing event" ctx_id=308593cb-6e06-4793-accd-e7b91a0d450e dev_eui=18f1a83486a13fae qos=0 retain=false topic=application/3/device/18f1a83486a13fae/ack
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=info msg="finished client unary call" ctx_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 grpc.code=OK grpc.ctx_id=308593cb-6e06-4793-accd-e7b91a0d450e grpc.duration=4.720744ms grpc.method=HandleDownlinkACK grpc.service=as.ApplicationServerService span.kind=client system=grpc
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=info msg="device-queue item updated" ctx_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 dev_eui=18f1a83486a13fae emit_at_time_since_gps_epoch=362984h50m9.78s f_cnt=339 is_pending=true timeout_after="2021-06-03 09:49:59.78 +0100 BST"
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=info msg="adr request added to mac-command queue" ctx_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 dev_eui=18f1a83486a13fae dr=5 nb_trans=1 req_dr=5 req_nb_trans=1 req_tx_power_idx=6 tx_power=4
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=info msg="pending mac-command block set" cid=LinkADRReq commands=1 ctx_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 dev_eui=18f1a83486a13fae
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=info msg="gateway/mqtt: publishing gateway command" command=down downlink_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 gateway_id=dca632fffe0ca79b qos=0 topic=gateway/dca632fffe0ca79b/command/down
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=info msg="device-session saved" ctx_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 dev_addr=008d553d dev_eui=18f1a83486a13fae
Jun  3 10:49:45 rak-gateway chirpstack-gateway-bridge[474]: time="2021-06-03T10:49:45+02:00" level=info msg="integration/mqtt: downlink frame received" downlink_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 gateway_id=dca632fffe0ca79b
Jun  3 10:49:45 rak-gateway chirpstack-gateway-bridge[474]: time="2021-06-03T10:49:45+02:00" level=info msg="integration/mqtt: publishing event" downlink_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 event=ack qos=0 topic=gateway/dca632fffe0ca79b/event/ack
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=info msg="storage: downlink-frame saved" ctx_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 token=8963
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=info msg="backend/gateway: downlink tx acknowledgement received" downlink_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 gateway_id=dca632fffe0ca79b
Jun  3 10:49:45 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:45+02:00" level=error msg=COLLISION_BEACON dev_eui=18f1a83486a13fae type=DATA_DOWN_GATEWAY
Jun  3 10:49:45 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:45+02:00" level=info msg="finished unary call with code OK" ctx_id=3fe0f665-7727-413b-afce-26d5dd56f6d4 grpc.code=OK grpc.method=HandleError grpc.service=as.ApplicationServerService grpc.start_time="2021-06-03T10:49:45+02:00" grpc.time_ms=3.197 peer.address="[::1]:44460" span.kind=server system=grpc
Jun  3 10:49:45 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:45+02:00" level=info msg="integration/mqtt: publishing event" ctx_id=3fe0f665-7727-413b-afce-26d5dd56f6d4 dev_eui=18f1a83486a13fae qos=0 retain=false topic=application/3/device/18f1a83486a13fae/error
Jun  3 10:49:45 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:45+02:00" level=info msg="integration/logger: logging event" ctx_id=3fe0f665-7727-413b-afce-26d5dd56f6d4 dev_eui=18f1a83486a13fae type=error
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=info msg="finished client unary call" ctx_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2 grpc.code=OK grpc.ctx_id=3fe0f665-7727-413b-afce-26d5dd56f6d4 grpc.duration=4.118017ms grpc.method=HandleError grpc.service=as.ApplicationServerService span.kind=client system=grpc
Jun  3 10:49:45 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:45+02:00" level=info msg="sent error to application-server" ctx_id=2303ee9b-7386-4f1a-97f9-f37c0e5a5ec2
Jun  3 10:49:56 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:56+02:00" level=info msg="integration/mqtt: downlink event received" topic=application/3/device/18f1a83486a13fae/tx
Jun  3 10:49:56 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:56+02:00" level=info msg="finished unary call with code OK" ctx_id=019a02ab-9b2e-4457-92c7-017f12e53e1f grpc.code=OK grpc.method=GetNextDownlinkFCntForDevEUI grpc.service=ns.NetworkServerService grpc.start_time="2021-06-03T10:49:56+02:00" grpc.time_ms=2.042 peer.address="[::1]:35100" span.kind=server system=grpc
Jun  3 10:49:56 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:56+02:00" level=info msg="finished client unary call" ctx_id="<nil>" grpc.code=OK grpc.ctx_id=019a02ab-9b2e-4457-92c7-017f12e53e1f grpc.duration=3.027727ms grpc.method=GetNextDownlinkFCntForDevEUI grpc.service=ns.NetworkServerService span.kind=client system=grpc
Jun  3 10:49:56 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:56+02:00" level=info msg="get next ping-slot timestamp" after_beacon_start_time_ms=81140 beacon_start_time_s=10208948 dev_addr=008d553d ping_nb=8 ping_offset_ms=74 ping_slot_n=5
Jun  3 10:49:56 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:56+02:00" level=info msg="device-queue item created" ctx_id=33030f5a-c4e7-40a1-b6f5-ea4c575cc5af dev_eui=18f1a83486a13fae f_cnt=340
Jun  3 10:49:56 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:49:56+02:00" level=info msg="finished unary call with code OK" ctx_id=33030f5a-c4e7-40a1-b6f5-ea4c575cc5af grpc.code=OK grpc.method=CreateDeviceQueueItem grpc.service=ns.NetworkServerService grpc.start_time="2021-06-03T10:49:56+02:00" grpc.time_ms=9.088 peer.address="[::1]:35100" span.kind=server system=grpc
Jun  3 10:49:56 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:56+02:00" level=info msg="finished client unary call" ctx_id=27bd2892-65c1-4ef9-a48d-8973ee7b0cd9 grpc.code=OK grpc.ctx_id=33030f5a-c4e7-40a1-b6f5-ea4c575cc5af grpc.duration=10.101898ms grpc.method=CreateDeviceQueueItem grpc.service=ns.NetworkServerService span.kind=client system=grpc
Jun  3 10:49:56 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:49:56+02:00" level=info msg="downlink device-queue item handled" confirmed=true dev_eui=18f1a83486a13fae f_cnt=340
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=info msg="device-queue deleted" ctx_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb id=5145
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=warning msg="device-queue item discarded due to timeout" ctx_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb dev_eui=18f1a83486a13fae device_queue_item_fcnt=339
Jun  3 10:50:00 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:50:00+02:00" level=info msg="downlink device-queue item acknowledged" dev_eui=18f1a83486a13fae
Jun  3 10:50:00 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:50:00+02:00" level=info msg="finished unary call with code OK" ctx_id=954c1291-1f3c-42a6-ad90-780c82e7c1ed grpc.code=OK grpc.method=HandleDownlinkACK grpc.service=as.ApplicationServerService grpc.start_time="2021-06-03T10:50:00+02:00" grpc.time_ms=3.44 peer.address="[::1]:44460" span.kind=server system=grpc
Jun  3 10:50:00 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:50:00+02:00" level=info msg="integration/mqtt: publishing event" ctx_id=954c1291-1f3c-42a6-ad90-780c82e7c1ed dev_eui=18f1a83486a13fae qos=0 retain=false topic=application/3/device/18f1a83486a13fae/ack
Jun  3 10:50:00 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:50:00+02:00" level=info msg="integration/logger: logging event" ctx_id=954c1291-1f3c-42a6-ad90-780c82e7c1ed dev_eui=18f1a83486a13fae type=ack
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=info msg="finished client unary call" ctx_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb grpc.code=OK grpc.ctx_id=954c1291-1f3c-42a6-ad90-780c82e7c1ed grpc.duration=5.582446ms grpc.method=HandleDownlinkACK grpc.service=as.ApplicationServerService span.kind=client system=grpc
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=info msg="device-queue item updated" ctx_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb dev_eui=18f1a83486a13fae emit_at_time_since_gps_epoch=362984h50m25.14s f_cnt=340 is_pending=true timeout_after="2021-06-03 09:50:15.14 +0100 BST"
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=info msg="adr request added to mac-command queue" ctx_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb dev_eui=18f1a83486a13fae dr=5 nb_trans=1 req_dr=5 req_nb_trans=1 req_tx_power_idx=6 tx_power=4
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=info msg="pending mac-command block set" cid=LinkADRReq commands=1 ctx_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb dev_eui=18f1a83486a13fae
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=info msg="gateway/mqtt: publishing gateway command" command=down downlink_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb gateway_id=dca632fffe0ca79b qos=0 topic=gateway/dca632fffe0ca79b/command/down
Jun  3 10:50:00 rak-gateway chirpstack-gateway-bridge[474]: time="2021-06-03T10:50:00+02:00" level=info msg="integration/mqtt: downlink frame received" downlink_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb gateway_id=dca632fffe0ca79b
Jun  3 10:50:00 rak-gateway chirpstack-gateway-bridge[474]: time="2021-06-03T10:50:00+02:00" level=info msg="integration/mqtt: publishing event" downlink_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb event=ack qos=0 topic=gateway/dca632fffe0ca79b/event/ack
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: [down] PULL_ACK received in 0 ms
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: Beacon dequeued (count_us=409510579)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: Beacon queued (count_us=457510579, freq_hz=869525000, size=17):
Jun  3 10:50:00 rak-gateway ttn-gateway[402]:    => 00 00 50 5A E3 4D BC 30 00 65 73 39 34 4D FD 94 6C
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: [down] PULL_ACK received in 0 ms
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: [down] PULL_RESP received  - token[94:103] :)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: JSON down: {"txpk":{"imme":false,"rfch":0,"powe":14,"ant":0,"brd":0,"tmms":1306745394420,"freq":869.525,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":25,"data":"oD1VjQCFUgEDVv8AAWSjrixcM06iJkMblg=="}}
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: [down] a packet will be sent on timestamp value 427930579 (calculated from GPS time)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: == used txlut index:9
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: Beacon dequeued (count_us=425510578)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: Beacon queued (count_us=473510580, freq_hz=869525000, size=17):
Jun  3 10:50:00 rak-gateway ttn-gateway[402]:    => 00 00 60 5A E3 4D 55 1C 00 65 73 39 34 4D FD 94 6C
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: Disabling GPS mode for concentrator's counter...
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: host/sx1301 time offset=(1622709748s:490645µs) - drift=40µs
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: Enabling GPS mode for concentrator's counter.
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: ##### 2021-06-03 08:49:37 GMT #####
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: ### [UPSTREAM] ###
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # RF packets received by concentrator: 0
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # CRC_OK: 0.00%, CRC_FAIL: 0.00%, NO_CRC: 0.00%
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # RF packets forwarded: 0 (0 bytes)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # PUSH_DATA datagrams sent: 1 (156 bytes)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # PUSH_DATA acknowledged: 100.00%
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: ### [DOWNSTREAM] ###
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # PULL_DATA sent: 2 (100.00% acknowledged)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # PULL_RESP(onse) datagrams received: 2 (424 bytes)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # RF packets sent to concentrator: 4 (50 bytes)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # TX errors: 0
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # TX rejected (collision packet): 0.02% (req:4565, rej:1)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # TX rejected (collision beacon): 34.98% (req:4565, rej:1597)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # TX rejected (too late): 0.00% (req:4565, rej:0)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # TX rejected (too early): 0.26% (req:4565, rej:12)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # BEACON queued: 4585
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=info msg="device-session saved" ctx_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb dev_addr=008d553d dev_eui=18f1a83486a13fae
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=info msg="storage: downlink-frame saved" ctx_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb token=40748
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=info msg="backend/gateway: downlink tx acknowledgement received" downlink_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb gateway_id=dca632fffe0ca79b
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # BEACON sent so far: 4582
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # BEACON rejected: 0
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: ### [JIT] ###
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # SX1301 time (PPS): 428510367
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: src/jitqueue.c:452:jit_print_queue(): INFO: [jit] queue contains 3 packets:
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: src/jitqueue.c:453:jit_print_queue(): INFO: [jit] queue contains 3 beacons:
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: src/jitqueue.c:459:jit_print_queue():  - node[0]: count_us=441510579 - type=3
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: src/jitqueue.c:459:jit_print_queue():  - node[1]: count_us=457510579 - type=3
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: src/jitqueue.c:459:jit_print_queue():  - node[2]: count_us=473510580 - type=3
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: ### [GPS] ###
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # Valid time reference (age: 0 sec)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: # GPS coordinates: latitude 40.39526, longitude -3.79555, altitude 677 m
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: ##### END #####
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: JSON up: {"stat":{"time":"2021-06-03 08:49:37 GMT","lati":40.39526,"long":-3.79555,"alti":677,"rxnb":0,"rxok":0,"rxfw":0,"ackr":100.0,"dwnb":2,"txnb":4}}
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: [up] PUSH_ACK received in 0 ms
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: [down] PULL_ACK received in 0 ms
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: [down] PULL_RESP received  - token[3:35] :)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: JSON down: {"txpk":{"imme":false,"rfch":0,"powe":14,"ant":0,"brd":0,"tmms":1306745409780,"freq":869.525,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":25,"data":"oD1VjQCFUwEDVv8AAWS4p1e9RHImTDLlmQ=="}}
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: [down] a packet will be sent on timestamp value 443290580 (calculated from GPS time)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: == used txlut index:9
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: src/jitqueue.c:286:jit_enqueue(): ERROR: Packet (type=1) REJECTED, collision with beacon already programmed at 441510579 (443290580)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: ERROR: Packet REJECTED (jit error=6)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: [down] PULL_ACK received in 0 ms
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: Beacon dequeued (count_us=441510579)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: tx_start_delay=1495 (1495.500000) - (1497, bw_delay=1.500000, notch_delay=0.000000)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: Beacon queued (count_us=489510580, freq_hz=869525000, size=17):
Jun  3 10:50:00 rak-gateway ttn-gateway[402]:    => 00 00 70 5A E3 4D F2 07 00 65 73 39 34 4D FD 94 6C
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: [down] PULL_ACK received in 0 ms
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: INFO: [down] PULL_RESP received  - token[44:159] :)
Jun  3 10:50:00 rak-gateway ttn-gateway[402]: JSON down: {"txpk":{"imme":false,"rfch":0,"powe":14,"ant":0,"brd":0,"tmms":1306745425140,"freq":869.525,"modu":"LORA","datr":"SF12BW125","codr":"4/5","ipol":true,"size":25,"data":"oD1VjQCFVAEDVv8AAWSlKIR71+GyTWIoYA=="}}
Jun  3 10:50:00 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:50:00+02:00" level=error msg=COLLISION_BEACON dev_eui=18f1a83486a13fae type=DATA_DOWN_GATEWAY
Jun  3 10:50:00 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:50:00+02:00" level=info msg="finished unary call with code OK" ctx_id=bfd0d139-0abb-4ae4-8344-31bad9e562da grpc.code=OK grpc.method=HandleError grpc.service=as.ApplicationServerService grpc.start_time="2021-06-03T10:50:00+02:00" grpc.time_ms=22.621 peer.address="[::1]:44460" span.kind=server system=grpc
Jun  3 10:50:00 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:50:00+02:00" level=info msg="integration/logger: logging event" ctx_id=bfd0d139-0abb-4ae4-8344-31bad9e562da dev_eui=18f1a83486a13fae type=error
Jun  3 10:50:00 rak-gateway chirpstack-application-server[472]: time="2021-06-03T10:50:00+02:00" level=info msg="integration/mqtt: publishing event" ctx_id=bfd0d139-0abb-4ae4-8344-31bad9e562da dev_eui=18f1a83486a13fae qos=0 retain=false topic=application/3/device/18f1a83486a13fae/error
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=info msg="finished client unary call" ctx_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb grpc.code=OK grpc.ctx_id=bfd0d139-0abb-4ae4-8344-31bad9e562da grpc.duration=23.82112ms grpc.method=HandleError grpc.service=as.ApplicationServerService span.kind=client system=grpc
Jun  3 10:50:00 rak-gateway chirpstack-network-server[476]: time="2021-06-03T10:50:00+02:00" level=info msg="sent error to application-server" ctx_id=9f2c9831-2a0f-4ccd-ba8d-f19577f094bb

Hi Roberto.
What exactly do you call beacon collision rate ?
Is it some downlink transmission requests that the gateway cannot achieve because they fall at the same time the scheduled class B beacon transmissions ?

Yes. I refered to these parameters in logs.

TX rejected (collision packet): 0.02% (req:4565, rej:1)
TX rejected (collision beacon): 34.98% (req:4565, rej:1597)

I detect that beacon period configuration isn’t right.

            "autoquit_threshold": 0,
            "beacon_period": **128**, /*this was wrong*/ /*pingSlot 128s. 0 disable class B beacon */
            "beacon_freq_hz": 869525000,

When beacon period is 128s (correct) instead 16s, TX rejected (collision beacon) is reduce until 2-3%. and TX rejected (collision packet) rise to 2%.

Is it possible reduce a little more rejected packet/beacon collisions?

which library have you used?

Hi.

Gateway: RAK7244. PacketForwarder & ChirpStack Gateway Bridge 3.13.2
Node: STM32WL. Code based in AT_Slave (ST packages STM32WL series 1.1.0 and 1.2.0)

I also encountered this problem, have you solved it?