brocaar / chirpstack-network-server

ChirpStack Network Server is an open-source LoRaWAN network-server.
https://www.chirpstack.io
MIT License
1.5k stars 546 forks source link

Downlink frame is not displayed in gateway live lorawan frame #515

Open wwx0715 opened 3 years ago

wwx0715 commented 3 years ago

chirpstack network server version 3.11.0

Gateway live lorawan frame show downlink frame right at ubuntu, but mostly not show downlink frame at imx6ull yocto. I can see some different from network server log:

at ubuntu: "device-session saved" and "storage: downlink-frame saved" first, and then receive "backend/gateway: downlink tx acknowledgement received", and then "sent downlink meta-data to network-controller" and "sent tx ack to application-server"

but at yocoto: "backend/gateway: downlink tx acknowledgement received" first, and then "device-session saved" and "storage: downlink-frame saved", and lost other to log

I try to add delay at gateway forward for sending tx ack(like 500ms), then work normal. So, what happened?

ubuntu:

12月 24 14:21:06 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:06+08:00" level=info msg="finished unary call with code OK" ctx_id=47d1122a-9853-462a-9ec4-2fb311d516e3 grpc.code=OK grpc.method=GetNextDownlinkFCntForDevEUI grpc.service=ns.NetworkServerService grpc.start_time="2020-12-24T14:21:06+08:00" grpc.time_ms=1.345 peer.address="192.168.2.38:38932" span.kind=server system=grpc 12月 24 14:21:06 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:06+08:00" level=info msg="device-queue item created" ctx_id=7a8e9df1-0c2d-4aae-9cf7-05be2ff5d18d dev_eui=0751373245b35c35 f_cnt=102 12月 24 14:21:06 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:06+08:00" level=info msg="finished unary call with code OK" ctx_id=7a8e9df1-0c2d-4aae-9cf7-05be2ff5d18d grpc.code=OK grpc.method=CreateDeviceQueueItem grpc.service=ns.NetworkServerService grpc.start_time="2020-12-24T14:21:06+08:00" grpc.time_ms=13.048 peer.address="192.168.2.38:38932" span.kind=server system=grpc 12月 24 14:21:06 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:06+08:00" level=info msg="finished unary call with code OK" ctx_id=f22e0454-e60b-42bb-84ea-feb8c154995f grpc.code=OK grpc.method=GetDeviceQueueItemsForDevEUI grpc.service=ns.NetworkServerService grpc.start_time="2020-12-24T14:21:06+08:00" grpc.time_ms=1.205 peer.address="192.168.2.38:38932" span.kind=server system=grpc 12月 24 14:21:07 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:07+08:00" level=info msg="device-queue deleted" ctx_id=e5cb7ac7-91d9-4528-bc9c-43abff8a70ac id=124853 12月 24 14:21:07 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:07+08:00" level=info msg="gateway/mqtt: publishing gateway command" command=down downlink_id=e5cb7ac7-91d9-4528-bc9c-43abff8a70ac gateway_id=abcd7ca7b0626a22 qos=0 topic=gateway/abcd7ca7b0626a22/command/down 12月 24 14:21:07 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:07+08:00" level=info msg="device-session saved" ctx_id=e5cb7ac7-91d9-4528-bc9c-43abff8a70ac dev_addr=01beda24 dev_eui=0751373245b35c35 12月 24 14:21:07 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:07+08:00" level=info msg="storage: downlink-frame saved" ctx_id=e5cb7ac7-91d9-4528-bc9c-43abff8a70ac token=58827 12月 24 14:21:07 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:07+08:00" level=info msg="backend/gateway: downlink tx acknowledgement received" downlink_id=e5cb7ac7-91d9-4528-bc9c-43abff8a70ac gateway_id=abcd7ca7b0626a22 12月 24 14:21:07 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:07+08:00" level=info msg="sent downlink meta-data to network-controller" ctx_id=e5cb7ac7-91d9-4528-bc9c-43abff8a70ac 12月 24 14:21:07 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:07+08:00" level=info msg="finished client unary call" ctx_id=e5cb7ac7-91d9-4528-bc9c-43abff8a70ac grpc.code=OK grpc.ctx_id=dd37c094-908f-4e22-ad5d-eedeb7833c59 grpc.duration=4.029945ms grpc.method=HandleTxAck grpc.service=as.ApplicationServerService span.kind=client system=grpc 12月 24 14:21:07 iotivity-2ndPowerEdge-T30 chirpstack-network-server[7613]: time="2020-12-24T14:21:07+08:00" level=info msg="sent tx ack to application-server" ctx_id=e5cb7ac7-91d9-4528-bc9c-43abff8a70ac

imx yocto:

Dec 24 14:21:10 MiWiFi-R3-srv chirpstack-network-server[15194]: time="2020-12-24T14:21:10+08:00" level=info msg="finished unary call with code OK" ctx_id=fe8c17dc-446d-49ee-89fb-7b4c91f7015a grpc.code=OK grpc.method=GetNextDownlinkFCntForDevEUI grpc.service=ns.NetworkServerService grpc.start_time="2020-12-24T14:21:10+08:00" grpc.time_ms=12.968 peer.address="[::1]:51222" span.kind=server system=grpc Dec 24 14:21:10 MiWiFi-R3-srv chirpstack-network-server[15194]: time="2020-12-24T14:21:10+08:00" level=info msg="device-queue item created" ctx_id=52eaf53a-0c90-43c4-b662-73cb6114a9d7 dev_eui=0a51373253b35535 f_cnt=72 Dec 24 14:21:10 MiWiFi-R3-srv chirpstack-network-server[15194]: time="2020-12-24T14:21:10+08:00" level=info msg="finished unary call with code OK" ctx_id=52eaf53a-0c90-43c4-b662-73cb6114a9d7 grpc.code=OK grpc.method=CreateDeviceQueueItem grpc.service=ns.NetworkServerService grpc.start_time="2020-12-24T14:21:10+08:00" grpc.time_ms=31.947 peer.address="[::1]:51222" span.kind=server system=grpc Dec 24 14:21:10 MiWiFi-R3-srv chirpstack-network-server[15194]: time="2020-12-24T14:21:10+08:00" level=info msg="finished unary call with code OK" ctx_id=2f0476f1-6523-403e-a34d-6e785f63ad89 grpc.code=OK grpc.method=GetDeviceQueueItemsForDevEUI grpc.service=ns.NetworkServerService grpc.start_time="2020-12-24T14:21:10+08:00" grpc.time_ms=12.436 peer.address="[::1]:51222" span.kind=server system=grpc Dec 24 14:21:10 MiWiFi-R3-srv chirpstack-network-server[15194]: time="2020-12-24T14:21:10+08:00" level=info msg="device-queue deleted" ctx_id=e55c008c-3b62-40fe-ae1f-6b2ca2a2dc8e id=73 Dec 24 14:21:10 MiWiFi-R3-srv chirpstack-network-server[15194]: time="2020-12-24T14:21:10+08:00" level=info msg="gateway/mqtt: publishing gateway command" command=down downlink_id=e55c008c-3b62-40fe-ae1f-6b2ca2a2dc8e gateway_id=abcd1c88795460dc qos=0 topic=gateway/abcd1c88795460dc/command/down Dec 24 14:21:10 MiWiFi-R3-srv chirpstack-network-server[15194]: time="2020-12-24T14:21:10+08:00" level=info msg="backend/gateway: downlink tx acknowledgement received" downlink_id=e55c008c-3b62-40fe-ae1f-6b2ca2a2dc8e gateway_id=abcd1c88795460dc Dec 24 14:21:10 MiWiFi-R3-srv chirpstack-network-server[15194]: time="2020-12-24T14:21:10+08:00" level=info msg="device-session saved" ctx_id=e55c008c-3b62-40fe-ae1f-6b2ca2a2dc8e dev_addr=00b0c5d7 dev_eui=0a51373253b35535 Dec 24 14:21:10 MiWiFi-R3-srv chirpstack-network-server[15194]: time="2020-12-24T14:21:10+08:00" level=info msg="storage: downlink-frame saved" ctx_id=e55c008c-3b62-40fe-ae1f-6b2ca2a2dc8e token=58716