search

meshtastic / firmware

Meshtastic device firmware
https://meshtastic.org
GNU General Public License v3.0
3.32k stars 804 forks source link

[Bug]: gps coordinates does not change #2868

Closed NickDolfin closed 11 months ago

NickDolfin commented 11 months ago

Category

Other

Hardware

T-Beam

Firmware Version

2.2.10.7cebd79

Description

I have a Tbeam T22_V1.1 / 20210222.

After aquiring a gps coordinate, it will not change anymore. gps update interval: 30s send out over mesh: 180s fix attempt duration: 900s

Any idea?

Relevant log output

No response

jp-bennett commented 11 months ago

Can you capture a log output using Meshtastic --noproto? Need to see what happens when it tries to get an updated GPS lock.

NickDolfin commented 11 months ago

C:\Users\nick\Desktop\Research\electronics\Lora\meshtastic> Meshtastic --noproto WARNING file:mesh_interface.py _sendToRadio line:680 Not sending packet because protocol use is disabled by noProto Connected to radio WARNING file:mesh_interface.py _sendPacket line:530 Not sending packet because protocol use is disabled by noProto INFO | 21:24:55 2513 [DeviceTelemetryModule] (Sending): air_util_tx=1.205278, channel_utilization=0.000000, battery_level=100, voltage=4.153000 DEBUG | 21:24:55 2513 [DeviceTelemetryModule] updateTelemetry LOCAL DEBUG | 21:24:55 2513 [DeviceTelemetryModule] Node status update: 2 online, 2 total INFO | 21:24:55 2513 [DeviceTelemetryModule] Sending packet to mesh DEBUG | 21:24:55 2513 [DeviceTelemetryModule] Update DB node 0xf71ea694, rx_time=1696541095, channel=0 DEBUG | 21:24:55 2513 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x25e0c71f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696541095 priority=1) DEBUG | 21:24:55 2513 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL DEBUG | 21:24:55 2513 [DeviceTelemetryModule] localSend to channel 0 DEBUG | 21:24:55 2513 [DeviceTelemetryModule] Add packet record (id=0x25e0c71f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696541095 priority=1) DEBUG | 21:24:55 2513 [DeviceTelemetryModule] Expanding short PSK #1 DEBUG | 21:24:55 2513 [DeviceTelemetryModule] Using AES128 key! DEBUG | 21:24:55 2513 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=25e0c71f, numBytes=23! DEBUG | 21:24:55 2513 [DeviceTelemetryModule] enqueuing for send (id=0x25e0c71f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696541095 priority=1) DEBUG | 21:24:55 2513 [DeviceTelemetryModule] txGood=59,rxGood=59,rxBad=0 DEBUG | 21:24:55 2513 [DeviceTelemetryModule] NOTE: tophone queue status queue is full, discarding oldest WARN | 21:24:55 2513 [DeviceTelemetryModule] ToPhone queue is full, discarding oldest DEBUG | 21:24:55 2513 [DeviceTelemetryModule] Using channel 0 (hash 0x8) DEBUG | 21:24:55 2513 [DeviceTelemetryModule] Expanding short PSK #1 DEBUG | 21:24:55 2513 [DeviceTelemetryModule] Using AES128 key! DEBUG | 21:24:55 2513 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=25e0c71f, numBytes=23! DEBUG | 21:24:56 2513 [DeviceTelemetryModule] decoded message (id=0x25e0c71f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696541095 priority=1) DEBUG | 21:24:56 2513 [RadioIf] Starting low level send (id=0x25e0c71f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696541095 priority=1) DEBUG | 21:24:56 2513 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=39, time 698 ms DEBUG | 21:24:56 2513 [RadioIf] AirTime - Packet transmitted : 698ms DEBUG | 21:24:56 2514 [RadioIf] Completed sending (id=0x25e0c71f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696541095 priority=1) DEBUG | 21:24:59 2517 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=39, time 698 ms DEBUG | 21:24:59 2517 [RadioIf] Lora RX (id=0x25e0c71f fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9 rxRSSI=-51) DEBUG | 21:24:59 2517 [RadioIf] AirTime - Packet received : 698ms DEBUG | 21:24:59 2517 [Router] Rx someone rebroadcasting for us (id=0x25e0c71f fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9 rxRSSI=-51) DEBUG | 21:24:59 2517 [Router] didn't find pending packet DEBUG | 21:24:59 2517 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x25e0c71f DEBUG | 21:24:59 2517 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x25e0c71f DEBUG | 21:24:59 2517 [Router] Add packet record (id=0x25e0c71f fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9 rxRSSI=-51) DEBUG | 21:24:59 2517 [Router] Ignoring incoming msg, because we've already seen it (id=0x25e0c71f fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9 rxRSSI=-51) DEBUG | 21:24:59 2517 [Router] cancelSending id=0x25e0c71f, removed=0 DEBUG | 21:24:59 2517 [Router] Incoming message was filtered 0xf71ea694 DEBUG | 21:25:03 2520 [GPS] WANT GPS=1 INFO | 21:25:03 2520 [GPS] Setting GPS power=1 WARN | 21:25:03 2520 [GPS] SOME data is TOO OLD: LOC 30052, TIME 30052, DATE 30052 WARN | 21:25:03 2520 [GPS] SOME data is TOO OLD: LOC 30259, TIME 30259, DATE 30259 WARN | 21:25:03 2521 [GPS] SOME data is TOO OLD: LOC 30478, TIME 30478, DATE 30478 WARN | 21:25:03 2521 [GPS] SOME data is TOO OLD: LOC 30696, TIME 30696, DATE 30696 DEBUG | 21:25:03 2521 [GPS] WANT GPS=0 DEBUG | 21:25:03 2521 [GPS] GPS Lock took 0, average 1 INFO | 21:25:03 2521 [GPS] Setting GPS power=0 DEBUG | 21:25:03 2521 [GPS] publishing pos@651f29b0:2, hasVal=1, Sats=5, GPSlock=1 DEBUG | 21:25:03 2521 [GPS] onGPSChanged() pos@651f29b0, time=1696541103, lat=52xxxxxxx, lon=56xxxxxx, alt=25 INFO | 21:25:03 2521 [GPS] updatePosition LOCAL pos@651f29b0, time=1696541103, latI=52xxxxxxx, lonI=56xxxxxx, alt=25 DEBUG | 21:25:03 2521 [GPS] Node status update: 2 online, 2 total DEBUG | 21:25:06 2523 [Power] Battery: usbPower=1, isCharging=0, batMv=4155, batPct=100 INFO | 21:25:16 2533 [PowerFSM] Turning off screen DEBUG | 21:25:26 2543 [Power] Battery: usbPower=1, isCharging=0, batMv=4155, batPct=100 DEBUG | 21:25:33 2551 [GPS] WANT GPS=1 INFO | 21:25:33 2551 [GPS] Setting GPS power=1 WARN | 21:25:33 2551 [GPS] SOME data is TOO OLD: LOC 30052, TIME 30052, DATE 30052 WARN | 21:25:34 2551 [GPS] SOME data is TOO OLD: LOC 30259, TIME 30259, DATE 30259 WARN | 21:25:34 2551 [GPS] SOME data is TOO OLD: LOC 30477, TIME 30477, DATE 30477 WARN | 21:25:34 2552 [GPS] SOME data is TOO OLD: LOC 30696, TIME 30696, DATE 30696 DEBUG | 21:25:34 2552 [GPS] WANT GPS=0 DEBUG | 21:25:34 2552 [GPS] GPS Lock took 0, average 1 INFO | 21:25:34 2552 [GPS] Setting GPS power=0 DEBUG | 21:25:34 2552 [GPS] publishing pos@651f29cf:2, hasVal=1, Sats=5, GPSlock=1 DEBUG | 21:25:34 2552 [GPS] onGPSChanged() pos@651f29cf, time=1696541134, lat=52xxxxxxx, lon=56xxxxxx, alt=25 INFO | 21:25:34 2552 [GPS] updatePosition LOCAL pos@651f29cf, time=1696541134, latI=52xxxxxxx, lonI=56xxxxxx, alt=25 DEBUG | 21:25:34 2552 [GPS] Node status update: 2 online, 2 total Traceback (most recent call last): File "", line 198, in _run_module_as_main File "", line 88, in _run_code File "C:\Users\nick\AppData\Roaming\Python\Python311\Scripts\meshtastic.exe__main.py", line 7, in File "C:\Users\nick\AppData\Roaming\Python\Python311\site-packages\meshtastic\main.py", line 1351, in main common() File "C:\Users\nick\AppData\Roaming\Python\Python311\site-packages\meshtastic\main__.py", line 975, in common time.sleep(1000) KeyboardInterrupt ^C C:\Users\nick\Desktop\Research\electronics\Lora\meshtastic>

NickDolfin commented 11 months ago

above is from 2.2.9 I downgraded, but it seems to have a similar result. And put some xx in the coordinates.

NickDolfin commented 11 months ago

and now with 2.2.10:

Connected to radio DEBUG | 21:48:30 41 [Power] Battery: usbPower=1, isCharging=0, batMv=4155, batPct=100

DEBUG | 21:48:33 45 [GPS] WANT GPS=1

INFO | 21:48:33 45 [GPS] Setting GPS power=1

WARN | 21:48:33 45 [GPS] SOME data is TOO OLD: LOC 30426, TIME 30426, DATE 30430

WARN | 21:48:34 45 [GPS] SOME data is TOO OLD: LOC 30634, TIME 30634, DATE 30638

WARN | 21:48:34 45 [GPS] SOME data is TOO OLD: LOC 30853, TIME 30853, DATE 30857

WARN | 21:48:34 46 [GPS] SOME data is TOO OLD: LOC 31072, TIME 31072, DATE 31076

WARN | 21:48:34 46 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:48:34 46 [GPS] WANT GPS=0

DEBUG | 21:48:34 46 [GPS] GPS Lock took 0, average 0

INFO | 21:48:34 46 [GPS] Setting GPS power=0

DEBUG | 21:48:34 46 [GPS] publishing pos@651f2f32:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:48:34 46 [GPS] onGPSChanged() pos@651f2f32, time=1696542514, lat=52x, lon=56x, alt=30

INFO | 21:48:34 46 [GPS] updatePosition LOCAL pos@651f2f32, time=1696542514, latI=52x, lonI=56x, alt=30

DEBUG | 21:48:34 46 [GPS] Node status update: 2 online, 2 total

INFO | 21:48:35 46 [DeviceTelemetryModule] (Sending): air_util_tx=0.047639, channel_utilization=8.343333, battery_level=100, voltage=4.155000

DEBUG | 21:48:35 46 [DeviceTelemetryModule] updateTelemetry LOCAL

DEBUG | 21:48:35 46 [DeviceTelemetryModule] Node status update: 2 online, 2 total

INFO | 21:48:35 46 [DeviceTelemetryModule] Sending packet to mesh

DEBUG | 21:48:35 46 [DeviceTelemetryModule] Update DB node 0xf71ea694, rx_time=1696542515, channel=0

DEBUG | 21:48:35 46 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x450da00a fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542515 priority=1)

DEBUG | 21:48:35 46 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL

DEBUG | 21:48:35 46 [DeviceTelemetryModule] localSend to channel 0

DEBUG | 21:48:35 46 [DeviceTelemetryModule] Add packet record (id=0x450da00a fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542515 priority=1)

DEBUG | 21:48:35 46 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:48:35 46 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:48:35 46 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da00a, numBytes=28!

DEBUG | 21:48:35 46 [DeviceTelemetryModule] enqueuing for send (id=0x450da00a fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542515 priority=1)

DEBUG | 21:48:35 46 [DeviceTelemetryModule] txGood=2,rxGood=4,rxBad=0

DEBUG | 21:48:35 46 [DeviceTelemetryModule] Using channel 0 (hash 0x8)

DEBUG | 21:48:35 46 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:48:35 46 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:48:35 46 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da00a, numBytes=28!

DEBUG | 21:48:35 46 [DeviceTelemetryModule] decoded message (id=0x450da00a fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542515 priority=1)

DEBUG | 21:48:35 46 [RadioIf] Starting low level send (id=0x450da00a fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542515 priority=1)

DEBUG | 21:48:35 46 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=44, time 755 ms

DEBUG | 21:48:35 46 [RadioIf] AirTime - Packet transmitted : 755ms

DEBUG | 21:48:36 47 [RadioIf] Completed sending (id=0x450da00a fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542515 priority=1)

DEBUG | 21:48:42 53 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=44, time 755 ms

DEBUG | 21:48:42 53 [RadioIf] Lora RX (id=0x450da00a fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.25 rxRSSI=-54)

DEBUG | 21:48:42 53 [RadioIf] AirTime - Packet received : 755ms

DEBUG | 21:48:42 53 [Router] Rx someone rebroadcasting for us (id=0x450da00a fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.25 rxRSSI=-54)

DEBUG | 21:48:42 53 [Router] didn't find pending packet

DEBUG | 21:48:42 53 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00a

DEBUG | 21:48:42 53 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00a

DEBUG | 21:48:42 53 [Router] Add packet record (id=0x450da00a fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.25 rxRSSI=-54)

DEBUG | 21:48:42 53 [Router] Ignoring incoming msg, because we've already seen it (id=0x450da00a fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.25 rxRSSI=-54)

DEBUG | 21:48:42 53 [Router] cancelSending id=0x450da00a, removed=0

DEBUG | 21:48:42 53 [Router] Incoming message was filtered 0xf71ea694

DEBUG | 21:48:50 61 [Power] Battery: usbPower=1, isCharging=0, batMv=4156, batPct=100

DEBUG | 21:49:04 76 [GPS] WANT GPS=1

INFO | 21:49:04 76 [GPS] Setting GPS power=1

WARN | 21:49:04 76 [GPS] SOME data is TOO OLD: LOC 30057, TIME 30057, DATE 30057

WARN | 21:49:05 76 [GPS] SOME data is TOO OLD: LOC 30265, TIME 30265, DATE 30265

WARN | 21:49:05 76 [GPS] SOME data is TOO OLD: LOC 30484, TIME 30484, DATE 30484

WARN | 21:49:05 76 [GPS] SOME data is TOO OLD: LOC 30703, TIME 30703, DATE 30703

WARN | 21:49:05 77 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:49:05 77 [GPS] WANT GPS=0

DEBUG | 21:49:05 77 [GPS] GPS Lock took 0, average 0

INFO | 21:49:05 77 [GPS] Setting GPS power=0

DEBUG | 21:49:05 77 [GPS] publishing pos@651f2f51:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:49:05 77 [GPS] onGPSChanged() pos@651f2f51, time=1696542545, lat=52x, lon=56x, alt=30

INFO | 21:49:05 77 [GPS] updatePosition LOCAL pos@651f2f51, time=1696542545, latI=52x, lonI=56x, alt=30

DEBUG | 21:49:05 77 [GPS] Node status update: 2 online, 2 total

DEBUG | 21:49:10 81 [Power] Battery: usbPower=1, isCharging=0, batMv=4155, batPct=100

Connected to radio INFO | 21:49:35 106 [DeviceTelemetryModule] (Sending): air_util_tx=0.089583, channel_utilization=3.775000, battery_level=100, voltage=4.156000

DEBUG | 21:49:35 106 [DeviceTelemetryModule] updateTelemetry LOCAL

DEBUG | 21:49:35 106 [DeviceTelemetryModule] Node status update: 2 online, 2 total

INFO | 21:49:35 106 [DeviceTelemetryModule] Sending packet to mesh

DEBUG | 21:49:35 106 [DeviceTelemetryModule] Update DB node 0xf71ea694, rx_time=1696542575, channel=0

DEBUG | 21:49:35 106 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x450da00b fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542575 priority=1)

DEBUG | 21:49:35 106 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL

DEBUG | 21:49:35 106 [DeviceTelemetryModule] localSend to channel 0

DEBUG | 21:49:35 106 [DeviceTelemetryModule] Add packet record (id=0x450da00b fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542575 priority=1)

DEBUG | 21:49:35 106 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:49:35 106 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:49:35 106 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da00b, numBytes=28!

DEBUG | 21:49:35 106 [DeviceTelemetryModule] enqueuing for send (id=0x450da00b fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542575 priority=1)

DEBUG | 21:49:35 106 [DeviceTelemetryModule] txGood=4,rxGood=6,rxBad=0

DEBUG | 21:49:35 106 [DeviceTelemetryModule] Using channel 0 (hash 0x8)

DEBUG | 21:49:35 106 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:49:35 106 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:49:35 106 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da00b, numBytes=28!

DEBUG | 21:49:35 106 [DeviceTelemetryModule] decoded message (id=0x450da00b fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542575 priority=1)

DEBUG | 21:49:35 106 [RadioIf] Starting low level send (id=0x450da00b fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542575 priority=1)

DEBUG | 21:49:35 106 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=44, time 755 ms

DEBUG | 21:49:35 106 [RadioIf] AirTime - Packet transmitted : 755ms

DEBUG | 21:49:35 107 [GPS] WANT GPS=1

INFO | 21:49:35 107 [GPS] Setting GPS power=1

WARN | 21:49:35 107 [GPS] SOME data is TOO OLD: LOC 30058, TIME 30058, DATE 30058

WARN | 21:49:36 107 [GPS] SOME data is TOO OLD: LOC 30265, TIME 30265, DATE 30265

DEBUG | 21:49:36 107 [RadioIf] Completed sending (id=0x450da00b fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542575 priority=1)

WARN | 21:49:36 107 [GPS] SOME data is TOO OLD: LOC 30484, TIME 30484, DATE 30484

WARN | 21:49:36 107 [GPS] SOME data is TOO OLD: LOC 30703, TIME 30703, DATE 30703

WARN | 21:49:36 108 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:49:36 108 [GPS] WANT GPS=0

DEBUG | 21:49:36 108 [GPS] GPS Lock took 0, average 0

INFO | 21:49:36 108 [GPS] Setting GPS power=0

DEBUG | 21:49:36 108 [GPS] publishing pos@651f2f70:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:49:36 108 [GPS] onGPSChanged() pos@651f2f70, time=1696542576, lat=52x, lon=56x, alt=30

INFO | 21:49:36 108 [GPS] updatePosition LOCAL pos@651f2f70, time=1696542576, latI=52x, lonI=56x, alt=30

DEBUG | 21:49:36 108 [GPS] Node status update: 2 online, 2 total

DEBUG | 21:49:38 110 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=44, time 755 ms

DEBUG | 21:49:38 110 [RadioIf] Lora RX (id=0x450da00b fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.25 rxRSSI=-53)

DEBUG | 21:49:38 110 [RadioIf] AirTime - Packet received : 755ms

DEBUG | 21:49:38 110 [Router] Rx someone rebroadcasting for us (id=0x450da00b fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.25 rxRSSI=-53)

DEBUG | 21:49:38 110 [Router] didn't find pending packet

DEBUG | 21:49:38 110 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00b

DEBUG | 21:49:38 110 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00b

DEBUG | 21:49:38 110 [Router] Add packet record (id=0x450da00b fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.25 rxRSSI=-53)

DEBUG | 21:49:38 110 [Router] Ignoring incoming msg, because we've already seen it (id=0x450da00b fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.25 rxRSSI=-53)

DEBUG | 21:49:38 110 [Router] cancelSending id=0x450da00b, removed=0

DEBUG | 21:49:38 110 [Router] Incoming message was filtered 0xf71ea694

DEBUG | 21:49:50 121 [Power] Battery: usbPower=1, isCharging=0, batMv=4156, batPct=100

DEBUG | 21:50:06 138 [GPS] WANT GPS=1

INFO | 21:50:06 138 [GPS] Setting GPS power=1

WARN | 21:50:06 138 [GPS] SOME data is TOO OLD: LOC 30058, TIME 30058, DATE 30058

WARN | 21:50:06 138 [GPS] SOME data is TOO OLD: LOC 30265, TIME 30265, DATE 30265

WARN | 21:50:07 138 [GPS] SOME data is TOO OLD: LOC 30484, TIME 30484, DATE 30484

WARN | 21:50:07 138 [GPS] SOME data is TOO OLD: LOC 30703, TIME 30703, DATE 30703

WARN | 21:50:07 139 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:50:07 139 [GPS] WANT GPS=0

DEBUG | 21:50:07 139 [GPS] GPS Lock took 0, average 0

INFO | 21:50:07 139 [GPS] Setting GPS power=0

DEBUG | 21:50:07 139 [GPS] publishing pos@651f2f8f:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:50:07 139 [GPS] onGPSChanged() pos@651f2f8f, time=1696542607, lat=52x, lon=56x, alt=30

INFO | 21:50:07 139 [GPS] updatePosition LOCAL pos@651f2f8f, time=1696542607, latI=52x, lonI=56x, alt=30

DEBUG | 21:50:07 139 [GPS] Node status update: 2 online, 2 total

DEBUG | 21:50:10 141 [Power] Battery: usbPower=1, isCharging=0, batMv=4156, batPct=100

DEBUG | 21:50:30 161 [Power] Battery: usbPower=1, isCharging=0, batMv=4156, batPct=100

INFO | 21:50:35 166 [DeviceTelemetryModule] (Sending): air_util_tx=0.110556, channel_utilization=0.000000, battery_level=100, voltage=4.156000

DEBUG | 21:50:35 166 [DeviceTelemetryModule] updateTelemetry LOCAL

DEBUG | 21:50:35 166 [DeviceTelemetryModule] Node status update: 2 online, 2 total

INFO | 21:50:35 166 [DeviceTelemetryModule] Sending packet to mesh

DEBUG | 21:50:35 166 [DeviceTelemetryModule] Update DB node 0xf71ea694, rx_time=1696542635, channel=0

DEBUG | 21:50:35 166 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x450da00c fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542635 priority=1)

DEBUG | 21:50:35 166 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL

DEBUG | 21:50:35 166 [DeviceTelemetryModule] localSend to channel 0

DEBUG | 21:50:35 166 [DeviceTelemetryModule] Add packet record (id=0x450da00c fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542635 priority=1)

DEBUG | 21:50:35 166 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:50:35 166 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:50:35 166 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da00c, numBytes=23!

DEBUG | 21:50:35 166 [DeviceTelemetryModule] enqueuing for send (id=0x450da00c fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542635 priority=1)

DEBUG | 21:50:35 166 [DeviceTelemetryModule] txGood=5,rxGood=7,rxBad=0

DEBUG | 21:50:35 166 [DeviceTelemetryModule] Using channel 0 (hash 0x8)

DEBUG | 21:50:35 166 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:50:35 166 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:50:35 166 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da00c, numBytes=23!

DEBUG | 21:50:35 166 [DeviceTelemetryModule] decoded message (id=0x450da00c fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542635 priority=1)

DEBUG | 21:50:35 166 [RadioIf] Starting low level send (id=0x450da00c fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542635 priority=1)

DEBUG | 21:50:35 166 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=39, time 698 ms

DEBUG | 21:50:35 166 [RadioIf] AirTime - Packet transmitted : 698ms

DEBUG | 21:50:36 167 [RadioIf] Completed sending (id=0x450da00c fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542635 priority=1)

DEBUG | 21:50:37 169 [GPS] WANT GPS=1

INFO | 21:50:37 169 [GPS] Setting GPS power=1

WARN | 21:50:37 169 [GPS] SOME data is TOO OLD: LOC 30058, TIME 30058, DATE 30058

WARN | 21:50:37 169 [GPS] SOME data is TOO OLD: LOC 30265, TIME 30265, DATE 30265

WARN | 21:50:38 169 [GPS] SOME data is TOO OLD: LOC 30484, TIME 30484, DATE 30484

WARN | 21:50:38 169 [GPS] SOME data is TOO OLD: LOC 30703, TIME 30703, DATE 30703

WARN | 21:50:38 169 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:50:38 169 [GPS] WANT GPS=0

DEBUG | 21:50:38 169 [GPS] GPS Lock took 0, average 0

INFO | 21:50:38 169 [GPS] Setting GPS power=0

DEBUG | 21:50:38 169 [GPS] publishing pos@651f2fae:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:50:38 169 [GPS] onGPSChanged() pos@651f2fae, time=1696542638, lat=52x, lon=56x, alt=30

INFO | 21:50:38 169 [GPS] updatePosition LOCAL pos@651f2fae, time=1696542638, latI=52x, lonI=56x, alt=30

DEBUG | 21:50:38 169 [GPS] Node status update: 2 online, 2 total

DEBUG | 21:50:39 170 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=39, time 698 ms

DEBUG | 21:50:39 170 [RadioIf] Lora RX (id=0x450da00c fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9 rxRSSI=-53)

DEBUG | 21:50:39 170 [RadioIf] AirTime - Packet received : 698ms

DEBUG | 21:50:39 170 [Router] Rx someone rebroadcasting for us (id=0x450da00c fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9 rxRSSI=-53)

DEBUG | 21:50:39 170 [Router] didn't find pending packet

DEBUG | 21:50:39 170 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00c

DEBUG | 21:50:39 170 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00c

DEBUG | 21:50:39 170 [Router] Add packet record (id=0x450da00c fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9 rxRSSI=-53)

DEBUG | 21:50:39 170 [Router] Ignoring incoming msg, because we've already seen it (id=0x450da00c fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9 rxRSSI=-53)

DEBUG | 21:50:39 170 [Router] cancelSending id=0x450da00c, removed=0

DEBUG | 21:50:39 170 [Router] Incoming message was filtered 0xf71ea694

DEBUG | 21:50:50 181 [Power] Battery: usbPower=1, isCharging=0, batMv=4156, batPct=100

INFO | 21:51:05 196 [PositionModule] Sending pos@651f2fae:6 to mesh (wantReplies=0)

DEBUG | 21:51:05 196 [PositionModule] cancelSending id=0x450da008, removed=0

INFO | 21:51:05 196 [PositionModule] Providing time to mesh 1696542638

INFO | 21:51:05 196 [PositionModule] Position reply: time=1696542638, latI=52x, lonI=-56x

DEBUG | 21:51:05 196 [PositionModule] Update DB node 0xf71ea694, rx_time=1696542665, channel=0

DEBUG | 21:51:05 196 [PositionModule] handleReceived(LOCAL) (id=0x450da00d fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1696542665 priority=10)

DEBUG | 21:51:05 196 [PositionModule] No modules interested in portnum=3, src=LOCAL

DEBUG | 21:51:05 196 [PositionModule] localSend to channel 0

DEBUG | 21:51:05 196 [PositionModule] Add packet record (id=0x450da00d fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1696542665 priority=10)

DEBUG | 21:51:05 196 [PositionModule] Expanding short PSK #1

DEBUG | 21:51:05 196 [PositionModule] Using AES128 key!

DEBUG | 21:51:05 196 [PositionModule] ESP32 crypt fr=f71ea694, num=450da00d, numBytes=26!

DEBUG | 21:51:05 196 [PositionModule] enqueuing for send (id=0x450da00d fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542665 priority=10)

DEBUG | 21:51:05 196 [PositionModule] txGood=6,rxGood=8,rxBad=0

DEBUG | 21:51:05 196 [PositionModule] Using channel 0 (hash 0x8)

DEBUG | 21:51:05 196 [PositionModule] Expanding short PSK #1

DEBUG | 21:51:05 196 [PositionModule] Using AES128 key!

DEBUG | 21:51:05 196 [PositionModule] ESP32 crypt fr=f71ea694, num=450da00d, numBytes=26!

DEBUG | 21:51:05 196 [PositionModule] decoded message (id=0x450da00d fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1696542665 priority=10)

DEBUG | 21:51:05 196 [RadioIf] Starting low level send (id=0x450da00d fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542665 priority=10)

DEBUG | 21:51:05 196 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=42, time 739 ms

DEBUG | 21:51:05 196 [RadioIf] AirTime - Packet transmitted : 739ms

DEBUG | 21:51:05 197 [RadioIf] Completed sending (id=0x450da00d fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542665 priority=10)

DEBUG | 21:51:08 199 [GPS] WANT GPS=1

INFO | 21:51:08 199 [GPS] Setting GPS power=1

WARN | 21:51:08 199 [GPS] SOME data is TOO OLD: LOC 30058, TIME 30058, DATE 30058

DEBUG | 21:51:08 200 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=42, time 739 ms

DEBUG | 21:51:08 200 [RadioIf] Lora RX (id=0x450da00d fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=10.75 rxRSSI=-52)

DEBUG | 21:51:08 200 [RadioIf] AirTime - Packet received : 739ms

DEBUG | 21:51:08 200 [Router] Rx someone rebroadcasting for us (id=0x450da00d fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=10.75 rxRSSI=-52)

DEBUG | 21:51:08 200 [Router] didn't find pending packet

DEBUG | 21:51:08 200 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00d

DEBUG | 21:51:08 200 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00d

DEBUG | 21:51:08 200 [Router] Add packet record (id=0x450da00d fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=10.75 rxRSSI=-52)

DEBUG | 21:51:08 200 [Router] Ignoring incoming msg, because we've already seen it (id=0x450da00d fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=10.75 rxRSSI=-52)

DEBUG | 21:51:08 200 [Router] cancelSending id=0x450da00d, removed=0

DEBUG | 21:51:08 200 [Router] Incoming message was filtered 0xf71ea694

WARN | 21:51:08 200 [GPS] SOME data is TOO OLD: LOC 30265, TIME 30265, DATE 30265

WARN | 21:51:09 200 [GPS] SOME data is TOO OLD: LOC 30484, TIME 30484, DATE 30484

WARN | 21:51:09 200 [GPS] SOME data is TOO OLD: LOC 30703, TIME 30703, DATE 30703

WARN | 21:51:09 200 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:51:09 200 [GPS] WANT GPS=0

DEBUG | 21:51:09 200 [GPS] GPS Lock took 0, average 0

INFO | 21:51:09 200 [GPS] Setting GPS power=0

DEBUG | 21:51:09 200 [GPS] publishing pos@651f2fcd:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:51:09 200 [GPS] onGPSChanged() pos@651f2fcd, time=1696542669, lat=52x, lon=56x, alt=30

INFO | 21:51:09 200 [GPS] updatePosition LOCAL pos@651f2fcd, time=1696542669, latI=52x, lonI=56x, alt=30

DEBUG | 21:51:09 200 [GPS] Node status update: 2 online, 2 total

DEBUG | 21:51:10 201 [Power] Battery: usbPower=1, isCharging=0, batMv=4156, batPct=100

DEBUG | 21:51:30 221 [Power] Battery: usbPower=1, isCharging=0, batMv=4155, batPct=100

INFO | 21:51:35 226 [DeviceTelemetryModule] (Sending): air_util_tx=0.150472, channel_utilization=2.463333, battery_level=100, voltage=4.155000

DEBUG | 21:51:35 226 [DeviceTelemetryModule] updateTelemetry LOCAL

DEBUG | 21:51:35 226 [DeviceTelemetryModule] Node status update: 2 online, 2 total

INFO | 21:51:35 226 [DeviceTelemetryModule] Sending packet to mesh

DEBUG | 21:51:35 226 [DeviceTelemetryModule] Update DB node 0xf71ea694, rx_time=1696542695, channel=0

DEBUG | 21:51:35 226 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x450da00e fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542695 priority=1)

DEBUG | 21:51:35 226 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL

DEBUG | 21:51:35 226 [DeviceTelemetryModule] localSend to channel 0

DEBUG | 21:51:35 226 [DeviceTelemetryModule] Add packet record (id=0x450da00e fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542695 priority=1)

DEBUG | 21:51:35 226 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:51:35 226 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:51:35 226 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da00e, numBytes=28!

DEBUG | 21:51:35 226 [DeviceTelemetryModule] enqueuing for send (id=0x450da00e fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542695 priority=1)

DEBUG | 21:51:35 227 [DeviceTelemetryModule] txGood=7,rxGood=9,rxBad=0

DEBUG | 21:51:35 227 [DeviceTelemetryModule] Using channel 0 (hash 0x8)

DEBUG | 21:51:35 227 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:51:35 227 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:51:35 227 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da00e, numBytes=28!

DEBUG | 21:51:35 227 [DeviceTelemetryModule] decoded message (id=0x450da00e fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542695 priority=1)

DEBUG | 21:51:35 227 [RadioIf] Starting low level send (id=0x450da00e fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542695 priority=1)

DEBUG | 21:51:35 227 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=44, time 755 ms

DEBUG | 21:51:35 227 [RadioIf] AirTime - Packet transmitted : 755ms

DEBUG | 21:51:36 227 [RadioIf] Completed sending (id=0x450da00e fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542695 priority=1)

DEBUG | 21:51:38 230 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=44, time 755 ms

DEBUG | 21:51:38 230 [RadioIf] Lora RX (id=0x450da00e fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9.75 rxRSSI=-53)

DEBUG | 21:51:38 230 [RadioIf] AirTime - Packet received : 755ms

DEBUG | 21:51:38 230 [Router] Rx someone rebroadcasting for us (id=0x450da00e fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9.75 rxRSSI=-53)

DEBUG | 21:51:38 230 [Router] didn't find pending packet

DEBUG | 21:51:38 230 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00e

DEBUG | 21:51:38 230 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00e

DEBUG | 21:51:38 230 [Router] Add packet record (id=0x450da00e fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9.75 rxRSSI=-53)

DEBUG | 21:51:38 230 [Router] Ignoring incoming msg, because we've already seen it (id=0x450da00e fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=9.75 rxRSSI=-53)

DEBUG | 21:51:38 230 [Router] cancelSending id=0x450da00e, removed=0

DEBUG | 21:51:38 230 [Router] Incoming message was filtered 0xf71ea694

DEBUG | 21:51:39 230 [GPS] WANT GPS=1

INFO | 21:51:39 230 [GPS] Setting GPS power=1

WARN | 21:51:39 230 [GPS] SOME data is TOO OLD: LOC 30058, TIME 30058, DATE 30058

WARN | 21:51:39 231 [GPS] SOME data is TOO OLD: LOC 30266, TIME 30266, DATE 30266

WARN | 21:51:39 231 [GPS] SOME data is TOO OLD: LOC 30485, TIME 30485, DATE 30485

WARN | 21:51:40 231 [GPS] SOME data is TOO OLD: LOC 30704, TIME 30704, DATE 30704

WARN | 21:51:40 231 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:51:40 231 [GPS] WANT GPS=0

DEBUG | 21:51:40 231 [GPS] GPS Lock took 0, average 0

INFO | 21:51:40 231 [GPS] Setting GPS power=0

DEBUG | 21:51:40 231 [GPS] publishing pos@651f2fec:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:51:40 231 [GPS] onGPSChanged() pos@651f2fec, time=1696542700, lat=52x, lon=56x, alt=30

INFO | 21:51:40 231 [GPS] updatePosition LOCAL pos@651f2fec, time=1696542700, latI=52x, lonI=56x, alt=30

DEBUG | 21:51:40 231 [GPS] Node status update: 2 online, 2 total

DEBUG | 21:51:50 241 [Power] Battery: usbPower=1, isCharging=0, batMv=4156, batPct=100

DEBUG | 21:52:10 261 [Power] Battery: usbPower=1, isCharging=0, batMv=4156, batPct=100

DEBUG | 21:52:10 261 [GPS] WANT GPS=1

INFO | 21:52:10 261 [GPS] Setting GPS power=1

WARN | 21:52:10 261 [GPS] SOME data is TOO OLD: LOC 30059, TIME 30059, DATE 30059

WARN | 21:52:10 262 [GPS] SOME data is TOO OLD: LOC 30267, TIME 30267, DATE 30267

WARN | 21:52:10 262 [GPS] SOME data is TOO OLD: LOC 30486, TIME 30486, DATE 30486

WARN | 21:52:11 262 [GPS] SOME data is TOO OLD: LOC 30705, TIME 30705, DATE 30705

WARN | 21:52:11 262 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:52:11 262 [GPS] WANT GPS=0

DEBUG | 21:52:11 262 [GPS] GPS Lock took 0, average 0

INFO | 21:52:11 262 [GPS] Setting GPS power=0

DEBUG | 21:52:11 262 [GPS] publishing pos@651f300b:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:52:11 262 [GPS] onGPSChanged() pos@651f300b, time=1696542731, lat=52x, lon=56x, alt=30

INFO | 21:52:11 262 [GPS] updatePosition LOCAL pos@651f300b, time=1696542731, latI=52x, lonI=56x, alt=30

DEBUG | 21:52:11 262 [GPS] Node status update: 2 online, 2 total

DEBUG | 21:52:30 281 [Power] Battery: usbPower=1, isCharging=0, batMv=4156, batPct=100

INFO | 21:52:35 287 [DeviceTelemetryModule] (Sending): air_util_tx=0.171444, channel_utilization=0.000000, battery_level=100, voltage=4.156000

DEBUG | 21:52:35 287 [DeviceTelemetryModule] updateTelemetry LOCAL

DEBUG | 21:52:35 287 [DeviceTelemetryModule] Node status update: 2 online, 2 total

INFO | 21:52:35 287 [DeviceTelemetryModule] Sending packet to mesh

DEBUG | 21:52:35 287 [DeviceTelemetryModule] Update DB node 0xf71ea694, rx_time=1696542755, channel=0

DEBUG | 21:52:35 287 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x450da00f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542755 priority=1)

DEBUG | 21:52:35 287 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL

DEBUG | 21:52:35 287 [DeviceTelemetryModule] localSend to channel 0

DEBUG | 21:52:35 287 [DeviceTelemetryModule] Add packet record (id=0x450da00f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542755 priority=1)

DEBUG | 21:52:35 287 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:52:35 287 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:52:35 287 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da00f, numBytes=23!

DEBUG | 21:52:35 287 [DeviceTelemetryModule] enqueuing for send (id=0x450da00f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542755 priority=1)

DEBUG | 21:52:35 287 [DeviceTelemetryModule] txGood=8,rxGood=10,rxBad=0

DEBUG | 21:52:35 287 [DeviceTelemetryModule] Using channel 0 (hash 0x8)

DEBUG | 21:52:35 287 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:52:35 287 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:52:35 287 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da00f, numBytes=23!

DEBUG | 21:52:35 287 [DeviceTelemetryModule] decoded message (id=0x450da00f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542755 priority=1)

DEBUG | 21:52:35 287 [RadioIf] Starting low level send (id=0x450da00f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542755 priority=1)

DEBUG | 21:52:35 287 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=39, time 698 ms

DEBUG | 21:52:35 287 [RadioIf] AirTime - Packet transmitted : 698ms

DEBUG | 21:52:36 287 [RadioIf] Completed sending (id=0x450da00f fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542755 priority=1)

DEBUG | 21:52:38 290 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=39, time 698 ms

DEBUG | 21:52:38 290 [RadioIf] Lora RX (id=0x450da00f fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.5 rxRSSI=-70)

DEBUG | 21:52:38 290 [RadioIf] AirTime - Packet received : 698ms

DEBUG | 21:52:38 290 [Router] Rx someone rebroadcasting for us (id=0x450da00f fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.5 rxRSSI=-70)

DEBUG | 21:52:38 290 [Router] didn't find pending packet

DEBUG | 21:52:38 290 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00f

DEBUG | 21:52:38 290 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da00f

DEBUG | 21:52:38 290 [Router] Add packet record (id=0x450da00f fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.5 rxRSSI=-70)

DEBUG | 21:52:38 290 [Router] Ignoring incoming msg, because we've already seen it (id=0x450da00f fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11.5 rxRSSI=-70)

DEBUG | 21:52:38 290 [Router] cancelSending id=0x450da00f, removed=0

DEBUG | 21:52:38 290 [Router] Incoming message was filtered 0xf71ea694

DEBUG | 21:52:41 292 [GPS] WANT GPS=1

INFO | 21:52:41 292 [GPS] Setting GPS power=1

WARN | 21:52:41 292 [GPS] SOME data is TOO OLD: LOC 30057, TIME 30057, DATE 30057

WARN | 21:52:41 292 [GPS] SOME data is TOO OLD: LOC 30265, TIME 30265, DATE 30265

WARN | 21:52:41 293 [GPS] SOME data is TOO OLD: LOC 30484, TIME 30484, DATE 30484

WARN | 21:52:41 293 [GPS] SOME data is TOO OLD: LOC 30704, TIME 30704, DATE 30704

WARN | 21:52:42 293 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:52:42 293 [GPS] WANT GPS=0

DEBUG | 21:52:42 293 [GPS] GPS Lock took 0, average 0

INFO | 21:52:42 293 [GPS] Setting GPS power=0

DEBUG | 21:52:42 293 [GPS] publishing pos@651f302a:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:52:42 293 [GPS] onGPSChanged() pos@651f302a, time=1696542762, lat=52x, lon=56x, alt=30

INFO | 21:52:42 293 [GPS] updatePosition LOCAL pos@651f302a, time=1696542762, latI=52x, lonI=56x, alt=30

DEBUG | 21:52:42 293 [GPS] Node status update: 2 online, 2 total

DEBUG | 21:52:50 301 [Power] Battery: usbPower=1, isCharging=0, batMv=4155, batPct=100

DEBUG | 21:53:10 321 [Power] Battery: usbPower=1, isCharging=0, batMv=4155, batPct=100

DEBUG | 21:53:12 323 [GPS] WANT GPS=1

INFO | 21:53:12 323 [GPS] Setting GPS power=1

WARN | 21:53:12 323 [GPS] SOME data is TOO OLD: LOC 30057, TIME 30057, DATE 30057

WARN | 21:53:12 323 [GPS] SOME data is TOO OLD: LOC 30265, TIME 30265, DATE 30265

WARN | 21:53:12 324 [GPS] SOME data is TOO OLD: LOC 30484, TIME 30484, DATE 30484

WARN | 21:53:12 324 [GPS] SOME data is TOO OLD: LOC 30703, TIME 30703, DATE 30703

WARN | 21:53:13 324 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:53:13 324 [GPS] WANT GPS=0

DEBUG | 21:53:13 324 [GPS] GPS Lock took 0, average 0

INFO | 21:53:13 324 [GPS] Setting GPS power=0

DEBUG | 21:53:13 324 [GPS] publishing pos@651f3049:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:53:13 324 [GPS] onGPSChanged() pos@651f3049, time=1696542793, lat=52x, lon=56x, alt=30

INFO | 21:53:13 324 [GPS] updatePosition LOCAL pos@651f3049, time=1696542793, latI=52x, lonI=56x, alt=30

DEBUG | 21:53:13 324 [GPS] Node status update: 2 online, 2 total

DEBUG | 21:53:30 341 [Power] Battery: usbPower=1, isCharging=0, batMv=4156, batPct=100

INFO | 21:53:35 347 [DeviceTelemetryModule] (Sending): air_util_tx=0.190833, channel_utilization=0.000000, battery_level=100, voltage=4.156000

DEBUG | 21:53:35 347 [DeviceTelemetryModule] updateTelemetry LOCAL

DEBUG | 21:53:35 347 [DeviceTelemetryModule] Node status update: 2 online, 2 total

INFO | 21:53:35 347 [DeviceTelemetryModule] Sending packet to mesh

DEBUG | 21:53:35 347 [DeviceTelemetryModule] Update DB node 0xf71ea694, rx_time=1696542815, channel=0

DEBUG | 21:53:35 347 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x450da010 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542815 priority=1)

DEBUG | 21:53:35 347 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL

DEBUG | 21:53:35 347 [DeviceTelemetryModule] localSend to channel 0

DEBUG | 21:53:35 347 [DeviceTelemetryModule] Add packet record (id=0x450da010 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542815 priority=1)

DEBUG | 21:53:35 347 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:53:35 347 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:53:35 347 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da010, numBytes=23!

DEBUG | 21:53:35 347 [DeviceTelemetryModule] enqueuing for send (id=0x450da010 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542815 priority=1)

DEBUG | 21:53:35 347 [DeviceTelemetryModule] txGood=9,rxGood=11,rxBad=0

DEBUG | 21:53:35 347 [DeviceTelemetryModule] Using channel 0 (hash 0x8)

DEBUG | 21:53:35 347 [DeviceTelemetryModule] Expanding short PSK #1

DEBUG | 21:53:35 347 [DeviceTelemetryModule] Using AES128 key!

DEBUG | 21:53:35 347 [DeviceTelemetryModule] ESP32 crypt fr=f71ea694, num=450da010, numBytes=23!

DEBUG | 21:53:35 347 [DeviceTelemetryModule] decoded message (id=0x450da010 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1696542815 priority=1)

DEBUG | 21:53:36 347 [RadioIf] Starting low level send (id=0x450da010 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542815 priority=1)

DEBUG | 21:53:36 347 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=39, time 698 ms

DEBUG | 21:53:36 347 [RadioIf] AirTime - Packet transmitted : 698ms

DEBUG | 21:53:36 348 [RadioIf] Completed sending (id=0x450da010 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542815 priority=1)

DEBUG | 21:53:41 353 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=39, time 698 ms

DEBUG | 21:53:41 353 [RadioIf] Lora RX (id=0x450da010 fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=10.75 rxRSSI=-69)

DEBUG | 21:53:41 353 [RadioIf] AirTime - Packet received : 698ms

DEBUG | 21:53:41 353 [Router] Rx someone rebroadcasting for us (id=0x450da010 fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=10.75 rxRSSI=-69)

DEBUG | 21:53:41 353 [Router] didn't find pending packet

DEBUG | 21:53:41 353 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da010

DEBUG | 21:53:41 353 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da010

DEBUG | 21:53:41 353 [Router] Add packet record (id=0x450da010 fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=10.75 rxRSSI=-69)

DEBUG | 21:53:41 353 [Router] Ignoring incoming msg, because we've already seen it (id=0x450da010 fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=10.75 rxRSSI=-69)

DEBUG | 21:53:41 353 [Router] cancelSending id=0x450da010, removed=0

DEBUG | 21:53:41 353 [Router] Incoming message was filtered 0xf71ea694

DEBUG | 21:53:43 354 [GPS] WANT GPS=1

INFO | 21:53:43 354 [GPS] Setting GPS power=1

WARN | 21:53:43 354 [GPS] SOME data is TOO OLD: LOC 30057, TIME 30057, DATE 30057

WARN | 21:53:43 354 [GPS] SOME data is TOO OLD: LOC 30265, TIME 30265, DATE 30265

WARN | 21:53:43 355 [GPS] SOME data is TOO OLD: LOC 30484, TIME 30484, DATE 30484

WARN | 21:53:43 355 [GPS] SOME data is TOO OLD: LOC 30703, TIME 30703, DATE 30703

WARN | 21:53:44 355 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:53:44 355 [GPS] WANT GPS=0

DEBUG | 21:53:44 355 [GPS] GPS Lock took 0, average 0

INFO | 21:53:44 355 [GPS] Setting GPS power=0

DEBUG | 21:53:44 355 [GPS] publishing pos@651f3067:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:53:44 355 [GPS] onGPSChanged() pos@651f3067, time=1696542824, lat=52x, lon=56, alt=30

INFO | 21:53:44 355 [GPS] updatePosition LOCAL pos@651f3067, time=1696542824, latI=52x, lonI=56x, alt=30

DEBUG | 21:53:44 355 [GPS] Node status update: 2 online, 2 total

DEBUG | 21:53:50 361 [Power] Battery: usbPower=1, isCharging=0, batMv=4156, batPct=100

INFO | 21:54:05 376 [PositionModule] Sending pos@651f3067:6 to mesh (wantReplies=0)

DEBUG | 21:54:05 376 [PositionModule] cancelSending id=0x450da00d, removed=0

INFO | 21:54:05 376 [PositionModule] Providing time to mesh 1696542824

INFO | 21:54:05 376 [PositionModule] Position reply: time=1696542824, latI=52x, lonI=-56x

DEBUG | 21:54:05 376 [PositionModule] Update DB node 0xf71ea694, rx_time=1696542845, channel=0

DEBUG | 21:54:05 376 [PositionModule] handleReceived(LOCAL) (id=0x450da011 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1696542845 priority=10)

DEBUG | 21:54:05 376 [PositionModule] No modules interested in portnum=3, src=LOCAL

DEBUG | 21:54:05 376 [PositionModule] localSend to channel 0

DEBUG | 21:54:05 376 [PositionModule] Add packet record (id=0x450da011 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1696542845 priority=10)

DEBUG | 21:54:05 376 [PositionModule] Expanding short PSK #1

DEBUG | 21:54:05 376 [PositionModule] Using AES128 key!

DEBUG | 21:54:05 376 [PositionModule] ESP32 crypt fr=f71ea694, num=450da011, numBytes=26!

DEBUG | 21:54:05 376 [PositionModule] enqueuing for send (id=0x450da011 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542845 priority=10)

DEBUG | 21:54:05 376 [PositionModule] txGood=10,rxGood=12,rxBad=0

DEBUG | 21:54:05 376 [PositionModule] Using channel 0 (hash 0x8)

DEBUG | 21:54:05 376 [PositionModule] Expanding short PSK #1

DEBUG | 21:54:05 376 [PositionModule] Using AES128 key!

DEBUG | 21:54:05 376 [PositionModule] ESP32 crypt fr=f71ea694, num=450da011, numBytes=26!

DEBUG | 21:54:05 376 [PositionModule] decoded message (id=0x450da011 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1696542845 priority=10)

DEBUG | 21:54:05 376 [RadioIf] Starting low level send (id=0x450da011 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542845 priority=10)

DEBUG | 21:54:05 376 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=42, time 739 ms

DEBUG | 21:54:05 376 [RadioIf] AirTime - Packet transmitted : 739ms

DEBUG | 21:54:06 377 [RadioIf] Completed sending (id=0x450da011 fr=0x94 to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1696542845 priority=10)

DEBUG | 21:54:10 381 [Power] Battery: usbPower=1, isCharging=0, batMv=4155, batPct=100

DEBUG | 21:54:10 382 [RadioIf] (bw=250, sf=11, cr=4/8) packet symLen=8 ms, payloadSize=42, time 739 ms

DEBUG | 21:54:10 382 [RadioIf] Lora RX (id=0x450da011 fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11 rxRSSI=-70)

DEBUG | 21:54:10 382 [RadioIf] AirTime - Packet received : 739ms

DEBUG | 21:54:10 382 [Router] Rx someone rebroadcasting for us (id=0x450da011 fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11 rxRSSI=-70)

DEBUG | 21:54:10 382 [Router] didn't find pending packet

DEBUG | 21:54:10 382 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da011

DEBUG | 21:54:10 382 [Router] Found existing packet record for fr=0xf71ea694,to=0xffffffff,id=0x450da011

DEBUG | 21:54:11 382 [Router] Add packet record (id=0x450da011 fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11 rxRSSI=-70)

DEBUG | 21:54:11 382 [Router] Ignoring incoming msg, because we've already seen it (id=0x450da011 fr=0x94 to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=11 rxRSSI=-70)

DEBUG | 21:54:11 382 [Router] cancelSending id=0x450da011, removed=0

DEBUG | 21:54:11 382 [Router] Incoming message was filtered 0xf71ea694

DEBUG | 21:54:14 385 [GPS] WANT GPS=1

INFO | 21:54:14 385 [GPS] Setting GPS power=1

WARN | 21:54:14 385 [GPS] SOME data is TOO OLD: LOC 30057, TIME 30057, DATE 30057

WARN | 21:54:14 385 [GPS] SOME data is TOO OLD: LOC 30265, TIME 30265, DATE 30265

WARN | 21:54:14 385 [GPS] SOME data is TOO OLD: LOC 30484, TIME 30484, DATE 30484

WARN | 21:54:14 386 [GPS] SOME data is TOO OLD: LOC 30703, TIME 30703, DATE 30703

WARN | 21:54:14 386 [GPS] BOGUS course.value() REJECTED: -1499236378

DEBUG | 21:54:14 386 [GPS] WANT GPS=0

DEBUG | 21:54:14 386 [GPS] GPS Lock took 0, average 0

INFO | 21:54:14 386 [GPS] Setting GPS power=0

DEBUG | 21:54:14 386 [GPS] publishing pos@651f3086:2, hasVal=1, Sats=4, GPSlock=1

DEBUG | 21:54:14 386 [GPS] onGPSChanged() pos@651f3086, time=1696542854, lat=52x, lon=56x, alt=30

INFO | 21:54:14 386 [GPS] updatePosition LOCAL pos@651f3086, time=1696542854, latI=52x, lonI=56x, alt=30

DEBUG | 21:54:14 386 [GPS] Node status update: 2 online, 2 total

DEBUG | 21:54:30 401 [Power] Battery: usbPower=1, isCharging=0, batMv=4155, batPct=100

NickDolfin commented 11 months ago

I hope you can find something.

Many thanks.

NickDolfin commented 11 months ago

gps

After a while I see the above picture on my ipad. Device was stationary - ie not moving. A bit strange don't you think.

thebentern commented 11 months ago

Should be fixed in 2.2.11. Please test and reopen if not resolved

NickDolfin commented 10 months ago

Hi,

I just reopened this (I think).

After flashing version 2.2.11 I still get wrong positions:

About half of the time I get an altitude of 42.950 km high.