search

Tanganelli / CoAPthon

CoAPthon is a python library to the CoAP protocol aligned with the RFC
MIT License
221 stars 130 forks source link

sleepy node (mostly staying in low power, radio not reachable) sending duplicate messages #161

Closed anesh1992 closed 4 years ago

anesh1992 commented 4 years ago

Hi, I have a sleepy node running Coap clients(based on Contiki) that sends data every 5 minutes and stays in low power mode (node not reachable), when it wakes up, seems like it sends data again and again to the Coapthon server, there are multiple transactions. That's not good for a node running on battery. Any idea, why that might be happening? Any pointer will be helpful, for now, I have no idea where to look.

2020-05-28 12:23:05,546 - MainThread - coapthon.server.coap - DEBUG - receive_datagram - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ] 

 msg no: 435    temp:...27 bytes
2020-05-28 12:23:05,550 - MainThread - coapthon.layers.messagelayer - DEBUG - receive_request - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ] 

 msg no: 435    temp:...27 bytes
397.165278 2020-05-28 12:23:05.564582
From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ] 

 msg no: 435    temp:...27 bytes 

 msg no: 435    temp: 21.6

2020-05-28 12:23:05,577 - Thread-475 - coapthon.server.coap - DEBUG - Notify
2020-05-28 12:23:05,584 - Thread-475 - coapthon.layers.messagelayer - DEBUG - send_response - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), None-None, CHANGED-None, [] 0...1 bytes
2020-05-28 12:23:05,591 - Thread-475 - coapthon.server.coap - DEBUG - send_datagram - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), ACK-63918, CHANGED-None, [] 0...1 bytes
2020-05-28 12:23:09,099 - MainThread - coapthon.server.coap - DEBUG - receive_datagram - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ] 

 msg no: 435    temp:...27 bytes
2020-05-28 12:23:09,103 - MainThread - coapthon.layers.messagelayer - DEBUG - receive_request - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ] 

 msg no: 435    temp:...27 bytes
2020-05-28 12:23:09,108 - MainThread - coapthon.server.coap - DEBUG - message duplicated, transaction completed
2020-05-28 12:23:09,113 - MainThread - coapthon.server.coap - DEBUG - send_datagram - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), ACK-63918, CHANGED-None, [] 0...1 bytes
2020-05-28 12:23:16,361 - MainThread - coapthon.server.coap - DEBUG - receive_datagram - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ] 

 msg no: 435    temp:...27 bytes
2020-05-28 12:23:16,366 - MainThread - coapthon.layers.messagelayer - DEBUG - receive_request - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ] 

 msg no: 435    temp:...27 bytes
2020-05-28 12:23:16,370 - MainThread - coapthon.server.coap - DEBUG - message duplicated, transaction completed
2020-05-28 12:23:16,375 - MainThread - coapthon.server.coap - DEBUG - send_datagram - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), ACK-63918, CHANGED-None, [] 0...1 bytes
2020-05-28 12:23:30,704 - MainThread - coapthon.server.coap - DEBUG - receive_datagram - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ] 

 msg no: 435    temp:...27 bytes
2020-05-28 12:23:30,710 - MainThread - coapthon.layers.messagelayer - DEBUG - receive_request - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ] 

 msg no: 435    temp:...27 bytes
2020-05-28 12:23:30,715 - MainThread - coapthon.server.coap - DEBUG - message duplicated, transaction completed
2020-05-28 12:23:30,721 - MainThread - coapthon.server.coap - DEBUG - send_datagram - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), ACK-63918, CHANGED-None, [] 0...1 bytes
2020-05-28 12:24:56,814 - MainThread - coapthon.server.coap - DEBUG - receive_datagram - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ] 

 msg no: 435    temp:...27 bytes
2020-05-28 12:24:56,819 - MainThread - coapthon.layers.messagelayer - DEBUG - receive_request - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ] 

 msg no: 435    temp:...27 bytes
2020-05-28 12:24:56,824 - MainThread - coapthon.server.coap - DEBUG - message duplicated, transaction completed
2020-05-28 12:24:56,829 - MainThread - coapthon.server.coap - DEBUG - send_datagram - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), ACK-63918, CHANGED-None, [] 0...1 bytes

Thanks & Regards -Anesh

Tanganelli commented 4 years ago

Hi, I think the problem is in contiki, not in coapthon... Maybe you can check with wireshark the messages actually exchanged, from the logs it seems everything ok from the coapthon side.

Il giorno gio 28 mag 2020 alle ore 18:46 anesh1992 notifications@github.com ha scritto:

Hi, I have a sleepy node running Coap clients(based on Contiki) that sends data every 5 minutes and stays in low power mode (node not reachable), when it wakes up, seems like it sends data again and again to the Coapthon server, there are multiple transactions. That's not good for a node running on battery. Any idea, why that might be happening? Any pointer will be helpful, for now, I have no idea where to look.

2020-05-28 12:23:05,546 - MainThread - coapthon.server.coap - DEBUG - receive_datagram - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ]

msg no: 435 temp:...27 bytes 2020-05-28 12:23:05,550 - MainThread - coapthon.layers.messagelayer - DEBUG - receive_request - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ]

msg no: 435 temp:...27 bytes 397.165278 2020-05-28 12:23:05.564582 From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ]

msg no: 435 temp:...27 bytes

msg no: 435 temp: 21.6

2020-05-28 12:23:05,577 - Thread-475 - coapthon.server.coap - DEBUG - Notify 2020-05-28 12:23:05,584 - Thread-475 - coapthon.layers.messagelayer - DEBUG - send_response - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), None-None, CHANGED-None, [] 0...1 bytes 2020-05-28 12:23:05,591 - Thread-475 - coapthon.server.coap - DEBUG - send_datagram - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), ACK-63918, CHANGED-None, [] 0...1 bytes 2020-05-28 12:23:09,099 - MainThread - coapthon.server.coap - DEBUG - receive_datagram - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ]

msg no: 435 temp:...27 bytes 2020-05-28 12:23:09,103 - MainThread - coapthon.layers.messagelayer - DEBUG - receive_request - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ]

msg no: 435 temp:...27 bytes 2020-05-28 12:23:09,108 - MainThread - coapthon.server.coap - DEBUG - message duplicated, transaction completed 2020-05-28 12:23:09,113 - MainThread - coapthon.server.coap - DEBUG - send_datagram - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), ACK-63918, CHANGED-None, [] 0...1 bytes 2020-05-28 12:23:16,361 - MainThread - coapthon.server.coap - DEBUG - receive_datagram - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ]

msg no: 435 temp:...27 bytes 2020-05-28 12:23:16,366 - MainThread - coapthon.layers.messagelayer - DEBUG - receive_request - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ]

msg no: 435 temp:...27 bytes 2020-05-28 12:23:16,370 - MainThread - coapthon.server.coap - DEBUG - message duplicated, transaction completed 2020-05-28 12:23:16,375 - MainThread - coapthon.server.coap - DEBUG - send_datagram - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), ACK-63918, CHANGED-None, [] 0...1 bytes 2020-05-28 12:23:30,704 - MainThread - coapthon.server.coap - DEBUG - receive_datagram - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ]

msg no: 435 temp:...27 bytes 2020-05-28 12:23:30,710 - MainThread - coapthon.layers.messagelayer - DEBUG - receive_request - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ]

msg no: 435 temp:...27 bytes 2020-05-28 12:23:30,715 - MainThread - coapthon.server.coap - DEBUG - message duplicated, transaction completed 2020-05-28 12:23:30,721 - MainThread - coapthon.server.coap - DEBUG - send_datagram - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), ACK-63918, CHANGED-None, [] 0...1 bytes 2020-05-28 12:24:56,814 - MainThread - coapthon.server.coap - DEBUG - receive_datagram - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ]

msg no: 435 temp:...27 bytes 2020-05-28 12:24:56,819 - MainThread - coapthon.layers.messagelayer - DEBUG - receive_request - From ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), To None, CON-63918, POST-None, [Uri-Path: ad_temp_to_dir, ]

msg no: 435 temp:...27 bytes 2020-05-28 12:24:56,824 - MainThread - coapthon.server.coap - DEBUG - message duplicated, transaction completed 2020-05-28 12:24:56,829 - MainThread - coapthon.server.coap - DEBUG - send_datagram - From None, To ('XXXX::XXX:XXXX:XXXX:XXXX', 5683), ACK-63918, CHANGED-None, [] 0...1 bytes

Thanks & Regards -Anesh

— You are receiving this because you are subscribed to this thread. Reply to this email directly, view it on GitHub https://github.com/Tanganelli/CoAPthon/issues/161, or unsubscribe https://github.com/notifications/unsubscribe-auth/ABTPN6RBYTLLOIPRYLVQYTTRT2IOTANCNFSM4NNH7DXQ .

anesh1992 commented 4 years ago

Hi, Thank you for showing direction, I will verify by on Wireshark.