reported by alpha testers in 1.1.20: blank messages appear occasionally when idle

[geeksville]

see the thread for details. I just reproed

[geeksville]

This issue has been mentioned on Meshtastic. There might be relevant details there:

https://meshtastic.discourse.group/t/want-to-help-alpha-test-this-build-1-1-20/1862/58

[geeksville]

Possibly related:

When I send a message from the local node, and I see the ack arrive from the remote node. That ack isn't showing up in the app % of the time as a "received checkbox". from the log we can see the local device receive the ack and then tell the phone about it

Log from the local node (remote was fine)

Battery: usbPower=1, isCharging=1, batMv=4191, batPct=100
Trigger powerFSM 9
PACKET FROM PHONE (id=0xf7a733bf Fr0x98 To0xff, WantAck1, HopLim0 Portnum=1)
Update DB node 0xabf84098, rx_time=1608083594
Received text msg from=0xabf84098, id=-140037185, msg=morn????
Trigger powerFSM 5
showing standard frames
Setting fast framerate
Plugin text handled=0
FIXME not implemented addRoute
FIXME-update-db Sniffing packet
Delivering rx packet (id=0xf7a733bf Fr0x98 To0xff, WantAck1, HopLim0 Portnum=1 rxtime=1608083594)
Trigger powerFSM 3
Forwarding to phone (id=0xf7a733bf Fr0x98 To0xff, WantAck1, HopLim0 Portnum=1 rxtime=1608083594)
Update DB node 0xabf84098, rx_time=1608083594
Received text msg from=0xabf84098, id=-140037185, msg=morn????
Trigger powerFSM 5
showing standard frames
Setting fast framerate
Plugin text handled=0
Adding packet record (id=0xf7a733bf Fr0x98 To0xff, WantAck1, HopLim1 Portnum=1 rxtime=1608083594)
enqueuing for send (id=0xf7a733bf Fr0x98 To0xff, WantAck0, HopLim1 encrypted rxtime=1608083594)
(bw=125, sf=12, cr=4/8) packet symLen=32 ms, payloadSize=30, time 3022 ms
txGood=218,rxGood=199,rxBad=0
Setting idle framerate
getFromRadio, state=6
phone downloaded packet (id=0xf7a733bf Fr0x98 To0xff, WantAck1, HopLim0 Portnum=1 rxtime=1608083594)
encoding toPhone packet to phone variant=2, 43 bytes
DEBUG:root:Received: {'packet': {'from': 2885173400, 'to': 4294967295, 'decoded': {'data': {'portnum': 'TEXT_MESSAGE_APP', 'payload': 'bW9ybvCfpbA='}}, 'id': 4154930111, 'rxTime': 1608083594, 'wantAck': True}}
WARNING:root:Node not found for fromId
DEBUG:root:Publishing topic meshtastic.receive.text
Received: {'from': 2885173400, 'to': 4294967295, 'decoded': {'data': {'portnum': 'TEXT_MESSAGE_APP', 'payload': b'morn\xf0\x9f\xa5\xb0', 'text': 'morn🥰'}}, 'id': 4154930111, 'rxTime': 1608083594, 'wantAck': True, 'fromId': None, 'toId': '^all'}
Telling client we have new packets 149
Telling client we have new packets 149
BLE notify fromNum
BLE reading a uint32
BLE fromRadio called omlen=1, ourlen=0
Starting low level send (id=0xf7a733bf Fr0x98 To0xff, WantAck0, HopLim1 encrypted rxtime=1608083594)
Completed sending (id=0xf7a733bf Fr0x98 To0xff, WantAck0, HopLim1 encrypted rxtime=1608083594)
Lora RX (id=0xf7a733bf Fr0x98 To0xff, WantAck0, HopLim0 encrypted rxSNR=10)
Rx someone rebroadcasting for us (id=0xf7a733bf Fr0x98 To0xff, WantAck0, HopLim0 encrypted rxSNR=10)
Someone is retransmitting for us, generate implicit ack
Sending an ack=0x1,to=0xabf84098,idFrom=0xf7a733bf,id=0x77a733c1
Enqueuing local (id=0x77a733c1 Fr0x98 To0x98, WantAck0, HopLim0 Payload:None successId=f7a733bf rxtime=1608083602)
Found existing packet record for fr=0xabf84098,to=0xffffffff,id=-140037185
Ignoring incoming msg, because we've already seen it: fr=0xabf84098,to=0xffffffff,id=-140037185,hop_limit=0
Adding packet record (id=0x77a733c1 Fr0x98 To0x98, WantAck0, HopLim0 Payload:None successId=f7a733bf rxtime=1608083602)
FIXME not implemented addRoute
Received a ack=-140037185, stopping retransmissions
FIXME-update-db Sniffing packet
Delivering rx packet (id=0x77a733c1 Fr0x98 To0x98, WantAck0, HopLim0 Payload:None successId=f7a733bf rxtime=1608083602)
Trigger powerFSM 3
Forwarding to phone (id=0x77a733c1 Fr0x98 To0x98, WantAck0, HopLim0 Payload:None successId=f7a733bf rxtime=1608083602)
Update DB node 0xabf84098, rx_time=1608083602
Node status update: 1 online, 3 total
getFromRadio, state=6
phone downloaded packet (id=0x77a733c1 Fr0x98 To0x98, WantAck0, HopLim0 Payload:None successId=f7a733bf rxtime=1608083602)
encoding toPhone packet to phone variant=2, 33 bytes
DEBUG:root:Received: {'packet': {'from': 2885173400, 'to': 2885173400, 'decoded': {'successId': 4154930111}, 'id': 2007446465, 'rxTime': 1608083602}}
WARNING:root:Node not found for fromId
WARNING:root:Node not found for fromId
DEBUG:root:Publishing topic meshtastic.receive
Received: {'from': 2885173400, 'to': 2885173400, 'decoded': {'successId': 4154930111}, 'id': 2007446465, 'rxTime': 1608083602, 'fromId': None, 'toId': None}
Telling client we have new packets 150
Telling client we have new packets 150
BLE notify fromNum
BLE reading a uint32
BLE fromRadio called omlen=1, ourlen=0
Battery: usbPower=1, isCharging=1, batMv=4191, batPct=100

[geeksville]

oops - the next 'bug' I mentioned above was not a bug, it was a side effect of python tool disabling BLE api.

[geeksville]

Cause was: since all data types are now provided by the android service, the messaging app needs to filter out and only consider text messages.

[geeksville]

Fixed in Android