Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

[Bug]: gps coordinates does not change #2868

Closed
NickDolfin opened this issue Oct 5, 2023 · 8 comments
Closed

[Bug]: gps coordinates does not change #2868

NickDolfin opened this issue Oct 5, 2023 · 8 comments
Labels
bug Something isn't working

Comments

@NickDolfin
Copy link

NickDolfin commented Oct 5, 2023 •
edited

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
@NickDolfin NickDolfin added the bug Something isn't working label Oct 5, 2023
@jp-bennett
Copy link
Collaborator

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

@NickDolfin
Copy link
Author

NickDolfin commented Oct 5, 2023 •
edited

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
Copy link
Author

NickDolfin commented Oct 5, 2023 •
edited

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

@NickDolfin
Copy link
Author

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
Copy link
Author

I hope you can find something.

Many thanks.

@NickDolfin
Copy link
Author

NickDolfin commented Oct 5, 2023 •
edited

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
Copy link
Contributor

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

@NickDolfin
Copy link
Author

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.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@jp-bennett @thebentern @NickDolfin