Join is not completed on Docker alpine 3.17

[rbsouzarn]

Hello. First, thank you very much for your simulator. I have been testing the simulator with the latest stable release, version 1.0.2, without compiling the code, together with ChirpStack. Running the binary directly on Linux Ubuntu 20.04.6 LTS everything works perfectly. When I run the binary in docker, alpine 3.17 image, the join is not completed. The CS receives the joinRequest, processes it and responds with the downlink that is never received by the simulator.

Below is the simulator log:

_[ Oct 16 12:36:09 ] [YES]: Setup gateways OK! [ Oct 16 12:36:09 ] [YES]: Setup devices OK! [ Oct 16 12:36:09 ] [YES]: SETUP OK!

[ Oct 16 12:36:09 ] GW[V-GATEWAY]: Turn ON [ Oct 16 12:36:09 ] GW[V-GATEWAY]: PULL DATA send [ Oct 16 12:36:09 ] DEV[V-DEVICE] |Activation| {A}: Turn ON [ Oct 16 12:36:09 ] DEV[V-DEVICE] |Activation| {A}: JOIN REQUEST sent [ Oct 16 12:36:09 ] DEV[V-DEVICE] |Activation| {A}: Open RXs [ Oct 16 12:36:09 ] GW[V-GATEWAY]: PUSH DATA send [ Oct 16 12:36:21 ] DEV[V-DEVICE] |Activation| {A}: No downlink received [ Oct 16 12:36:21 ] DEV[V-DEVICE] |Activation| {A}: Unjoined_

Below is the CS log:

_2023-10-16T12:36:09.703739Z INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_config_id="eu868" topic="eu868/gateway/9e5accc5497961e6/event/up" qos=0 json=false 2023-10-16T12:36:09.704823Z INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_config_id="eu868" topic="eu868/gateway/9e5accc5497961e6/event/stats" qos=0 json=false 2023-10-16T12:36:09.707644Z INFO stats{gateway_id=9e5accc5497961e6}: chirpstack::storage::gateway: Gateway state updated gateway_id=9e5accc5497961e6 2023-10-16T12:36:09.708409Z INFO stats{gateway_id=9e5accc5497961e6}: chirpstack::storage::metrics: Metrics saved name=gw:9e5accc5497961e6 aggregation=HOUR 2023-10-16T12:36:09.709091Z INFO stats{gateway_id=9e5accc5497961e6}: chirpstack::storage::metrics: Metrics saved name=gw:9e5accc5497961e6 aggregation=DAY 2023-10-16T12:36:09.709650Z INFO stats{gateway_id=9e5accc5497961e6}: chirpstack::storage::metrics: Metrics saved name=gw:9e5accc5497961e6 aggregation=MONTH 2023-10-16T12:36:09.907349Z INFO up{deduplication_id=e23c98db-8e3d-412d-ac4f-9c71cde75566}: chirpstack::uplink: Uplink received m_type="JoinRequest" 2023-10-16T12:36:09.920307Z INFO up{deduplication_id=e23c98db-8e3d-412d-ac4f-9c71cde75566}:join_request: chirpstack::storage::device_keys: Device-nonce validated and stored dev_eui=282f5b5d8de3 27ef dev_nonce=5275 2023-10-16t12: 36: 09.921519Z info up {deduplication_id = E23C98DB-8E3D-412D-AC4F-9C71CDE75566}: join_request: chirpstack :: storage :: device_keys: device-keys updated dev 5d8de327ef 2023-10-16T12:36:09.922599Z INFO up{deduplication_id=e23c98db-8e3d-412d-ac4f-9c71cde75566}:join_request: chirpstack::storage::device_session: Device-session saved dev_eui=282f5b5d8de32 7ef dev_addr=01396f01 2023-10-16T12:36:09.923456Z INFO up{deduplication_id=e23c98db-8e3d-412d-ac4f-9c71cde75566}:join_request: chirpstack::storage::device_queue: Device queue flushed dev_eui=282f5b5d8de327ef count=0 2023-10-16T12:36:09.925010Z INFO up{deduplication_id=e23c98db-8e3d-412d-ac4f-9c71cde75566}:join_request: chirpstack::storage::device: Enabled class updated dev_eui=282f5b5d8de327ef enabled_class =A 2023-10-16T12:36:09.925260Z INFO up{deduplication_id=e23c98db-8e3d-412d-ac4f-9c71cde75566}:join_request: chirpstack::gateway::backend::mqtt: Sending downlink frame gateway_id=9e5accc5497961e6 topic=eu868/gateway /9e5accc5497961e6/command/down json=false 2023-10-16T12:36:09.926200Z INFO up{deduplication_id=e23c98db-8e3d-412d-ac4f-9c71cde75566}:join_request: chirpstack::storage::downlink_frame: Downlink-frame saved downlinkid=2178120891 2023-10-16T12:36:09.929594Z INFO chirpstack::integration::mqtt: Publishing event topic=application/17ceb164-9b65-44a1-9efd-293d99cd0ff3/device/282f5b5d8de327ef/event/join

[rbsouzarn]

In addition, when running directly on Ubuntu, the CS log is as follows:

_2023-10-16T12:26:47.537302Z INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_config_id="eu868" topic="eu868/gateway/9e5accc5497961e6/event/stats" qos=0 json=false 2023-10-16T12:26:47.538030Z INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_config_id="eu868" topic="eu868/gateway/9e5accc5497961e6/event/up" qos=0 json=false 2023-10-16T12:26:47.539055Z INFO stats{gateway_id=9e5accc5497961e6}: chirpstack::storage::gateway: Gateway state updated gateway_id=9e5accc5497961e6 2023-10-16T12:26:47.539591Z INFO stats{gateway_id=9e5accc5497961e6}: chirpstack::storage::metrics: Metrics saved name=gw:9e5accc5497961e6 aggregation=HOUR 2023-10-16T12:26:47.540103Z INFO stats{gateway_id=9e5accc5497961e6}: chirpstack::storage::metrics: Metrics saved name=gw:9e5accc5497961e6 aggregation=DAY 2023-10-16T12:26:47.540546Z INFO stats{gateway_id=9e5accc5497961e6}: chirpstack::storage::metrics: Metrics saved name=gw:9e5accc5497961e6 aggregation=MONTH 2023-10-16T12:26:47.740570Z INFO up{deduplication_id=79ea158e-4ccb-4b11-acf2-ac549ae40d48}: chirpstack::uplink: Uplink received m_type="JoinRequest" 2023-10-16T12:26:47.748471Z INFO up{deduplication_id=79ea158e-4ccb-4b11-acf2-ac549ae40d48}:join_request: chirpstack::storage::device_keys: Device-nonce validated and stored dev_eui=282f5b5d8de327 ef dev_nonce=10908 2023-10-16T12:26:47.749458Z INFO up{deduplication_id=79ea158e-4ccb-4b11-acf2-ac549ae40d48}:join_request: chirpstack::storage::device_keys: Device-keys updated dev_eui=282f5b5d8de327ef 2023-10-16T12:26:47.750278Z INFO up{deduplication_id=79ea158e-4ccb-4b11-acf2-ac549ae40d48}:join_request: chirpstack::storage::device_session: Device-session saved dev_eui=282f5b5d8de327ef dev_addr=01024055 2023-10-16T12:26:47.750911Z INFO up{deduplication_id=79ea158e-4ccb-4b11-acf2-ac549ae40d48}:join_request: chirpstack::storage::device_queue: Device queue flushed dev_eui=282f5b5d8de327ef count= 0 2023-10-16t12: 26: 47.752034z info up {deduplication_id = 79EA158E-4CCB-41111-AC549AE40D48}: join_request: chirpstack :: Storage :: Device: ENABLED Class updated dev EF Enabled_Class = A 2023-10-16T12:26:47.752138Z INFO up{deduplication_id=79ea158e-4ccb-4b11-acf2-ac549ae40d48}:join_request: chirpstack::gateway::backend::mqtt: Sending downlink frame gateway_id=9e5accc5497961e6 topic= eu868/gateway /9e5accc5497961e6/command/down json=false 2023-10-16T12:26:47.752972Z INFO up{deduplication_id=79ea158e-4ccb-4b11-acf2-ac549ae40d48}:join_request: chirpstack::storage::downlink_frame: Downlink-frame saved downlink_id=815189070 2023-10-16T12:26:47.753777Z INFO chirpstack::integration::mqtt: Publishing event topic=application/17ceb164-9b65-44a1-9efd-293d99cd0ff3/device/282f5b5d8de327ef/event/join 2023-10-16T12:26:49.539371Z INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_config_id="eu868" topic="eu868/gateway/9e5accc5497961e6/event/ack" qos=0 json=false 2023-10-16T12:26:49.539797Z INFO chirpstack::downlink::tx_ack: Log downlink-frame for gateway gateway_id=9e5accc5497961e6 2023-10-16T12:26:49.540419Z INFO chirpstack::downlink::tx_ack: Log downlink-frame for device deviceeui=282f5b5d8de327ef

Note the bold line receiving the ACK, which does not happen when running in docker.

[fedyfausto]

did you try the release version tagged?

[augusthjerrild]

Same error here. I tried the release version. Any idea why? The error is in docker for me aswell.

[fedyfausto]

ok, so the problem could be a network problem? Did you try to use the host mode of the network?

[augusthjerrild]

I'm currently sitting in an office, using the same network as my coworkers, and one of my coworkers has no problem making it work.. So should not be the network I guess? It's worth mentioning, that the gateway shows data in ChirpStack, so there is some kind of connection. The device is just always unjoined..

I haven't tried the host mode of the network. Might be a stupid question, but how can I use that?

[damien-r]

Good Evening, We faced the same problem and after investigations, we have a strong impression that it comes from the network. As packet forwarder works in UDP, the answer from ChirpStack is addressed to a random UDP port. Because LWN Simulator is in another docker Stack and this random UDP port is not open, the answer is lost. I confirmed this by running a Wireshark analysis, where I saw this random port as destination to the join response sent by ChirpStack. I also tried to put both ChirpStack and LWN-Simulator in the same stack so they can discuss freely and the joins were accepted.

[rbsouzarn]

Good Evening, We faced the same problem and after investigations, we have a strong impression that it comes from the network. As packet forwarder works in UDP, the answer from ChirpStack is addressed to a random UDP port. Because LWN Simulator is in another docker Stack and this random UDP port is not open, the answer is lost. I confirmed this by running a Wireshark analysis, where I saw this random port as destination to the join response sent by ChirpStack. I also tried to put both ChirpStack and LWN-Simulator in the same stack so they can discuss freely and the joins were accepted.

Great job. I can see a light here.

[lakshmanachimata]

Hi damien,

Good catch there , Can you please share the steps to run ChirpStack and LWN-Simulator in same docker stack. I am blocked due to this.

[damien-r]

Hi @lakshmanachimata

I propose 2 solutions: 1) Same stack Put the content of https://github.com/chirpstack/chirpstack-docker and https://github.com/UniCT-ARSLab/LWN-Simulator in the same directory (especially the config files and LWN source code) Combine both docker-compose.yml files Run it So they are run in the same stack and can communicate freely

2) Open network of LWN-Simulator stack See https://docs.docker.com/network/drivers/#:~:text=Remove%20network%20isolation Add network_mode: "host" in https://github.com/UniCT-ARSLab/LWN-Simulator/blob/main/docker-compose.yml This opens all network to the LWN Simulator and allows UDP answers to enter it freely, but removes isolation of the simulator.

[lakshmanachimata]

Hi Damien, Thanks for the details, I have already tried first approach and faced few issues, I ll try both of them with further.

[lakshmanachimata]

Hi Damien (@damien-r ) , I ran the chirpstack-docker in host network mode , I see 1700 udp port is opened. But still seeing the same issue

I see response to join request of the device is coming to mqtt as well (refer below screen shot),

I am not able to understand why device communication is not happening back from gateway

chirpstack_1 | 2023-11-18T14:07:01.524822Z INFO chirpstack::downlink::tx_ack: Log downlink-frame for device device_eui=de4bba5236336d91

I see downlink frame of a ackowledgement from gateway also came, but device is not able to read the message back . I am blocked here, what are the ways to go further

[damien-r]

Hi @lakshmanachimata

I ran the chirpstack-docker in host network mode , I see 1700 udp port is opened. But still seeing the same issue

The problem might be there. You should set the LWN Simulator docker in host network_mode to allow UDP replies from ChirpStack to be addressed to the Simulator container.

[lakshmanachimata]

Hi @damien-r I ran LWN simulator also in same docker as Chirpstack server still I see downlink comm is not working. (seems some config gap might be there, I need to re-verify all the details). I ll keep updating the post here, If I am able to make handshake success

[lakshmanachimata]

I have added logs on simulator code and tested myself with lot of config changes, no luck on this. gw is able to receive data and ack is being sent, but device not able to get the join accept call sent by chirpstack server ( server -> mqtt is done). mqtt published the join ack with below payload

device join ack

{ "deduplicationId": "91b14a24-d272-43ce-a397-273ae7f4b654", "time": "2023-11-23T13:31:34.413+00:00", "deviceInfo": { "tenantId": "2bcc5e05-7bfe-4293-9532-57d2ffebf059", "tenantName": "test-tenant", "applicationId": "ef72773b-9cae-485e-aca3-8b8ed385b172", "applicationName": "test-app", "deviceProfileId": "f84482b3-34a5-49d6-9c44-2b14be7aeffd", "deviceProfileName": "test-dev-profile", "deviceName": "test-dev", "devEui": "ed99ae9c3929cb2a", "deviceClassEnabled": "CLASS_A", "tags": {} }, "devAddr": "01c0e210" }

gateway downlink message also received with phy payload.

{ "downlinkId": 4289012261, "downlinkIdLegacy": "AAAAAAAAAAAAAAAA/6UiJQ==", "items": [ { "phyPayload": "IKFMHkhM5ZiYrRxJf9mTZgPH5eMDB9IhWFQ1FiqTK5Zq", "txInfoLegacy": { "frequency": 868100000, "power": 14, "timing": "DELAY", "context": "ZV9UNg==", "loraModulationInfo": { "bandwidth": 125, "spreadingFactor": 7, "codeRateLegacy": "4/5", "polarizationInversion": true }, "delayTimingInfo": { "delay": "5s" } }, "txInfo": { "frequency": 868100000, "power": 14, "modulation": { "lora": { "bandwidth": 125000, "spreadingFactor": 7, "codeRate": "CR_4_5", "polarizationInversion": true } }, "timing": { "delay": { "delay": "5s" } }, "context": "ZV9UNg==" } }, { "phyPayload": "IKFMHkhM5ZiYrRxJf9mTZgPH5eMDB9IhWFQ1FiqTK5Zq", "txInfoLegacy": { "frequency": 869525000, "power": 27, "timing": "DELAY", "context": "ZV9UNg==", "loraModulationInfo": { "bandwidth": 125, "spreadingFactor": 12, "codeRateLegacy": "4/5", "polarizationInversion": true }, "delayTimingInfo": { "delay": "6s" } }, "txInfo": { "frequency": 869525000, "power": 27, "modulation": { "lora": { "bandwidth": 125000, "spreadingFactor": 12, "codeRate": "CR_4_5", "polarizationInversion": true } }, "timing": { "delay": { "delay": "6s" } }, "context": "ZV9UNg==" } } ], "gatewayIdLegacy": "IafoDsp8oW0=", "gatewayId": "21a7e80eca7ca16d" }

either virtual gw is not receiving this message or gw not able to forward the message to device, this needs to be investigated

[lakshmanachimata]

Adding Chirpstack server side log

ber of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:01:54.905087Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:01:55.047661Z DEBUG gRPC{uri=/api.InternalService/StreamDeviceFrames}: chirpstack::api: Started processing request chirpstack_1 | 2023-11-23T14:01:55.054499Z INFO gRPC{uri=/api.InternalService/StreamDeviceFrames}: chirpstack::api: Finished processing request status="200" latency=6.856681ms chirpstack_1 | 2023-11-23T14:01:55.055389Z TRACE chirpstack::framelog: Frame-log received from stream key=up id=1700747978043-0 chirpstack_1 | 2023-11-23T14:01:55.055648Z TRACE chirpstack::framelog: frame-log received from stream key=down id=1700747979835-0 chirpstack_1 | 2023-11-23T14:01:55.055666Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack_1 | 2023-11-23T14:01:55.055814Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack_1 | 2023-11-23T14:01:55.055816Z TRACE chirpstack::framelog: Frame-log received from stream key=up id=1700747990048-0 chirpstack_1 | 2023-11-23T14:01:55.055990Z TRACE chirpstack::framelog: frame-log received from stream key=down id=1700747991844-0 chirpstack_1 | 2023-11-23T14:01:55.056131Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack_1 | 2023-11-23T14:01:55.056229Z TRACE chirpstack::framelog: Frame-log received from stream key=up id=1700748002053-0 chirpstack_1 | 2023-11-23T14:01:55.056594Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack_1 | 2023-11-23T14:01:55.056710Z TRACE chirpstack::framelog: frame-log received from stream key=down id=1700748003849-0 chirpstack_1 | 2023-11-23T14:01:55.056861Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack_1 | 2023-11-23T14:01:55.056951Z TRACE chirpstack::framelog: Frame-log received from stream key=up id=1700748014054-0 chirpstack_1 | 2023-11-23T14:01:55.057138Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack_1 | 2023-11-23T14:01:55.057282Z TRACE chirpstack::framelog: frame-log received from stream key=down id=1700748015853-0 chirpstack_1 | 2023-11-23T14:01:55.057425Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack_1 | 2023-11-23T14:01:55.057505Z TRACE chirpstack::framelog: Frame-log received from stream key=up id=1700748026064-0 chirpstack_1 | 2023-11-23T14:01:55.057672Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack_1 | 2023-11-23T14:01:55.057760Z TRACE chirpstack::framelog: frame-log received from stream key=down id=1700748028814-0 chirpstack_1 | 2023-11-23T14:01:55.057893Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack_1 | 2023-11-23T14:01:55.057990Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack-gateway-bridge-basicstation_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 25.00073464","time":"2023-11-23T14:01:55Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 25.000989322","time":"2023-11-23T14:01:55Z"} chirpstack_1 | 2023-11-23T14:01:55.905945Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:01:55.905959Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:01:55.905986Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:01:55.905990Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:01:55.908427Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:01:55.908461Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:01:55.908886Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:01:55.908910Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:01:56.909424Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:01:56.909460Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:01:56.910568Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:01:56.910590Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:01:56.915787Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:01:56.915823Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:01:56.920264Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:01:56.920303Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:01:57.917538Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:01:57.917575Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:01:57.919648Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:01:57.919681Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:01:57.920748Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:01:57.920768Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:01:57.922808Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:01:57.922836Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:01:58.921047Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:01:58.921088Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:01:58.923007Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:01:58.923041Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:01:58.924083Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:01:58.924109Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:01:58.926256Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:01:58.926278Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:01:59.924809Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:01:59.924848Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:01:59.926922Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:01:59.926939Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:01:59.926957Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:01:59.926977Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:01:59.929320Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:01:59.929349Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack-gateway-bridge-basicstation_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 30.000641756","time":"2023-11-23T14:02:00Z"} chirpstack-gateway-bridge-basicstation_1 | {"level":"debug","module":"mqtt","msg":"[pinger] keepalive sending ping","time":"2023-11-23T14:02:00Z"} chirpstack-gateway-bridge-basicstation_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncoming Received Message","time":"2023-11-23T14:02:00Z"} chirpstack-gateway-bridge-basicstation_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncomingComms: got msg on ibound","time":"2023-11-23T14:02:00Z"} chirpstack-gateway-bridge-basicstation_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncomingComms: received pingresp","time":"2023-11-23T14:02:00Z"} chirpstack-gateway-bridge-basicstation_1 | {"level":"debug","module":"mqtt","msg":"[net] logic waiting for msg on ibound","time":"2023-11-23T14:02:00Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 30.001236671","time":"2023-11-23T14:02:00Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[pinger] keepalive sending ping","time":"2023-11-23T14:02:00Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncoming Received Message","time":"2023-11-23T14:02:00Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncomingComms: got msg on ibound","time":"2023-11-23T14:02:00Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncomingComms: received pingresp","time":"2023-11-23T14:02:00Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] logic waiting for msg on ibound","time":"2023-11-23T14:02:00Z"} chirpstack_1 | 2023-11-23T14:02:00.928159Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:00.928181Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:00.929828Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:00.929838Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:00.929851Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:00.929860Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:00.931581Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:00.931604Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:01.931005Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:01.931031Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:01.932488Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:01.932520Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:01.933554Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:01.933568Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:01.935799Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:01.935835Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully lorawan-simulatoraoi_1 | 2023/11/23 14:02:02 [SIM]: SETUP OK! lorawan-simulatoraoi_1 | 2023/11/23 14:02:02 [SIM]: START lorawan-simulatoraoi_1 | 2023/11/23 14:02:02 GW[test-gw]: Setup OK! for BA 127.0.0.1:1700 chirpstack-gateway-bridge_1 | {"addr":{"IP":"127.0.0.1","Port":59259,"Zone":""},"level":"debug","msg":"backend/semtechudp: received udp packet from gateway","protocol_version":2,"time":"2023-11-23T14:02:02Z","type":2} chirpstack-gateway-bridge_1 | {"addr":{"IP":"127.0.0.1","Port":59259,"Zone":""},"level":"debug","msg":"backend/semtechudp: sending udp packet to gateway","protocol_version":2,"time":"2023-11-23T14:02:02Z","type":4} chirpstack-gateway-bridge_1 | {"gateway_id":"21a7e80eca7ca16d","level":"debug","msg":"integration/mqtt: set gateway subscription","subscribe":true,"time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"gateway_id":"21a7e80eca7ca16d","level":"debug","msg":"integration/mqtt: gateway subscription set","subscribe":true,"time":"2023-11-23T14:02:02Z"} lorawan-simulatoraoi_1 | 2023/11/23 14:02:02 GW[test-gw]: UDP connection with 127.0.0.1:1700 lorawan-simulatoraoi_1 | 2023/11/23 14:02:02 GW[test-gw]: Turn ON lorawan-simulatoraoi_1 | 2023/11/23 14:02:02 GW[test-gw]: PULL DATA send lorawan-simulatoraoi_1 | 2023/11/23 14:02:02 DEV[test-dev] |Activation| {A}: Setup OK! ed99ae9c3929cb2a lorawan-simulatoraoi_1 | 2023/11/23 14:02:02 DEV[test-dev] |Activation| {A}: Turn ON device ed99ae9c3929cb2a lorawan-simulatoraoi_1 | 2023/11/23 14:02:02 DEV[test-dev] |Activation| {A}: JOIN REQUEST sent is AAAAAAAAAAAAKsspOZyume2tgtVfr5E= from ed99ae9c3929cb2a lorawan-simulatoraoi_1 | 2023/11/23 14:02:02 DEV[test-dev] |Activation| {A}: Open RXs for OTAA 0 and 868100000 lorawan-simulatoraoi_1 | 2023/11/23 14:02:02 GW[test-gw]: PUSH DATA send chirpstack_1 | 2023-11-23T14:02:02.317217Z INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_config_id="eu868" topic="eu868/gateway/21a7e80eca7ca16d/event/up" qos=0 json=true chirpstack_1 | 2023-11-23T14:02:02.317499Z TRACE chirpstack::uplink: Adding uplink event to deduplication set key="up:collect:08a0cff89d03120a1a0808c8d00710072801:0000000000000000002acb29399cae99edad82d55faf91" chirpstack-gateway-bridge_1 | {"addr":{"IP":"127.0.0.1","Port":59259,"Zone":""},"level":"debug","msg":"backend/semtechudp: received udp packet from gateway","protocol_version":2,"time":"2023-11-23T14:02:02Z","type":0} chirpstack-gateway-bridge_1 | {"event":"up","level":"info","msg":"integration/mqtt: publishing event","qos":0,"time":"2023-11-23T14:02:02Z","topic":"eu868/gateway/21a7e80eca7ca16d/event/up","uplink_id":15938} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] enter Publish","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] sending publish message, topic: eu868/gateway/21a7e80eca7ca16d/event/up","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound msg to write 0","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound wrote msg, id: 0","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] outgoing waiting for an outbound message","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"addr":{"IP":"127.0.0.1","Port":59259,"Zone":""},"level":"debug","msg":"backend/semtechudp: sending udp packet to gateway","protocol_version":2,"time":"2023-11-23T14:02:02Z","type":1} chirpstack-gateway-bridge_1 | {"event":"stats","level":"info","msg":"integration/mqtt: publishing event","qos":0,"time":"2023-11-23T14:02:02Z","topic":"eu868/gateway/21a7e80eca7ca16d/event/stats"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] enter Publish","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] sending publish message, topic: eu868/gateway/21a7e80eca7ca16d/event/stats","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound msg to write 0","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound wrote msg, id: 0","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] outgoing waiting for an outbound message","time":"2023-11-23T14:02:02Z"} chirpstack_1 | 2023-11-23T14:02:02.318017Z INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_config_id="eu868" topic="eu868/gateway/21a7e80eca7ca16d/event/stats" qos=0 json=true chirpstack_1 | 2023-11-23T14:02:02.318132Z TRACE stats{gateway_id=21a7e80eca7ca16d}: chirpstack::uplink::stats: Update gateway state chirpstack_1 | 2023-11-23T14:02:02.318984Z TRACE chirpstack::uplink: Requesting deduplication lock lock_key="up:collect:08a0cff89d03120a1a0808c8d00710072801:0000000000000000002acb29399cae99edad82d55faf91:lock" chirpstack_1 | 2023-11-23T14:02:02.319491Z TRACE chirpstack::uplink: Waiting for more uplink events to receive key="up:collect:08a0cff89d03120a1a0808c8d00710072801:0000000000000000002acb29399cae99edad82d55faf91" chirpstack_1 | 2023-11-23T14:02:02.332231Z INFO stats{gateway_id=21a7e80eca7ca16d}: chirpstack::storage::gateway: Gateway state updated gateway_id=21a7e80eca7ca16d chirpstack_1 | 2023-11-23T14:02:02.332282Z TRACE stats{gateway_id=21a7e80eca7ca16d}: chirpstack::uplink::stats: Saving stats chirpstack_1 | 2023-11-23T14:02:02.332951Z INFO stats{gateway_id=21a7e80eca7ca16d}: chirpstack::storage::metrics: Metrics saved name=gw:21a7e80eca7ca16d aggregation=HOUR chirpstack_1 | 2023-11-23T14:02:02.333305Z INFO stats{gateway_id=21a7e80eca7ca16d}: chirpstack::storage::metrics: Metrics saved name=gw:21a7e80eca7ca16d aggregation=DAY chirpstack_1 | 2023-11-23T14:02:02.333601Z INFO stats{gateway_id=21a7e80eca7ca16d}: chirpstack::storage::metrics: Metrics saved name=gw:21a7e80eca7ca16d aggregation=MONTH chirpstack_1 | 2023-11-23T14:02:02.333637Z TRACE stats{gateway_id=21a7e80eca7ca16d}: chirpstack::uplink::stats: Updating gateway configuration chirpstack_1 | 2023-11-23T14:02:02.333653Z TRACE stats{gateway_id=21a7e80eca7ca16d}: chirpstack::uplink::stats: Gateway configuration only works with Concentratord, skipping chirpstack-gateway-bridge_1 | {"level":"info","msg":"integration/mqtt: subscribing to topic","qos":0,"time":"2023-11-23T14:02:02Z","topic":"eu868/gateway/21a7e80eca7ca16d/command/#"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] enter Subscribe","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] SUBSCRIBE: dup: false qos: 1 retain: false rLength: 0 MessageID: 1 topics: [eu868/gateway/21a7e80eca7ca16d/command/#]","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] sending subscribe message, topic: eu868/gateway/21a7e80eca7ca16d/command/#","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] exit Subscribe","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound priority msg to write, type *packets.SubscribePacket","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] outgoing waiting for an outbound message","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncoming Received Message","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncomingComms: got msg on ibound","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"warning","module":"mqtt","msg":"[store] memorystore del: message 1 not found","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncomingComms: received suback, id: 1","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncomingComms: granted qoss [0]","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] logic waiting for msg on ibound","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","msg":"integration/mqtt: subscribed to topic","qos":0,"time":"2023-11-23T14:02:02Z","topic":"eu868/gateway/21a7e80eca7ca16d/command/#"} chirpstack-gateway-bridge_1 | {"gateway_id":"21a7e80eca7ca16d","level":"info","msg":"integration/mqtt: publishing state","qos":0,"state":"conn","time":"2023-11-23T14:02:02Z","topic":"eu868/gateway/21a7e80eca7ca16d/state/conn"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] enter Publish","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] sending publish message, topic: eu868/gateway/21a7e80eca7ca16d/state/conn","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound msg to write 0","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound wrote msg, id: 0","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] outgoing waiting for an outbound message","time":"2023-11-23T14:02:02Z"} chirpstack_1 | 2023-11-23T14:02:02.520054Z TRACE chirpstack::uplink: Collecting received uplink events key="up:collect:08a0cff89d03120a1a0808c8d00710072801:0000000000000000002acb29399cae99edad82d55faf91" chirpstack_1 | 2023-11-23T14:02:02.520634Z INFO up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::uplink: Uplink received m_type="JoinRequest" chirpstack_1 | 2023-11-23T14:02:02.520658Z DEBUG up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::uplink: Updating gateway meta-data for uplink frame-set chirpstack_1 | 2023-11-23T14:02:02.523540Z DEBUG up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::uplink: Logging uplink frame to Redis Stream chirpstack_1 | 2023-11-23T14:02:02.524175Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Getting JoinRequestPayload chirpstack_1 | 2023-11-23T14:02:02.524219Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Getting device chirpstack_1 | 2023-11-23T14:02:02.526380Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Trying to get Join Server client join_eui=0000000000000000 chirpstack_1 | 2023-11-23T14:02:02.526429Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Join Server client does not exist chirpstack_1 | 2023-11-23T14:02:02.526448Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Getting application chirpstack_1 | 2023-11-23T14:02:02.529563Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Getting tenant chirpstack_1 | 2023-11-23T14:02:02.531020Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Getting device-profile chirpstack_1 | 2023-11-23T14:02:02.535721Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Filtering rx_info by tenant_id chirpstack_1 | 2023-11-23T14:02:02.535762Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Filtering rx_info by region_config_id chirpstack_1 | 2023-11-23T14:02:02.535780Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Logging uplink frame-set chirpstack_1 | 2023-11-23T14:02:02.539217Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Validate dev-nonce and get device-keys chirpstack_1 | 2023-11-23T14:02:02.542526Z INFO up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::storage::device_keys: Device-nonce validated, join-nonce incremented and stored dev_eui=ed99ae9c3929cb2a dev_nonce=33453 chirpstack_1 | 2023-11-23T14:02:02.542585Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Constructing JoinAccept payload chirpstack_1 | 2023-11-23T14:02:02.542641Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Setting session-keys chirpstack_1 | 2023-11-23T14:02:02.542663Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Logging uplink meta chirpstack_1 | 2023-11-23T14:02:02.543209Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Creating device-session chirpstack_1 | 2023-11-23T14:02:02.543758Z INFO up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::storage::device_session: Device-session saved dev_eui=ed99ae9c3929cb2a dev_addr=003a62c2 chirpstack_1 | 2023-11-23T14:02:02.543795Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Flushing device-queue chirpstack_1 | 2023-11-23T14:02:02.544771Z INFO up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::storage::device_queue: Device queue flushed dev_eui=ed99ae9c3929cb2a count=0 chirpstack_1 | 2023-11-23T14:02:02.548433Z INFO up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::storage::device: Enabled class updated dev_eui=ed99ae9c3929cb2a enabled_class=A chirpstack_1 | 2023-11-23T14:02:02.548480Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Setting DevAddr chirpstack_1 | 2023-11-23T14:02:02.551959Z INFO up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::storage::device: Updated DevAddr dev_eui=ed99ae9c3929cb2a dev_addr=003a62c2 chirpstack_1 | 2023-11-23T14:02:02.552002Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Setting JoinEUI chirpstack_1 | 2023-11-23T14:02:02.553835Z INFO up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::storage::device: Updated JoinEUI dev_eui=ed99ae9c3929cb2a join_eui=0000000000000000 chirpstack_1 | 2023-11-23T14:02:02.553874Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Starting downlink join-accept flow chirpstack_1 | 2023-11-23T14:02:02.553931Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request:join_accept{downlink_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::downlink::join: Set device-gateway rx-info chirpstack_1 | 2023-11-23T14:02:02.553957Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request:join_accept{downlink_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::downlink::join: Select downlink gateway chirpstack_1 | 2023-11-23T14:02:02.553989Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request:join_accept{downlink_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::downlink::join: Setting tx-info chirpstack_1 | 2023-11-23T14:02:02.554009Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request:join_accept{downlink_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::downlink::join: Setting tx-info for RX1 chirpstack_1 | 2023-11-23T14:02:02.554033Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request:join_accept{downlink_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::downlink::join: Setting tx-info for RX2 chirpstack_1 | 2023-11-23T14:02:02.554055Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request:join_accept{downlink_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::downlink::join: Setting downlink frame chirpstack_1 | 2023-11-23T14:02:02.554080Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request:join_accept{downlink_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::downlink::join: Saving downlink frame chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncoming Received Message","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncomingComms: got msg on ibound","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncomingComms: received publish, msgId: 0","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] logic waiting for msg on ibound","time":"2023-11-23T14:02:02Z"} chirpstack_1 | 2023-11-23T14:02:02.554509Z INFO up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request:join_accept{downlink_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::storage::downlink_frame: Downlink-frame saved downlink_id=117807600 chirpstack_1 | 2023-11-23T14:02:02.554547Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request:join_accept{downlink_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::downlink::join: Sending join-accept response chirpstack_1 | 2023-11-23T14:02:02.554818Z INFO up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request:join_accept{downlink_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::gateway::backend::mqtt: Sending downlink frame gateway_id=21a7e80eca7ca16d topic=eu868/gateway/21a7e80eca7ca16d/command/down json=true chirpstack_1 | 2023-11-23T14:02:02.555570Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request:join_accept{downlink_id=dab64434-27aa-439e-b228-d93fddbbeef4}: chirpstack::gateway::backend::mqtt: Message sent chirpstack_1 | 2023-11-23T14:02:02.555626Z TRACE up{deduplication_id=dab64434-27aa-439e-b228-d93fddbbeef4}:join_request: chirpstack::uplink::join: Sending join event chirpstack-gateway-bridge_1 | {"downlink_id":117807600,"gateway_id":"21a7e80eca7ca16d","level":"info","msg":"integration/mqtt: downlink frame received","time":"2023-11-23T14:02:02Z"} chirpstack-gateway-bridge_1 | {"addr":{"IP":"127.0.0.1","Port":59259,"Zone":""},"level":"debug","msg":"backend/semtechudp: sending udp packet to gateway","protocol_version":2,"time":"2023-11-23T14:02:02Z","type":3} chirpstack_1 | 2023-11-23T14:02:02.562915Z INFO chirpstack::integration::mqtt: Publishing event topic=application/ef72773b-9cae-485e-aca3-8b8ed385b172/device/ed99ae9c3929cb2a/event/join chirpstack_1 | 2023-11-23T14:02:02.933178Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:02.933208Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:02.934761Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:02.934789Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:02.936850Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:02.936866Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:02.939644Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:02.939685Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:03.061425Z TRACE chirpstack::framelog: Frame-log received from stream key=up id=1700748122536-0 chirpstack_1 | 2023-11-23T14:02:03.061740Z TRACE chirpstack::api::internal: Message received from Redis Stream channel lorawan-simulatoraoi_1 | 2023/11/23 14:02:03 GW[test-gw]: PULL ACK received chirpstack_1 | 2023-11-23T14:02:03.936418Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:03.936451Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:03.938768Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:03.938803Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:03.940870Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:03.940890Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:03.943127Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:03.943155Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully lorawan-simulatoraoi_1 | 2023/11/23 14:02:04 GW[test-gw]: PUSH ACK received chirpstack_1 | 2023-11-23T14:02:04.940412Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:04.940455Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:04.942173Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:04.942208Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:04.944260Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:04.944275Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:04.946306Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:04.946333Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully lorawan-simulatoraoi_1 | 2023/11/23 14:02:05 GW[test-gw]: PULL RESP received lorawan-simulatoraoi_1 | 2023/11/23 14:02:05 GW[test-gw]: Pull response came for freq 868100000 lorawan-simulatoraoi_1 | 2023/11/23 14:02:05 GW[test-gw]: TX ACK sent chirpstack-gateway-bridge_1 | {"addr":{"IP":"127.0.0.1","Port":59259,"Zone":""},"level":"debug","msg":"backend/semtechudp: received udp packet from gateway","protocol_version":2,"time":"2023-11-23T14:02:05Z","type":5} chirpstack-gateway-bridge_1 | {"event":"ack","level":"info","msg":"integration/mqtt: publishing event","qos":0,"time":"2023-11-23T14:02:05Z","topic":"eu868/gateway/21a7e80eca7ca16d/event/ack"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] enter Publish","time":"2023-11-23T14:02:05Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] sending publish message, topic: eu868/gateway/21a7e80eca7ca16d/event/ack","time":"2023-11-23T14:02:05Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound msg to write 0","time":"2023-11-23T14:02:05Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound wrote msg, id: 0","time":"2023-11-23T14:02:05Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] outgoing waiting for an outbound message","time":"2023-11-23T14:02:05Z"} chirpstack_1 | 2023-11-23T14:02:05.316836Z INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_config_id="eu868" topic="eu868/gateway/21a7e80eca7ca16d/event/ack" qos=0 json=true chirpstack_1 | 2023-11-23T14:02:05.317054Z TRACE tx_ack{downlink_id=117807600}: chirpstack::downlink::tx_ack: Get downlink-frame from Redis chirpstack_1 | 2023-11-23T14:02:05.317535Z TRACE tx_ack{downlink_id=117807600}: chirpstack::downlink::tx_ack: Decoding PhyPayload chirpstack_1 | 2023-11-23T14:02:05.317565Z TRACE tx_ack{downlink_id=117807600}: chirpstack::downlink::tx_ack: Logging downlink frame chirpstack_1 | 2023-11-23T14:02:05.317589Z INFO tx_ack{downlink_id=117807600}: chirpstack::downlink::tx_ack: Log downlink-frame for gateway gateway_id=21a7e80eca7ca16d chirpstack_1 | 2023-11-23T14:02:05.318269Z INFO tx_ack{downlink_id=117807600}: chirpstack::downlink::tx_ack: Log downlink-frame for device device_eui=ed99ae9c3929cb2a chirpstack_1 | 2023-11-23T14:02:05.318842Z TRACE tx_ack{downlink_id=117807600}: chirpstack::downlink::tx_ack: Logging downlink meta chirpstack-gateway-bridge-basicstation_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 5.000321726","time":"2023-11-23T14:02:05Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 0.446118049","time":"2023-11-23T14:02:05Z"} chirpstack_1 | 2023-11-23T14:02:05.943169Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:05.943207Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:05.945173Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:05.945238Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:05.947305Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:05.947326Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:05.949227Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:05.949253Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:06.063080Z TRACE chirpstack::framelog: frame-log received from stream key=down id=1700748125318-0 chirpstack_1 | 2023-11-23T14:02:06.063374Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack_1 | 2023-11-23T14:02:06.945851Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:06.945888Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:06.947780Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:06.947816Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:06.949869Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:06.949885Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:06.951824Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:06.951846Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:07.948975Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:07.948999Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:07.950880Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:07.950913Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:07.952963Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:07.952977Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:07.954657Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:07.954683Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:08.951906Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:08.951941Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:08.953940Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:08.953972Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:08.956032Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:08.956049Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:08.958154Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:08.958184Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:09.955370Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:09.955406Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:09.957107Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:09.957137Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:09.959205Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:09.959224Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:09.961174Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:09.961200Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack-gateway-bridge-basicstation_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 9.999983378","time":"2023-11-23T14:02:10Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 5.446019994","time":"2023-11-23T14:02:10Z"} chirpstack_1 | 2023-11-23T14:02:10.958546Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:10.958586Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:10.960565Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:10.960600Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:10.962662Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:10.962681Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:10.964773Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:10.964796Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:11.962327Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:11.962366Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:11.964112Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:11.964146Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:11.966200Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:11.966221Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:11.968323Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:11.968352Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:12.965544Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:12.965585Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:12.967506Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:12.967537Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:12.969658Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:12.969674Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:12.971548Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:12.971575Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:13.968439Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:13.968478Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:13.970328Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:13.970360Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:13.972434Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:13.972457Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:13.974675Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:13.974711Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully lorawan-simulatoraoi_1 | 2023/11/23 14:02:14 DEV[test-dev] |Activation| {A}: None downlink received lorawan-simulatoraoi_1 | 2023/11/23 14:02:14 DEV[test-dev] |Activation| {A}: Unjoined lorawan-simulatoraoi_1 | 2023/11/23 14:02:14 DEV[test-dev] |Activation| {A}: JOIN REQUEST sent is AAAAAAAAAAAAKsspOZyume0CRyeK+gk= from ed99ae9c3929cb2a lorawan-simulatoraoi_1 | 2023/11/23 14:02:14 DEV[test-dev] |Activation| {A}: Open RXs for OTAA 0 and 868100000 lorawan-simulatoraoi_1 | 2023/11/23 14:02:14 GW[test-gw]: PUSH DATA send chirpstack-gateway-bridge_1 | {"addr":{"IP":"127.0.0.1","Port":59259,"Zone":""},"level":"debug","msg":"backend/semtechudp: received udp packet from gateway","protocol_version":2,"time":"2023-11-23T14:02:14Z","type":0} chirpstack-gateway-bridge_1 | {"event":"up","level":"info","msg":"integration/mqtt: publishing event","qos":0,"time":"2023-11-23T14:02:14Z","topic":"eu868/gateway/21a7e80eca7ca16d/event/up","uplink_id":54644} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] enter Publish","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] sending publish message, topic: eu868/gateway/21a7e80eca7ca16d/event/up","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound msg to write 0","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound wrote msg, id: 0","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] outgoing waiting for an outbound message","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"addr":{"IP":"127.0.0.1","Port":59259,"Zone":""},"level":"debug","msg":"backend/semtechudp: sending udp packet to gateway","protocol_version":2,"time":"2023-11-23T14:02:14Z","type":1} chirpstack_1 | 2023-11-23T14:02:14.320636Z INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_config_id="eu868" topic="eu868/gateway/21a7e80eca7ca16d/event/up" qos=0 json=true chirpstack_1 | 2023-11-23T14:02:14.320897Z TRACE chirpstack::uplink: Adding uplink event to deduplication set key="up:collect:08a0cff89d03120a1a0808c8d00710072801:0000000000000000002acb29399cae99ed0247278afa09" chirpstack-gateway-bridge_1 | {"event":"stats","level":"info","msg":"integration/mqtt: publishing event","qos":0,"time":"2023-11-23T14:02:14Z","topic":"eu868/gateway/21a7e80eca7ca16d/event/stats"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] enter Publish","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] sending publish message, topic: eu868/gateway/21a7e80eca7ca16d/event/stats","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound msg to write 0","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound wrote msg, id: 0","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] outgoing waiting for an outbound message","time":"2023-11-23T14:02:14Z"} chirpstack_1 | 2023-11-23T14:02:14.322334Z INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_config_id="eu868" topic="eu868/gateway/21a7e80eca7ca16d/event/stats" qos=0 json=true chirpstack_1 | 2023-11-23T14:02:14.322505Z TRACE stats{gateway_id=21a7e80eca7ca16d}: chirpstack::uplink::stats: Update gateway state chirpstack_1 | 2023-11-23T14:02:14.322873Z TRACE chirpstack::uplink: Requesting deduplication lock lock_key="up:collect:08a0cff89d03120a1a0808c8d00710072801:0000000000000000002acb29399cae99ed0247278afa09:lock" chirpstack_1 | 2023-11-23T14:02:14.323102Z TRACE chirpstack::uplink: Waiting for more uplink events to receive key="up:collect:08a0cff89d03120a1a0808c8d00710072801:0000000000000000002acb29399cae99ed0247278afa09" chirpstack_1 | 2023-11-23T14:02:14.335423Z INFO stats{gateway_id=21a7e80eca7ca16d}: chirpstack::storage::gateway: Gateway state updated gateway_id=21a7e80eca7ca16d chirpstack_1 | 2023-11-23T14:02:14.335484Z TRACE stats{gateway_id=21a7e80eca7ca16d}: chirpstack::uplink::stats: Saving stats chirpstack_1 | 2023-11-23T14:02:14.336204Z INFO stats{gateway_id=21a7e80eca7ca16d}: chirpstack::storage::metrics: Metrics saved name=gw:21a7e80eca7ca16d aggregation=HOUR chirpstack_1 | 2023-11-23T14:02:14.336629Z INFO stats{gateway_id=21a7e80eca7ca16d}: chirpstack::storage::metrics: Metrics saved name=gw:21a7e80eca7ca16d aggregation=DAY chirpstack_1 | 2023-11-23T14:02:14.337054Z INFO stats{gateway_id=21a7e80eca7ca16d}: chirpstack::storage::metrics: Metrics saved name=gw:21a7e80eca7ca16d aggregation=MONTH chirpstack_1 | 2023-11-23T14:02:14.337082Z TRACE stats{gateway_id=21a7e80eca7ca16d}: chirpstack::uplink::stats: Updating gateway configuration chirpstack_1 | 2023-11-23T14:02:14.337099Z TRACE stats{gateway_id=21a7e80eca7ca16d}: chirpstack::uplink::stats: Gateway configuration only works with Concentratord, skipping chirpstack_1 | 2023-11-23T14:02:14.524574Z TRACE chirpstack::uplink: Collecting received uplink events key="up:collect:08a0cff89d03120a1a0808c8d00710072801:0000000000000000002acb29399cae99ed0247278afa09" chirpstack_1 | 2023-11-23T14:02:14.525305Z INFO up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::uplink: Uplink received m_type="JoinRequest" chirpstack_1 | 2023-11-23T14:02:14.525338Z DEBUG up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::uplink: Updating gateway meta-data for uplink frame-set chirpstack_1 | 2023-11-23T14:02:14.526562Z DEBUG up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::uplink: Logging uplink frame to Redis Stream chirpstack_1 | 2023-11-23T14:02:14.527201Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Getting JoinRequestPayload chirpstack_1 | 2023-11-23T14:02:14.527223Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Getting device chirpstack_1 | 2023-11-23T14:02:14.527962Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Trying to get Join Server client join_eui=0000000000000000 chirpstack_1 | 2023-11-23T14:02:14.527997Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Join Server client does not exist chirpstack_1 | 2023-11-23T14:02:14.528015Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Getting application chirpstack_1 | 2023-11-23T14:02:14.528538Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Getting tenant chirpstack_1 | 2023-11-23T14:02:14.529251Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Getting device-profile chirpstack_1 | 2023-11-23T14:02:14.530318Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Filtering rx_info by tenant_id chirpstack_1 | 2023-11-23T14:02:14.530349Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Filtering rx_info by region_config_id chirpstack_1 | 2023-11-23T14:02:14.530372Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Logging uplink frame-set chirpstack_1 | 2023-11-23T14:02:14.531862Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Validate dev-nonce and get device-keys chirpstack_1 | 2023-11-23T14:02:14.545093Z INFO up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::storage::device_keys: Device-nonce validated, join-nonce incremented and stored dev_eui=ed99ae9c3929cb2a dev_nonce=18178 chirpstack_1 | 2023-11-23T14:02:14.545135Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Constructing JoinAccept payload chirpstack_1 | 2023-11-23T14:02:14.545190Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Setting session-keys chirpstack_1 | 2023-11-23T14:02:14.545214Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Logging uplink meta chirpstack_1 | 2023-11-23T14:02:14.545679Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Creating device-session chirpstack_1 | 2023-11-23T14:02:14.546226Z INFO up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::storage::device_session: Device-session saved dev_eui=ed99ae9c3929cb2a dev_addr=00e808e3 chirpstack_1 | 2023-11-23T14:02:14.546265Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Flushing device-queue chirpstack_1 | 2023-11-23T14:02:14.546978Z INFO up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::storage::device_queue: Device queue flushed dev_eui=ed99ae9c3929cb2a count=0 chirpstack_1 | 2023-11-23T14:02:14.548498Z INFO up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::storage::device: Enabled class updated dev_eui=ed99ae9c3929cb2a enabled_class=A chirpstack_1 | 2023-11-23T14:02:14.548532Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Setting DevAddr chirpstack_1 | 2023-11-23T14:02:14.550077Z INFO up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::storage::device: Updated DevAddr dev_eui=ed99ae9c3929cb2a dev_addr=00e808e3 chirpstack_1 | 2023-11-23T14:02:14.550108Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Setting JoinEUI chirpstack_1 | 2023-11-23T14:02:14.551385Z INFO up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::storage::device: Updated JoinEUI dev_eui=ed99ae9c3929cb2a join_eui=0000000000000000 chirpstack_1 | 2023-11-23T14:02:14.551413Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Starting downlink join-accept flow chirpstack_1 | 2023-11-23T14:02:14.551442Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request:join_accept{downlink_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::downlink::join: Set device-gateway rx-info chirpstack_1 | 2023-11-23T14:02:14.551470Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request:join_accept{downlink_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::downlink::join: Select downlink gateway chirpstack_1 | 2023-11-23T14:02:14.551507Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request:join_accept{downlink_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::downlink::join: Setting tx-info chirpstack_1 | 2023-11-23T14:02:14.551532Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request:join_accept{downlink_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::downlink::join: Setting tx-info for RX1 chirpstack_1 | 2023-11-23T14:02:14.551561Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request:join_accept{downlink_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::downlink::join: Setting tx-info for RX2 chirpstack_1 | 2023-11-23T14:02:14.551583Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request:join_accept{downlink_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::downlink::join: Setting downlink frame chirpstack_1 | 2023-11-23T14:02:14.551612Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request:join_accept{downlink_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::downlink::join: Saving downlink frame chirpstack_1 | 2023-11-23T14:02:14.551994Z INFO up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request:join_accept{downlink_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::storage::downlink_frame: Downlink-frame saved downlink_id=2406439698 chirpstack_1 | 2023-11-23T14:02:14.552029Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request:join_accept{downlink_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::downlink::join: Sending join-accept response chirpstack_1 | 2023-11-23T14:02:14.552258Z INFO up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request:join_accept{downlink_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::gateway::backend::mqtt: Sending downlink frame gateway_id=21a7e80eca7ca16d topic=eu868/gateway/21a7e80eca7ca16d/command/down json=true chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncoming Received Message","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncomingComms: got msg on ibound","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] startIncomingComms: received publish, msgId: 0","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] logic waiting for msg on ibound","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"downlink_id":2406439698,"gateway_id":"21a7e80eca7ca16d","level":"info","msg":"integration/mqtt: downlink frame received","time":"2023-11-23T14:02:14Z"} chirpstack-gateway-bridge_1 | {"addr":{"IP":"127.0.0.1","Port":59259,"Zone":""},"level":"debug","msg":"backend/semtechudp: sending udp packet to gateway","protocol_version":2,"time":"2023-11-23T14:02:14Z","type":3} chirpstack_1 | 2023-11-23T14:02:14.553816Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request:join_accept{downlink_id=3982c184-ab1f-423e-b8ae-b805ca703b79}: chirpstack::gateway::backend::mqtt: Message sent chirpstack_1 | 2023-11-23T14:02:14.553874Z TRACE up{deduplication_id=3982c184-ab1f-423e-b8ae-b805ca703b79}:join_request: chirpstack::uplink::join: Sending join event chirpstack_1 | 2023-11-23T14:02:14.555140Z INFO chirpstack::integration::mqtt: Publishing event topic=application/ef72773b-9cae-485e-aca3-8b8ed385b172/device/ed99ae9c3929cb2a/event/join chirpstack_1 | 2023-11-23T14:02:14.971690Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:14.971730Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:14.973655Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:14.973693Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:14.975773Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:14.975798Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:14.977778Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:14.977811Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:15.067095Z TRACE chirpstack::framelog: Frame-log received from stream key=up id=1700748134530-0 chirpstack_1 | 2023-11-23T14:02:15.067406Z TRACE chirpstack::api::internal: Message received from Redis Stream channel lorawan-simulatoraoi_1 | 2023/11/23 14:02:15 GW[test-gw]: PUSH ACK received chirpstack-gateway-bridge-basicstation_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 15.000796309","time":"2023-11-23T14:02:15Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 1.44212505","time":"2023-11-23T14:02:15Z"} chirpstack_1 | 2023-11-23T14:02:15.975678Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:15.975713Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:15.977737Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:15.977780Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:15.978808Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:15.978825Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:15.980815Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:15.980838Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully lorawan-simulatoraoi_1 | 2023/11/23 14:02:16 GW[test-gw]: PULL RESP received chirpstack-gateway-bridge_1 | {"addr":{"IP":"127.0.0.1","Port":59259,"Zone":""},"level":"debug","msg":"backend/semtechudp: received udp packet from gateway","protocol_version":2,"time":"2023-11-23T14:02:16Z","type":5} chirpstack-gateway-bridge_1 | {"event":"ack","level":"info","msg":"integration/mqtt: publishing event","qos":0,"time":"2023-11-23T14:02:16Z","topic":"eu868/gateway/21a7e80eca7ca16d/event/ack"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] enter Publish","time":"2023-11-23T14:02:16Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[client] sending publish message, topic: eu868/gateway/21a7e80eca7ca16d/event/ack","time":"2023-11-23T14:02:16Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound msg to write 0","time":"2023-11-23T14:02:16Z"} lorawan-simulatoraoi_1 | 2023/11/23 14:02:16 GW[test-gw]: Pull response came for freq 868100000 lorawan-simulatoraoi_1 | 2023/11/23 14:02:16 GW[test-gw]: TX ACK sent chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] obound wrote msg, id: 0","time":"2023-11-23T14:02:16Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[net] outgoing waiting for an outbound message","time":"2023-11-23T14:02:16Z"} chirpstack_1 | 2023-11-23T14:02:16.324235Z INFO chirpstack::gateway::backend::mqtt: Message received from gateway region_config_id="eu868" topic="eu868/gateway/21a7e80eca7ca16d/event/ack" qos=0 json=true chirpstack_1 | 2023-11-23T14:02:16.324411Z TRACE tx_ack{downlink_id=2406439698}: chirpstack::downlink::tx_ack: Get downlink-frame from Redis chirpstack_1 | 2023-11-23T14:02:16.324827Z TRACE tx_ack{downlink_id=2406439698}: chirpstack::downlink::tx_ack: Decoding PhyPayload chirpstack_1 | 2023-11-23T14:02:16.324864Z TRACE tx_ack{downlink_id=2406439698}: chirpstack::downlink::tx_ack: Logging downlink frame chirpstack_1 | 2023-11-23T14:02:16.324887Z INFO tx_ack{downlink_id=2406439698}: chirpstack::downlink::tx_ack: Log downlink-frame for gateway gateway_id=21a7e80eca7ca16d chirpstack_1 | 2023-11-23T14:02:16.325587Z INFO tx_ack{downlink_id=2406439698}: chirpstack::downlink::tx_ack: Log downlink-frame for device device_eui=ed99ae9c3929cb2a chirpstack_1 | 2023-11-23T14:02:16.326295Z TRACE tx_ack{downlink_id=2406439698}: chirpstack::downlink::tx_ack: Logging downlink meta chirpstack_1 | 2023-11-23T14:02:16.979477Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:16.979506Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:16.981007Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:16.981037Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:16.982080Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:16.982096Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:16.983865Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:16.983889Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:17.068420Z TRACE chirpstack::framelog: frame-log received from stream key=down id=1700748136325-0 chirpstack_1 | 2023-11-23T14:02:17.068691Z TRACE chirpstack::api::internal: Message received from Redis Stream channel chirpstack_1 | 2023-11-23T14:02:17.981813Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:17.981848Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:17.983594Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:17.983622Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:17.985700Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:17.985720Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:17.987685Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:17.987714Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:18.984738Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:18.984775Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:18.986816Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:18.986850Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:18.988906Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:18.988922Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:18.990756Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:18.990785Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:19.988121Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:19.988155Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:19.990053Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:19.990089Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:19.992145Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:19.992161Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:19.994143Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:19.994171Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack-gateway-bridge-basicstation_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 20.000131769","time":"2023-11-23T14:02:20Z"} chirpstack-gateway-bridge_1 | {"level":"debug","module":"mqtt","msg":"[pinger] ping check 4.439609058","time":"2023-11-23T14:02:20Z"} chirpstack_1 | 2023-11-23T14:02:20.991658Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:20.991690Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:20.993282Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:20.993310Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:20.995349Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:20.995362Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:20.997113Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:20.997135Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully chirpstack_1 | 2023-11-23T14:02:21.994264Z TRACE chirpstack::downlink::scheduler: Starting multicast-group queue scheduler loop run chirpstack_1 | 2023-11-23T14:02:21.994297Z TRACE chirpstack::downlink::scheduler: Getting schedulable multicast-group queue items chirpstack_1 | 2023-11-23T14:02:21.996135Z TRACE chirpstack::downlink::scheduler: Got this number of multicast-group queue items count=0 chirpstack_1 | 2023-11-23T14:02:21.996167Z TRACE chirpstack::downlink::scheduler: Multicast-group queue scheduler run completed successfully chirpstack_1 | 2023-11-23T14:02:21.998231Z TRACE chirpstack::downlink::scheduler: Starting class_b_c_scheduler_loop run chirpstack_1 | 2023-11-23T14:02:21.998246Z TRACE chirpstack::downlink::scheduler: Getting devices that have schedulable queue-items chirpstack_1 | 2023-11-23T14:02:22.000224Z TRACE chirpstack::downlink::scheduler: Got this number of devices with schedulable queue-items device_count=0 chirpstack_1 | 2023-11-23T14:02:22.000248Z TRACE chirpstack::downlink::scheduler: class_b_c_scheduler_loop completed successfully

[fedyfausto]

Excuse me guys but actually, I am working on a presentation to present the simulator this December at a conference and I am looking for a collaborator that can help me to fix these issues.

Good Evening, We faced the same problem and after investigations, we have a strong impression that it comes from the network. As packet forwarder works in UDP, the answer from ChirpStack is addressed to a random UDP port. Because LWN Simulator is in another docker Stack and this random UDP port is not open, the answer is lost. I confirmed this by running a Wireshark analysis, where I saw this random port as destination to the join response sent by ChirpStack. I also tried to put both ChirpStack and LWN-Simulator in the same stack so they can discuss freely and the joins were accepted.

Thanks for the response for the other users :D

Anyway, I suggest that if you want to run all environments via Docker, try to set the network mode to HOST, if the problem continues, we can schedule a call to face the problem together!

[damien-r]

@fedyfausto Thanks. It works in host mode. May I suggest to add it in the project's docker-compose.yml file, so it works "out of the box"?

[DmSilkin]

@fedyfausto Hi, i think I am facing the same issue, can you please provide your docker-compose.yml? I've changed everything to host mode but still getting joins

[fedyfausto]

Did you try the 1.0.2 version from the releases? https://github.com/UniCT-ARSLab/LWN-Simulator/releases/tag/v1.0.2