search

meshtastic / firmware

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

T-Beam APX2101-V1.2 w/NEO M8N-0-10[Bug]: #3097

Closed BobsBlueNorth closed 8 months ago

BobsBlueNorth commented 8 months ago

20240122_205429

Category

Hardware Compatibility

Hardware

T-Beam

Firmware Version

2.2.18.e9bde80

Description

I have two T-Beams w/ the NEO M8N GNSS.

These two devices seem to be having an issue with updating their GPS location after the initial boot. I have been watching the logs and I can see the GNSS boot, lock and report the position at start-up.

I can also see the GNSS being woken up every two minutes looking for updates in location. What I find interesting is it never updates the position. It always reports back the exact same Lat/Long/Alt, even when I disconnect the GNSS antenna.

When the GNSS is asleep, the LED indicator light for it is off. During the update at the 2 minutes mark, it flashes once.

I am not seeing this on my T-Beams with NEO-6M or my Heltec Wireless Trackers with UC6580 GNSS. They both update correctly. Every two minutes they wake the GNSS, get a new location, and then go back to sleep.

Relevant log output

ets Jul 29 2019 12:21:46

rst:0x1 (POWERON_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0030,len:1184
load:0x40078000,len:13192
load:0x40080400,len:3028
entry 0x400805e4
E (836) esp_core_dump_flash: No core dum���ѥѥ���found!
E (837) esp_core_dump_flash: No core dump partition found!
[    12][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 480 / 2 = 240 Mhz, APB: 80000000 Hz
[   464][I][esp32-hal-psram.c:96] psramInit(): PSRAM enabled
��@INFO  | ??:??:?? 0 

//\ E S H T /\ S T / C

INFO  | ??:??:?? 0 Booted, wake cause 0 (boot count 1), reset_reason=reset
DEBUG | ??:??:?? 0 Filesystem files (516096/1048576 Bytes):
DEBUG | ??:??:?? 0  /prefs/channels.proto (53 Bytes)
DEBUG | ??:??:?? 0  /prefs/config.proto (89 Bytes)
DEBUG | ??:??:?? 0  /prefs/db.proto (159 Bytes)
DEBUG | ??:??:?? 0  /static/.gitkeep (0 Bytes)
DEBUG | ??:??:?? 0  /static/Logo_Black.svg.gz (602 Bytes)
DEBUG | ??:??:?? 0  /static/Logo_White.svg.gz (610 Bytes)
DEBUG | ??:??:?? 0  /static/apple-touch-icon.png.gz (3164 Bytes)
DEBUG | ??:??:?? 0  /static/favicon.ico.gz (2270 Bytes)
DEBUG | ??:??:?? 0  /static/icon.svg.gz (852 Bytes)
DEBUG | ??:??:?? 0  /static/index-H3yz6dTg.js.gz (348 Bytes)
DEBUG | ??:??:?? 0  /static/index-eebp_vQT.js.gz (232487 Bytes)
DEBUG | ??:??:?? 0  /static/index-tjk88OUb.css.gz (16125 Bytes)
DEBUG | ??:??:?? 0  /static/index.html.gz (547 Bytes)
DEBUG | ??:??:?? 0  /static/maplibre-gl-j-ujuLPK.js.gz (208176 Bytes)
DEBUG | ??:??:?? 0  /static/robots.txt.gz (42 Bytes)
DEBUG | ??:??:?? 0  /static/site.webmanifest.gz (197 Bytes)
[   986][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=21 scl=22 freq=100000
[   988][I][XPowersCommon.tpp:248] begin(): SDA:21 SCL:22
[   992][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
INFO  | ??:??:?? 0 AXP2101 PMU init succeeded, using AXP2101 PMU
[  1025][D][XPowersAXP2101.tpp:2791] setInterruptImpl(): DISABLE - HEX:0xffffffff BIN:
DEBUG | ??:??:?? 1 =======================================================================
DEBUG | ??:??:?? 1 DC1  : +   Voltage:3300 mV 
DEBUG | ??:??:?? 1 DC2  : -   Voltage:900 mV 
DEBUG | ??:??:?? 1 DC3  : -   Voltage:900 mV 
DEBUG | ??:??:?? 1 DC4  : -   Voltage:1100 mV 
DEBUG | ??:??:?? 1 ALDO1: -   Voltage:1800 mV 
DEBUG | ??:??:?? 1 ALDO2: +   Voltage:3300 mV 
DEBUG | ??:??:?? 1 ALDO3: +   Voltage:3300 mV 
DEBUG | ??:??:?? 1 ALDO4: -   Voltage:2900 mV 
DEBUG | ??:??:?? 1 BLDO1: -   Voltage:1800 mV 
DEBUG | ??:??:?? 1 BLDO2: -   Voltage:2800 mV 
DEBUG | ??:??:?? 1 =======================================================================
DEBUG | ??:??:?? 1 Battery: usbPower=1, isCharging=0, batMv=4166, batPct=100
INFO  | ??:??:?? 1 Scanning for i2c devices...
[  1096][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
DEBUG | ??:??:?? 1 Scanning for i2c devices on port 1
DEBUG | ??:??:?? 1 I2C device found at address 0x34
INFO  | ??:??:?? 1 axp192/axp2101 PMU found
INFO  | ??:??:?? 1 1 I2C devices found
DEBUG | ??:??:?? 1 acc_info = 0
INFO  | ??:??:?? 1 Meshtastic hwvendor=4, swver=2.2.18.e9bde80
DEBUG | ??:??:?? 1 Setting random seed 2880098309
DEBUG | ??:??:?? 1 Total heap: 178472
DEBUG | ??:??:?? 1 Free heap: 144220
DEBUG | ??:??:?? 1 Total PSRAM: 4192107
DEBUG | ??:??:?? 1 Free PSRAM: 4191847
DEBUG | ??:??:?? 1 NVS: UsedEntries 90, FreeEntries 540, AllEntries 630, NameSpaces 3
DEBUG | ??:??:?? 1 Setup Preferences in Flash Storage
DEBUG | ??:??:?? 1 Number of Device Reboots: 10
DEBUG | ??:??:?? 1 OTA firmware version 0.2.1.2fbe7fc
INFO  | ??:??:?? 1 Initializing NodeDB
INFO  | ??:??:?? 1 Loading /prefs/db.proto
INFO  | ??:??:?? 1 Loaded saved devicestate version 22
INFO  | ??:??:?? 1 Loading /prefs/config.proto
INFO  | ??:??:?? 1 Loaded saved config version 22
[  1286][E][vfs_api.cpp:105] open(): /littlefs/prefs/module.proto does not exist, no permits for creation
INFO  | ??:??:?? 1 No /prefs/module.proto preferences found
INFO  | ??:??:?? 1 Installing default ModuleConfig
INFO  | ??:??:?? 1 Loading /prefs/channels.proto
INFO  | ??:??:?? 1 Loaded saved channelFile version 22
[  1365][E][vfs_api.cpp:105] open(): /littlefs/oem/oem.proto does not exist, no permits for creation
INFO  | ??:??:?? 1 No /oem/oem.proto preferences found
DEBUG | ??:??:?? 1 cleanupMeshDB purged 0 entries
WARN  | ??:??:?? 1 Using nodenum 0x1c50623c 
DEBUG | ??:??:?? 1 Number of Device Reboots: 10
DEBUG | ??:??:?? 1 Expanding short PSK #1
INFO  | ??:??:?? 1 Wanted region 1, using US
DEBUG | ??:??:?? 1 region=1, NODENUM=0x1c50623c, dbsize=1
INFO  | ??:??:?? 1 Saving /prefs/db.proto
WARN  | ??:??:?? 1 SPI.begin(SCK=5, MISO=19, MOSI=27, NSS=18)
DEBUG | ??:??:?? 1 Read RTC time as 1
INFO  | ??:??:?? 1 Setting GPS power=1
DEBUG | ??:??:?? 1 WANT GPS=1
INFO  | ??:??:?? 1 Setting GPS power=1
DEBUG | ??:??:?? 1 Using GPIO34 for GPS RX
DEBUG | ??:??:?? 1 Using GPIO12 for GPS TX
DEBUG | ??:??:?? 1 NeighborInfoModule is disabled
INFO  | ??:??:?? 1 External Notification Module Disabled
WARN  | ??:??:?? 1 RF95Interface(cs=18, irq=26, rst=23, busy=33)
INFO  | ??:??:?? 1 Starting meshradio init...
DEBUG | ??:??:?? 1 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=0, time 231 ms
DEBUG | ??:??:?? 1 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=253, time 2115 ms
INFO  | ??:??:?? 1 Radio freq=906.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=US, name=LongFast, config=0, ch=19, power=30
INFO  | ??:??:?? 1 Radio myRegion->freqStart -> myRegion->freqEnd: 902.000000 -> 928.000000 (26.000000 mhz)
INFO  | ??:??:?? 1 Radio myRegion->numChannels: 104 x 250.000kHz
INFO  | ??:??:?? 1 Radio channel_num: 20
INFO  | ??:??:?? 1 Radio frequency: 906.875000
INFO  | ??:??:?? 1 Slot time: 42 msec
INFO  | ??:??:?? 1 Set radio: final power level=20
INFO  | ??:??:?? 1 RF95 init result -2
INFO  | ??:??:?? 1 Frequency set to 906.875000
INFO  | ??:??:?? 1 Bandwidth set to 250.000000
INFO  | ??:??:?? 1 Power output set to 20
WARN  | ??:??:?? 1 Failed to find RF95 radio
WARN  | ??:??:?? 1 SX126xInterface(cs=18, irq=33, rst=23, busy=32)
DEBUG | ??:??:?? 1 SX126X_DIO3_TCXO_VOLTAGE defined, using DIO3 as TCXO reference voltage at 1.800000 V
INFO  | ??:??:?? 1 Starting meshradio init...
DEBUG | ??:??:?? 1 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=0, time 231 ms
DEBUG | ??:??:?? 1 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=253, time 2115 ms
INFO  | ??:??:?? 1 Radio freq=906.875, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=US, name=LongFast, config=0, ch=19, power=30
INFO  | ??:??:?? 1 Radio myRegion->freqStart -> myRegion->freqEnd: 902.000000 -> 928.000000 (26.000000 mhz)
INFO  | ??:??:?? 1 Radio myRegion->numChannels: 104 x 250.000kHz
INFO  | ??:??:?? 1 Radio channel_num: 20
INFO  | ??:??:?? 1 Radio frequency: 906.875000
INFO  | ??:??:?? 1 Slot time: 42 msec
INFO  | ??:??:?? 1 Set radio: final power level=22
INFO  | ??:??:?? 2 SX126x init result 0
INFO  | ??:??:?? 2 Frequency set to 906.875000
INFO  | ??:??:?? 2 Bandwidth set to 250.000000
INFO  | ??:??:?? 2 Power output set to 22
DEBUG | ??:??:?? 2 Current limit set to 140.000000
DEBUG | ??:??:?? 2 Current limit set result 0
DEBUG | ??:??:?? 2 Setting DIO2 as RF switch
DEBUG | ??:??:?? 2 SX126X_RXEN not defined, defaulting to RADIOLIB_NC
DEBUG | ??:??:?? 2 SX126X_TXEN not defined, defaulting to RADIOLIB_NC
DEBUG | ??:??:?? 2 Using MCU pin -1 as RXEN and pin -1 as TXEN to control RF switching
INFO  | ??:??:?? 2 Set RX gain to boosted mode; result: 0
INFO  | ??:??:?? 2 SX1262 Radio init succeeded, using SX1262 radio
INFO  | ??:??:?? 2 Not using WIFI
DEBUG | ??:??:?? 2 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=237, time 2000 ms
DEBUG | ??:??:?? 2 LoRA bitrate = 118.500000 bytes / sec
[  2132][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 320 / 4 = 80 Mhz, APB: 80000000 Hz
DEBUG | ??:??:?? 2 [Power] Battery: usbPower=1, isCharging=0, batMv=4166, batPct=100
DEBUG | ??:??:?? 2 [GPS] NMEA GPS time 2024-01-15 01:50:00
DEBUG | ??:??:?? 2 [GPS] Upgrading time to quality 4
DEBUG | 01:50:00 2 [GPS] Read RTC time as 1705283400
WARN  | 01:50:00 2 [GPS] Warning, 1 new GPS checksum failures, for a total of 1.
DEBUG | 01:50:00 2 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0
DEBUG | 01:50:00 2 [GPS] No GPS lock
DEBUG | 01:50:00 2 [GPS] onGPSChanged() pos@0, time=1705283400, lat=0, lon=0, alt=0
INFO  | 01:50:00 2 [GPS] updatePosition LOCAL pos@0, time=1705283400, latI=0, lonI=0, alt=0
DEBUG | 01:50:00 2 [GPS] Setting local position: latitude=0, longitude=0, time=1705283400
DEBUG | 01:50:00 2 [GPS] Node status update: 1 online, 1 total
INFO  | 01:50:00 2 [RangeTestModule] Range Test Module - Disabled
WARN  | 01:50:02 5 [GPS] BOGUS course.value() REJECTED: 1113705842
DEBUG | 01:50:02 5 [GPS] hasValidLocation RISING EDGE
DEBUG | 01:50:02 5 [GPS] WANT GPS=0
DEBUG | 01:50:02 5 [GPS] GPS Lock took 3, average 0
INFO  | 01:50:02 5 [GPS] Setting GPS power=0
DEBUG | 01:50:02 5 [GPS] publishing pos@65a48f4b:2, hasVal=1, Sats=8, GPSlock=1
DEBUG | 01:50:02 5 [GPS] New GPS pos@65a48f4b:3 lat=61.309120, lon=-149.529300, alt=175, pdop=1.42, track=0.00, speed=0.01, sats=8
DEBUG | 01:50:02 5 [GPS] onGPSChanged() pos@65a48f4b, time=1705283402, lat=613091201, lon=-1495293001, alt=175
INFO  | 01:50:02 5 [GPS] updatePosition LOCAL pos@65a48f4b, time=1705283402, latI=613091201, lonI=-1495293001, alt=175
DEBUG | 01:50:02 5 [GPS] Setting local position: latitude=613091201, longitude=-1495293001, time=1705283402
DEBUG | 01:50:02 5 [GPS] Node status update: 1 online, 1 total
INFO  | 01:50:02 5 [GPS] Sending smart pos@65a48f4b:6 to mesh (distanceTraveled=12715394.000000m, minDistanceThreshold=100m, timeElapsed=5079ms)
INFO  | 01:50:02 5 [GPS] Providing time to mesh 1705283402
INFO  | 01:50:02 5 [GPS] Position reply: time=1705283402, latI=613091201, lonI=--1495293001
DEBUG | 01:50:02 5 [GPS] Initial packet id 204334879, numPacketId 4294967295
DEBUG | 01:50:02 5 [GPS] Update DB node 0x1c50623c, rx_time=1705283402, channel=0
DEBUG | 01:50:02 5 [GPS] handleReceived(LOCAL) (id=0x0c2de721 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1705283402 priority=10)
DEBUG | 01:50:02 5 [GPS] No modules interested in portnum=3, src=LOCAL
DEBUG | 01:50:02 5 [GPS] localSend to channel 0
DEBUG | 01:50:02 5 [GPS] Add packet record (id=0x0c2de721 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1705283402 priority=10)
DEBUG | 01:50:02 5 [GPS] Expanding short PSK #1
DEBUG | 01:50:02 5 [GPS] Using AES128 key!
DEBUG | 01:50:02 5 [GPS] ESP32 crypt fr=1c50623c, num=c2de721, numBytes=32!
DEBUG | 01:50:02 5 [GPS] enqueuing for send (id=0x0c2de721 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1705283402 priority=10)
DEBUG | 01:50:02 5 [GPS] txGood=0,rxGood=0,rxBad=0
DEBUG | 01:50:02 5 [GPS] Using channel 0 (hash 0x8)
DEBUG | 01:50:03 5 [GPS] Expanding short PSK #1
DEBUG | 01:50:03 5 [GPS] Using AES128 key!
DEBUG | 01:50:03 5 [GPS] ESP32 crypt fr=1c50623c, num=c2de721, numBytes=32!
DEBUG | 01:50:03 5 [GPS] decoded message (id=0x0c2de721 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1705283402 priority=10)
INFO  | 01:50:03 5 [PowerFSM] Initialise the NimBLE bluetooth module
DEBUG | 01:50:03 5 [RadioIf] Starting low level send (id=0x0c2de721 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1705283402 priority=10)
DEBUG | 01:50:03 6 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=48, time 591 ms
DEBUG | 01:50:03 6 [RadioIf] AirTime - Packet transmitted : 591ms
DEBUG | 01:50:04 6 [RadioIf] Completed sending (id=0x0c2de721 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1705283402 priority=10)
DEBUG | 01:50:19 22 [Power] Battery: usbPower=1, isCharging=0, batMv=4166, batPct=100
INFO  | 01:50:29 31 [NodeInfoModule] Sending our nodeinfo to mesh (wantReplies=1)
INFO  | 01:50:29 31 [NodeInfoModule] sending owner !1c50623c/Meshtastic 623c/623c
DEBUG | 01:50:29 31 [NodeInfoModule] Update DB node 0x1c50623c, rx_time=1705283429, channel=0
DEBUG | 01:50:29 31 [NodeInfoModule] handleReceived(LOCAL) (id=0x0c2de722 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP rxtime=1705283429 priority=10)
DEBUG | 01:50:29 31 [NodeInfoModule] No modules interested in portnum=4, src=LOCAL
DEBUG | 01:50:29 31 [NodeInfoModule] localSend to channel 0
DEBUG | 01:50:29 31 [NodeInfoModule] Add packet record (id=0x0c2de722 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP rxtime=1705283429 priority=10)
DEBUG | 01:50:29 31 [NodeInfoModule] Expanding short PSK #1
DEBUG | 01:50:29 31 [NodeInfoModule] Using AES128 key!
DEBUG | 01:50:29 31 [NodeInfoModule] ESP32 crypt fr=1c50623c, num=c2de722, numBytes=50!
DEBUG | 01:50:29 31 [NodeInfoModule] enqueuing for send (id=0x0c2de722 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1705283429 priority=10)
DEBUG | 01:50:29 31 [NodeInfoModule] txGood=1,rxGood=0,rxBad=0
DEBUG | 01:50:29 31 [RadioIf] Starting low level send (id=0x0c2de722 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1705283429 priority=10)
DEBUG | 01:50:29 31 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=66, time 722 ms
DEBUG | 01:50:29 31 [RadioIf] AirTime - Packet transmitted : 722ms
DEBUG | 01:50:30 32 [RadioIf] Completed sending (id=0x0c2de722 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1705283429 priority=10)
DEBUG | 01:50:39 42 [Power] Battery: usbPower=1, isCharging=0, batMv=4165, batPct=100
INFO  | 01:50:44 46 [DeviceTelemetryModule] (Sending): air_util_tx=0.036472, channel_utilization=2.188333, battery_level=100, voltage=4.165000
DEBUG | 01:50:44 46 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | 01:50:44 46 [DeviceTelemetryModule] Node status update: 1 online, 1 total
INFO  | 01:50:44 46 [DeviceTelemetryModule] Sending packet to mesh
DEBUG | 01:50:44 46 [DeviceTelemetryModule] Update DB node 0x1c50623c, rx_time=1705283444, channel=0
DEBUG | 01:50:44 46 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x0c2de723 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1705283444 priority=1)
DEBUG | 01:50:44 46 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL
DEBUG | 01:50:44 46 [DeviceTelemetryModule] localSend to channel 0
DEBUG | 01:50:44 46 [DeviceTelemetryModule] Add packet record (id=0x0c2de723 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1705283444 priority=1)
DEBUG | 01:50:44 46 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | 01:50:44 46 [DeviceTelemetryModule] Using AES128 key!
DEBUG | 01:50:44 46 [DeviceTelemetryModule] ESP32 crypt fr=1c50623c, num=c2de723, numBytes=28!
DEBUG | 01:50:44 46 [DeviceTelemetryModule] enqueuing for send (id=0x0c2de723 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1705283444 priority=1)
DEBUG | 01:50:44 46 [DeviceTelemetryModule] txGood=2,rxGood=0,rxBad=0
DEBUG | 01:50:44 46 [DeviceTelemetryModule] Using channel 0 (hash 0x8)
DEBUG | 01:50:44 46 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | 01:50:44 46 [DeviceTelemetryModule] Using AES128 key!
DEBUG | 01:50:44 46 [DeviceTelemetryModule] ESP32 crypt fr=1c50623c, num=c2de723, numBytes=28!
DEBUG | 01:50:44 46 [DeviceTelemetryModule] decoded message (id=0x0c2de723 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1705283444 priority=1)
DEBUG | 01:50:44 46 [RadioIf] Starting low level send (id=0x0c2de723 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1705283444 priority=1)
DEBUG | 01:50:44 46 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=44, time 559 ms
DEBUG | 01:50:44 46 [RadioIf] AirTime - Packet transmitted : 559ms
DEBUG | 01:50:45 47 [RadioIf] Completed sending (id=0x0c2de723 fr=0x3c to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1705283444 priority=1)
DEBUG | 01:50:59 62 [Power] Battery: usbPower=1, isCharging=0, batMv=4165, batPct=100
DEBUG | 01:51:20 82 [Power] Battery: usbPower=1, isCharging=0, batMv=4165, batPct=100
DEBUG | 01:51:40 102 [Power] Battery: usbPower=1, isCharging=0, batMv=4164, batPct=100
DEBUG | 01:52:00 122 [Power] Battery: usbPower=1, isCharging=0, batMv=4166, batPct=100
DEBUG | 01:52:03 125 [GPS] WANT GPS=1
INFO  | 01:52:03 125 [GPS] Setting GPS power=1
WARN  | 01:52:03 125 [GPS] SOME data is TOO OLD: LOC 120435, TIME 120435, DATE 120441
WARN  | 01:52:03 125 [GPS] SOME data is TOO OLD: LOC 120643, TIME 120643, DATE 120649
WARN  | 01:52:03 125 [GPS] SOME data is TOO OLD: LOC 120862, TIME 120862, DATE 120868
WARN  | 01:52:03 125 [GPS] SOME data is TOO OLD: LOC 121081, TIME 121081, DATE 121087
WARN  | 01:52:03 126 [GPS] BOGUS course.value() REJECTED: 1113705842
DEBUG | 01:52:03 126 [GPS] WANT GPS=0
DEBUG | 01:52:03 126 [GPS] GPS Lock took 0, average 0
INFO  | 01:52:03 126 [GPS] Setting GPS power=0
DEBUG | 01:52:03 126 [GPS] publishing pos@65a48fc4:2, hasVal=1, Sats=8, GPSlock=1
DEBUG | 01:52:03 126 [GPS] onGPSChanged() pos@65a48fc4, time=1705283523, lat=613091201, lon=-1495293001, alt=175
INFO  | 01:52:03 126 [GPS] updatePosition LOCAL pos@65a48fc4, time=1705283523, latI=613091201, lonI=-1495293001, alt=175
DEBUG | 01:52:03 126 [GPS] Setting local position: latitude=613091201, longitude=-1495293001, time=1705283523
DEBUG | 01:52:03 126 [GPS] Node status update: 1 online, 1 total
DEBUG | 01:52:20 142 [Power] Battery: usbPower=1, isCharging=0, batMv=4165, batPct=100
DEBUG | 01:52:40 162 [Power] Battery: usbPower=1, isCharging=0, batMv=4166, batPct=100
DEBUG | 01:53:00 182 [Power] Battery: usbPower=1, isCharging=0, batMv=4166, batPct=100
DEBUG | 01:53:20 202 [Power] Battery: usbPower=1, isCharging=0, batMv=4165, batPct=100
DEBUG | 01:53:40 222 [Power] Battery: usbPower=1, isCharging=0, batMv=4166, batPct=100
DEBUG | 01:54:00 242 [Power] Battery: usbPower=1, isCharging=0, batMv=4165, batPct=100
DEBUG | 01:54:03 246 [GPS] WANT GPS=1
INFO  | 01:54:03 246 [GPS] Setting GPS power=1
WARN  | 01:54:03 246 [GPS] SOME data is TOO OLD: LOC 120065, TIME 120065, DATE 120065
WARN  | 01:54:04 246 [GPS] SOME data is TOO OLD: LOC 120271, TIME 120271, DATE 120271
WARN  | 01:54:04 246 [GPS] SOME data is TOO OLD: LOC 120491, TIME 120491, DATE 120491
WARN  | 01:54:04 246 [GPS] SOME data is TOO OLD: LOC 120710, TIME 120710, DATE 120710
WARN  | 01:54:04 247 [GPS] BOGUS course.value() REJECTED: 1113705842
DEBUG | 01:54:04 247 [GPS] WANT GPS=0
DEBUG | 01:54:04 247 [GPS] GPS Lock took 0, average 0
INFO  | 01:54:04 247 [GPS] Setting GPS power=0
DEBUG | 01:54:04 247 [GPS] publishing pos@65a4903d:2, hasVal=1, Sats=8, GPSlock=1
DEBUG | 01:54:04 247 [GPS] onGPSChanged() pos@65a4903d, time=1705283644, lat=613091201, lon=-1495293001, alt=175
INFO  | 01:54:04 247 [GPS] updatePosition LOCAL pos@65a4903d, time=1705283644, latI=613091201, lonI=-1495293001, alt=175
DEBUG | 01:54:04 247 [GPS] Setting local position: latitude=613091201, longitude=-1495293001, time=1705283644
DEBUG | 01:54:04 247 [GPS] Node status update: 1 online, 1 total
caveman99 commented 8 months ago

WARN | 01:52:03 126 [GPS] BOGUS course.value() REJECTED: 1113705842

this is the problem.

BobsBlueNorth commented 8 months ago

What would be causing the GNSS to report a bogus course?

BobsBlueNorth commented 8 months ago

Rolled back to 2.2.11 to see if that would change anything. With 2.2.11 I don't even get a lock, which normally takes a couples minutes max.

//\ E S H T /\ S T / C INFO | ??:??:?? 1 Meshtastic hwvendor=4, swver=2.2.11.10265aa

INFO | ??:??:?? 1 Setting GPS power=1 DEBUG | ??:??:?? 1 WANT GPS=1 INFO | ??:??:?? 1 Setting GPS power=1 DEBUG | ??:??:?? 1 Using GPIO34 for GPS RX DEBUG | ??:??:?? 1 Using GPIO12 for GPS TX

DEBUG | ??:??:?? 2 [GPS] NMEA GPS time 2024-01-23 04:43:42 DEBUG | ??:??:?? 2 [GPS] Upgrading time to quality 4 DEBUG | 04:43:42 2 [GPS] Read RTC time as 1705985022 WARN | 04:43:42 2 [GPS] Warning, 2 new GPS checksum failures, for a total of 2. DEBUG | 04:43:42 2 [GPS] WANT GPS=0 DEBUG | 04:43:42 2 [GPS] GPS Lock took 0, average 0 INFO | 04:43:42 2 [GPS] Setting GPS power=0 DEBUG | 04:43:42 2 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0 DEBUG | 04:43:42 2 [GPS] No GPS lock DEBUG | 04:43:42 2 [GPS] onGPSChanged() pos@0, time=1705985022, lat=0, lon=0, alt=0 INFO | 04:43:42 2 [GPS] updatePosition LOCAL pos@0, time=1705985022, latI=0, lonI=0, alt=0 DEBUG | 04:43:42 2 [GPS] Setting local position: latitude=0, longitude=0, time=1705985022 DEBUG | 04:43:42 2 [GPS] Node status update: 1 online, 9 total

DEBUG | 04:45:42 122 [GPS] WANT GPS=1 INFO | 04:45:42 122 [GPS] Setting GPS power=1 DEBUG | 04:45:42 122 [GPS] WANT GPS=0 DEBUG | 04:45:42 122 [GPS] GPS Lock took 0, average 0 INFO | 04:45:42 122 [GPS] Setting GPS power=0 DEBUG | 04:45:42 122 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0 DEBUG | 04:45:42 122 [GPS] onGPSChanged() pos@0, time=1705985142, lat=0, lon=0, alt=0 INFO | 04:45:42 122 [GPS] updatePosition LOCAL pos@0, time=1705985142, latI=0, lonI=0, alt=0 DEBUG | 04:45:42 122 [GPS] Setting local position: latitude=0, longitude=0, time=1705985142 DEBUG | 04:45:42 122 [GPS] Node status update: 4 online, 9 total

DEBUG | 04:47:42 242 [GPS] WANT GPS=1 INFO | 04:47:42 242 [GPS] Setting GPS power=1 DEBUG | 04:47:42 242 [GPS] WANT GPS=0 DEBUG | 04:47:42 242 [GPS] GPS Lock took 0, average 0 INFO | 04:47:42 242 [GPS] Setting GPS power=0 INFO | 04:47:42 242 [GPS] From Radio onread DEBUG | 04:47:42 242 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0 INFO | 04:47:42 242 [GPS] getFromRadio=STATE_SEND_PACKETS DEBUG | 04:47:42 242 [GPS] onGPSChanged() pos@0, time=1705985262, lat=0, lon=0, alt=0 DEBUG | 04:47:42 242 [GPS] phone downloaded packet (id=0x4bb0cb11 fr=0x78 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1705985261 rxSNR=8.5 rxRSSI=-77) INFO | 04:47:42 242 [GPS] updatePosition LOCAL pos@0, time=1705985262, latI=0, lonI=0, alt=0 DEBUG | 04:47:42 242 [GPS] Setting local position: latitude=0, longitude=0, time=1705985262 DEBUG | 04:47:42 242 [GPS] encoding toPhone packet to phone variant=2, 64 bytes DEBUG | 04:47:42 242 [GPS] Node status update: 6 online, 9 total

DEBUG | 04:49:42 362 [GPS] WANT GPS=1 INFO | 04:49:42 362 [GPS] Setting GPS power=1 DEBUG | 04:49:42 362 [GPS] WANT GPS=0 DEBUG | 04:49:42 362 [GPS] GPS Lock took 0, average 0 INFO | 04:49:42 362 [GPS] Setting GPS power=0 DEBUG | 04:49:42 362 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0 DEBUG | 04:49:42 362 [GPS] onGPSChanged() pos@0, time=1705985382, lat=0, lon=0, alt=0 INFO | 04:49:42 362 [GPS] updatePosition LOCAL pos@0, time=1705985382, latI=0, lonI=0, alt=0 DEBUG | 04:49:42 362 [GPS] Setting local position: latitude=0, longitude=0, time=1705985382 DEBUG | 04:49:42 362 [GPS] Node status update: 6 online, 9 total

DEBUG | 04:51:42 482 [GPS] WANT GPS=1 INFO | 04:51:42 482 [GPS] Setting GPS power=1 DEBUG | 04:51:42 482 [GPS] WANT GPS=0 DEBUG | 04:51:42 482 [GPS] GPS Lock took 0, average 0 INFO | 04:51:42 482 [GPS] Setting GPS power=0 DEBUG | 04:51:42 482 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0 DEBUG | 04:51:42 482 [GPS] onGPSChanged() pos@0, time=1705985502, lat=0, lon=0, alt=0 INFO | 04:51:42 482 [GPS] updatePosition LOCAL pos@0, time=1705985502, latI=0, lonI=0, alt=0 DEBUG | 04:51:42 482 [GPS] Setting local position: latitude=0, longitude=0, time=1705985502 DEBUG | 04:51:42 482 [GPS] Node status update: 6 online, 9 total

DEBUG | 04:53:42 603 [GPS] WANT GPS=1 INFO | 04:53:42 603 [GPS] Setting GPS power=1 DEBUG | 04:53:42 603 [GPS] WANT GPS=0 DEBUG | 04:53:42 603 [GPS] GPS Lock took 0, average 0 INFO | 04:53:42 603 [GPS] Setting GPS power=0 DEBUG | 04:53:42 603 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0 DEBUG | 04:53:42 603 [GPS] onGPSChanged() pos@0, time=1705985622, lat=0, lon=0, alt=0 INFO | 04:53:42 603 [GPS] updatePosition LOCAL pos@0, time=1705985622, latI=0, lonI=0, alt=0 DEBUG | 04:53:42 603 [GPS] Setting local position: latitude=0, longitude=0, time=1705985622 DEBUG | 04:53:42 603 [GPS] Node status update: 6 online, 9 total

DEBUG | 04:55:42 723 [GPS] WANT GPS=1 INFO | 04:55:42 723 [GPS] Setting GPS power=1 DEBUG | 04:55:42 723 [GPS] WANT GPS=0 DEBUG | 04:55:42 723 [GPS] GPS Lock took 0, average 0 INFO | 04:55:42 723 [GPS] Setting GPS power=0 DEBUG | 04:55:42 723 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0 DEBUG | 04:55:42 723 [GPS] onGPSChanged() pos@0, time=1705985742, lat=0, lon=0, alt=0 INFO | 04:55:42 723 [GPS] updatePosition LOCAL pos@0, time=1705985742, latI=0, lonI=0, alt=0 DEBUG | 04:55:42 723 [GPS] Setting local position: latitude=0, longitude=0, time=1705985742 DEBUG | 04:55:42 723 [GPS] Node status update: 6 online, 9 total

DEBUG | 04:57:42 843 [GPS] WANT GPS=1 INFO | 04:57:42 843 [GPS] Setting GPS power=1 DEBUG | 04:57:42 843 [GPS] WANT GPS=0 DEBUG | 04:57:42 843 [GPS] GPS Lock took 0, average 0 INFO | 04:57:42 843 [GPS] Setting GPS power=0 DEBUG | 04:57:42 843 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0 DEBUG | 04:57:42 843 [GPS] onGPSChanged() pos@0, time=1705985862, lat=0, lon=0, alt=0 INFO | 04:57:42 843 [GPS] updatePosition LOCAL pos@0, time=1705985862, latI=0, lonI=0, alt=0 DEBUG | 04:57:42 843 [GPS] Setting local position: latitude=0, longitude=0, time=1705985862 DEBUG | 04:57:42 843 [GPS] Node status update: 6 online, 9 total

BobsBlueNorth commented 8 months ago

RESTART AFTER UPDATING FIX ATTEMPT DURATION FROM 0 SECONDS TO 900 SECONDS This allows the unit to get a single GPS fix upon startup but still, no updates after that.

DEBUG | ??:??:?? 2 [GPS] Upgrading time to quality 4 DEBUG | 05:00:30 2 [GPS] Read RTC time as 1705986030 WARN | 05:00:30 2 [GPS] Warning, 1 new GPS checksum failures, for a total of 1. DEBUG | 05:00:30 2 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0 DEBUG | 05:00:30 2 [GPS] No GPS lock DEBUG | 05:00:30 2 [GPS] onGPSChanged() pos@0, time=1705986030, lat=0, lon=0, alt=0 INFO | 05:00:30 2 [GPS] updatePosition LOCAL pos@0, time=1705986030, latI=0, lonI=0, alt=0 DEBUG | 05:00:30 2 [GPS] Setting local position: latitude=0, longitude=0, time=1705986030 DEBUG | 05:00:30 2 [GPS] Node status update: 6 online, 9 total

WARN | 05:01:00 32 [GPS] BOGUS course.value() REJECTED: 841841379 DEBUG | 05:01:00 32 [GPS] hasValidLocation RISING EDGE DEBUG | 05:01:00 32 [GPS] WANT GPS=0 DEBUG | 05:01:00 32 [GPS] GPS Lock took 31, average 0 INFO | 05:01:00 32 [GPS] Setting GPS power=0 DEBUG | 05:01:00 32 [GPS] publishing pos@65af480d:2, hasVal=1, Sats=7, GPSlock=1 DEBUG | 05:01:00 32 [GPS] New GPS pos@65af480d:3 lat=61.309102, lon=-149.529373, alt=178, pdop=1.72, track=0.00, speed=0.00, sats=7 DEBUG | 05:01:00 32 [GPS] onGPSChanged() pos@65af480d, time=1705986060, lat=613091023, lon=-1495293731, alt=178 INFO | 05:01:00 32 [GPS] updatePosition LOCAL pos@65af480d, time=1705986060, latI=613091023, lonI=-1495293731, alt=178 DEBUG | 05:01:00 32 [GPS] Setting local position: latitude=613091023, longitude=-1495293731, time=1705986060 DEBUG | 05:01:00 32 [GPS] Node status update: 6 online, 9 total INFO | 05:01:00 32 [GPS] Sending smart pos@65af480d:6 to mesh (distanceTraveled=12715397.000000m, minDistanceThreshold=100m, timeElapsed=32980ms) INFO | 05:01:00 32 [GPS] Providing time to mesh 1705986060 INFO | 05:01:00 32 [GPS] Position reply: time=1705986060, latI=613091023, lonI=--1495293731 DEBUG | 05:01:00 33 [GPS] Update DB node 0x1c50630c, rx_time=1705986060, channel=0 DEBUG | 05:01:00 33 [GPS] handleReceived(LOCAL) (id=0x3bbf843d fr=0x0c to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1705986060 priority=10) DEBUG | 05:01:00 33 [GPS] No modules interested in portnum=3, src=LOCAL DEBUG | 05:01:00 33 [GPS] localSend to channel 0 DEBUG | 05:01:00 33 [GPS] Add packet record (id=0x3bbf843d fr=0x0c to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1705986060 priority=10) DEBUG | 05:01:00 33 [GPS] Using AES256 key! DEBUG | 05:01:00 33 [GPS] ESP32 crypt fr=1c50630c, num=3bbf843d, numBytes=30! DEBUG | 05:01:00 33 [GPS] enqueuing for send (id=0x3bbf843d fr=0x0c to=0xff, WantAck=0, HopLim=3 Ch=0xa4 encrypted rxtime=1705986060 priority=10) DEBUG | 05:01:00 33 [GPS] txGood=1,rxGood=0,rxBad=0 DEBUG | 05:01:00 33 [GPS] Using channel 0 (hash 0xa4) DEBUG | 05:01:00 33 [GPS] Using AES256 key! DEBUG | 05:01:00 33 [GPS] ESP32 crypt fr=1c50630c, num=3bbf843d, numBytes=30! DEBUG | 05:01:00 33 [GPS] decoded message (id=0x3bbf843d fr=0x0c to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1705986060 priority=10)

DEBUG | 05:03:00 153 [GPS] WANT GPS=1 INFO | 05:03:00 153 [GPS] Setting GPS power=1 WARN | 05:03:00 153 [GPS] SOME data is TOO OLD: LOC 120211, TIME 120211, DATE 120418 WARN | 05:03:00 153 [GPS] SOME data is TOO OLD: LOC 120418, TIME 120418, DATE 120625 WARN | 05:03:01 153 [GPS] SOME data is TOO OLD: LOC 120636, TIME 120636, DATE 120843 WARN | 05:03:01 153 [GPS] SOME data is TOO OLD: LOC 120854, TIME 120854, DATE 121061 WARN | 05:03:01 153 [GPS] BOGUS course.value() REJECTED: 841841379 DEBUG | 05:03:01 153 [GPS] WANT GPS=0 DEBUG | 05:03:01 153 [GPS] GPS Lock took 0, average 0 INFO | 05:03:01 153 [GPS] Setting GPS power=0 DEBUG | 05:03:01 153 [GPS] publishing pos@65af4885:2, hasVal=1, Sats=7, GPSlock=1 DEBUG | 05:03:01 153 [GPS] onGPSChanged() pos@65af4885, time=1705986181, lat=613091023, lon=-1495293731, alt=178 INFO | 05:03:01 153 [GPS] updatePosition LOCAL pos@65af4885, time=1705986181, latI=613091023, lonI=-1495293731, alt=178 DEBUG | 05:03:01 154 [GPS] Setting local position: latitude=613091023, longitude=-1495293731, time=1705986181 DEBUG | 05:03:01 154 [GPS] Node status update: 6 online, 9 total

DEBUG | 05:05:01 274 [GPS] WANT GPS=1 INFO | 05:05:01 274 [GPS] Setting GPS power=1 WARN | 05:05:01 274 [GPS] SOME data is TOO OLD: LOC 120064, TIME 120064, DATE 120064 WARN | 05:05:01 274 [GPS] SOME data is TOO OLD: LOC 120271, TIME 120271, DATE 120271 WARN | 05:05:02 274 [GPS] SOME data is TOO OLD: LOC 120489, TIME 120489, DATE 120489 WARN | 05:05:02 274 [GPS] SOME data is TOO OLD: LOC 120707, TIME 120707, DATE 120707 WARN | 05:05:02 274 [GPS] BOGUS course.value() REJECTED: 841841379 DEBUG | 05:05:02 274 [GPS] WANT GPS=0 DEBUG | 05:05:02 274 [GPS] GPS Lock took 0, average 0 INFO | 05:05:02 274 [GPS] Setting GPS power=0 DEBUG | 05:05:02 274 [GPS] publishing pos@65af48fe:2, hasVal=1, Sats=7, GPSlock=1 DEBUG | 05:05:02 274 [GPS] onGPSChanged() pos@65af48fe, time=1705986302, lat=613091023, lon=-1495293731, alt=178 INFO | 05:05:02 274 [GPS] updatePosition LOCAL pos@65af48fe, time=1705986302, latI=613091023, lonI=-1495293731, alt=178 DEBUG | 05:05:02 274 [GPS] Setting local position: latitude=613091023, longitude=-1495293731, time=1705986302 DEBUG | 05:05:02 274 [GPS] Node status update: 6 online, 9 total

DEBUG | 05:07:02 394 [GPS] WANT GPS=1 INFO | 05:07:02 394 [GPS] Setting GPS power=1 WARN | 05:07:02 394 [GPS] SOME data is TOO OLD: LOC 120063, TIME 120063, DATE 120063 WARN | 05:07:02 395 [GPS] SOME data is TOO OLD: LOC 120270, TIME 120270, DATE 120270 WARN | 05:07:02 395 [GPS] SOME data is TOO OLD: LOC 120488, TIME 120488, DATE 120488 WARN | 05:07:03 395 [GPS] SOME data is TOO OLD: LOC 120707, TIME 120707, DATE 120707 INFO | 05:07:03 395 [GPS] From Radio onread INFO | 05:07:03 395 [GPS] getFromRadio=STATE_SEND_PACKETS WARN | 05:07:03 395 [GPS] BOGUS course.value() REJECTED: 841841379 DEBUG | 05:07:03 395 [GPS] phone downloaded packet (id=0xb507ba92 fr=0x8c to=0x0c, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1705986362 rxSNR=8.75 rxRSSI=-45) DEBUG | 05:07:03 395 [GPS] WANT GPS=0 DEBUG | 05:07:03 395 [GPS] encoding toPhone packet to phone variant=2, 53 bytes DEBUG | 05:07:03 395 [GPS] GPS Lock took 0, average 0 INFO | 05:07:03 395 [GPS] Setting GPS power=0 DEBUG | 05:07:03 395 [GPS] publishing pos@65af4977:2, hasVal=1, Sats=7, GPSlock=1 DEBUG | 05:07:03 395 [GPS] onGPSChanged() pos@65af4977, time=1705986423, lat=613091023, lon=-1495293731, alt=178 INFO | 05:07:03 395 [GPS] updatePosition LOCAL pos@65af4977, time=1705986423, latI=613091023, lonI=-1495293731, alt=178 DEBUG | 05:07:03 395 [GPS] Setting local position: latitude=613091023, longitude=-1495293731, time=1705986423 DEBUG | 05:07:03 395 [GPS] Node status update: 6 online, 9 total

BobsBlueNorth commented 8 months ago

Here for comparison is an older T-Beam 1.1 with a NEO-6M. This unit has no problems finding a signal. You will also see it calculates an updated position every 3 minutes. This is even with the "BOGUS course.value"

//\ E S H T /\ S T / C INFO | ??:??:?? 0 Meshtastic hwvendor=4, swver=2.2.17.dbac2b1

INFO | ??:??:?? 1 Setting GPS power=1 DEBUG | ??:??:?? 1 WANT GPS=1 INFO | ??:??:?? 1 Setting GPS power=1 DEBUG | ??:??:?? 1 Using GPIO34 for GPS RX DEBUG | ??:??:?? 1 Using GPIO12 for GPS TX

DEBUG | ??:??:?? 1 [GPS] Probing for GPS at 9600 INFO | ??:??:?? 2 [GPS] Found a UBlox Module using baudrate 9600 DEBUG | ??:??:?? 2 [GPS] Module Info : DEBUG | ??:??:?? 2 [GPS] Soft version: 7.03 (45969) DEBUG | ??:??:?? 2 [GPS] Hard version: 00040007 DEBUG | ??:??:?? 2 [GPS] Extensions:0 WARN | ??:??:?? 3 [GPS] Unable to save GNSS module configuration. INFO | ??:??:?? 3 [RangeTestModule] Range Test Module - Disabled DEBUG | ??:??:?? 4 [GPS] NMEA GPS time 2024-01-23 05:13:23 DEBUG | ??:??:?? 4 [GPS] Upgrading time to quality 4 DEBUG | 05:13:23 4 [GPS] Read RTC time as 1705986803 DEBUG | 05:13:23 4 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0 DEBUG | 05:13:23 4 [GPS] No GPS lock DEBUG | 05:13:23 4 [GPS] onGPSChanged() pos@0, time=1705986803, lat=0, lon=0, alt=0 INFO | 05:13:23 4 [GPS] updatePosition LOCAL pos@0, time=1705986803, latI=0, lonI=0, alt=0 DEBUG | 05:13:23 4 [GPS] Setting local position: latitude=0, longitude=0, time=1705986803 DEBUG | 05:13:23 4 [GPS] Node status update: 1 online, 5 total

WARN | 05:14:34 75 [GPS] BOGUS course.value() REJECTED: -250057272 DEBUG | 05:14:34 75 [GPS] hasValidLocation RISING EDGE DEBUG | 05:14:34 75 [GPS] WANT GPS=0 DEBUG | 05:14:34 75 [GPS] GPS Lock took 74, average 0 INFO | 05:14:34 75 [GPS] Setting GPS power=0 DEBUG | 05:14:34 75 [GPS] publishing pos@65af4b3a:2, hasVal=1, Sats=5, GPSlock=1 DEBUG | 05:14:34 75 [GPS] New GPS pos@65af4b3a:3 lat=61.309114, lon=-149.529366, alt=184, pdop=2.36, track=0.00, speed=0.00, sats=5 DEBUG | 05:14:34 75 [GPS] onGPSChanged() pos@65af4b3a, time=1705986874, lat=613091141, lon=-1495293663, alt=184 INFO | 05:14:34 75 [GPS] updatePosition LOCAL pos@65af4b3a, time=1705986874, latI=613091141, lonI=-1495293663, alt=184 DEBUG | 05:14:34 75 [GPS] Setting local position: latitude=613091141, longitude=-1495293663, time=1705986874 DEBUG | 05:14:34 75 [GPS] Node status update: 5 online, 6 total INFO | 05:14:34 75 [GPS] Sending smart pos@65af4b3a:6 to mesh (distanceTraveled=12715396.000000m, minDistanceThreshold=100m, timeElapsed=75335ms) INFO | 05:14:34 75 [GPS] Providing time to mesh 1705986874 INFO | 05:14:34 75 [GPS] Position reply: time=1705986874, latI=613091141, lonI=--1495293663 DEBUG | 05:14:34 75 [GPS] Update DB node 0x84889654, rx_time=1705986874, channel=0 DEBUG | 05:14:34 75 [GPS] handleReceived(LOCAL) (id=0x301dd338 fr=0x54 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1705986874 priority=10) DEBUG | 05:14:34 75 [GPS] No modules interested in portnum=3, src=LOCAL DEBUG | 05:14:34 75 [GPS] localSend to channel 0 DEBUG | 05:14:34 75 [GPS] Add packet record (id=0x301dd338 fr=0x54 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1705986874 priority=10) DEBUG | 05:14:34 75 [GPS] Using AES256 key! DEBUG | 05:14:34 75 [GPS] ESP32 crypt fr=84889654, num=301dd338, numBytes=30! DEBUG | 05:14:34 75 [GPS] enqueuing for send (id=0x301dd338 fr=0x54 to=0xff, WantAck=0, HopLim=3 Ch=0xa4 encrypted rxtime=1705986874 priority=10) DEBUG | 05:14:34 75 [GPS] txGood=3,rxGood=15,rxBad=0 DEBUG | 05:14:34 75 [GPS] Using channel 0 (hash 0xa4) DEBUG | 05:14:34 75 [GPS] Using AES256 key! DEBUG | 05:14:34 75 [GPS] ESP32 crypt fr=84889654, num=301dd338, numBytes=30! DEBUG | 05:14:34 75 [GPS] decoded message (id=0x301dd338 fr=0x54 to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1705986874 priority=10)

DEBUG | 05:17:34 255 [GPS] WANT GPS=1 INFO | 05:17:34 255 [GPS] Setting GPS power=1 WARN | 05:17:34 255 [GPS] SOME data is TOO OLD: LOC 180249, TIME 180249, DATE 180454 WARN | 05:17:34 255 [GPS] SOME data is TOO OLD: LOC 180465, TIME 180465, DATE 180670 WARN | 05:17:37 258 [GPS] BOGUS course.value() REJECTED: -250057272 DEBUG | 05:17:37 258 [GPS] WANT GPS=0 DEBUG | 05:17:37 258 [GPS] GPS Lock took 2, average 2 INFO | 05:17:37 258 [GPS] Setting GPS power=0 DEBUG | 05:17:37 258 [GPS] publishing pos@65af4bf1:2, hasVal=1, Sats=4, GPSlock=1 DEBUG | 05:17:37 258 [GPS] New GPS pos@65af4bf1:3 lat=61.309219, lon=-149.529531, alt=178, pdop=3.94, track=0.00, speed=0.02, sats=4 DEBUG | 05:17:37 258 [GPS] onGPSChanged() pos@65af4bf1, time=1705987057, lat=613092188, lon=-1495295313, alt=178 INFO | 05:17:37 258 [GPS] updatePosition LOCAL pos@65af4bf1, time=1705987057, latI=613092188, lonI=-1495295313, alt=178 DEBUG | 05:17:37 258 [GPS] Setting local position: latitude=613092188, longitude=-1495295313, time=1705987057 DEBUG | 05:17:37 258 [GPS] Node status update: 6 online, 7 total

DEBUG | 05:20:37 438 [GPS] WANT GPS=1 INFO | 05:20:37 438 [GPS] Setting GPS power=1 WARN | 05:20:37 438 [GPS] SOME data is TOO OLD: LOC 180287, TIME 180287, DATE 180292 WARN | 05:20:37 438 [GPS] SOME data is TOO OLD: LOC 180503, TIME 180503, DATE 180508 DEBUG | 05:20:40 441 [Power] Battery: usbPower=1, isCharging=0, batMv=4131, batPct=98 DEBUG | 05:20:41 442 [GPS] WANT GPS=0 DEBUG | 05:20:41 442 [GPS] GPS Lock took 3, average 3 INFO | 05:20:41 442 [GPS] Setting GPS power=0 DEBUG | 05:20:41 442 [GPS] publishing pos@65af4ca9:2, hasVal=1, Sats=5, GPSlock=1 DEBUG | 05:20:41 442 [GPS] New GPS pos@65af4ca9:3 lat=61.309082, lon=-149.529347, alt=187, pdop=2.46, track=283.94, speed=0.03, sats=5 DEBUG | 05:20:41 442 [GPS] onGPSChanged() pos@65af4ca9, time=1705987241, lat=613090825, lon=-1495293468, alt=187 INFO | 05:20:41 442 [GPS] updatePosition LOCAL pos@65af4ca9, time=1705987241, latI=613090825, lonI=-1495293468, alt=187 DEBUG | 05:20:41 442 [GPS] Setting local position: latitude=613090825, longitude=-1495293468, time=1705987241 DEBUG | 05:20:41 442 [GPS] Node status update: 7 online, 8 total

geeksville commented 8 months ago

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

https://meshtastic.discourse.group/t/t-beam-v1-2-persists-saying-no-sats/9114/8

jp-bennett commented 8 months ago

@BobsBlueNorth working on it. Fixes are landing, and @GPSFan in particular has some work that may help.

BobsBlueNorth commented 8 months ago

I have been going nuts thinking I am the only one...until I saw the Meshtastic thread at about the same time geeksville posted it.

I see after more research the PMU changed between V1.1 and V1.2 T-Beams. Is this the root cause?

Some Cross-References: https://github.com/meshtastic/firmware/issues/2838

GPSFan commented 8 months ago

I've been severely sidetracked by local events and trying to get a PR out to support the Chatter 2. Meshtastic is a fantastic project, but moves very rapidly and it's hard to keep up with all the changes on GitHub. There are 3 basic issues with GPSs in general, module detection/identification, managing the power, and initialization. Of all the modules supported, by far u-blox has the most generations. Neo-6, Neo-7, Max-7, M8N, M10 and others. Different generations have improved capabilities, performance and power consumption. While documentation is available, it is not the easiest to understand and can contain errors/omissions/conflicts. Same goes for the boards on which the modules are mounted on. Any help is appreciated.

BobsBlueNorth commented 8 months ago

I just uploaded 2.2.20. Initial indications are the bug is fixed. So far I saw the GNSS come online and report a position. 2 minutes later the GNSS was woken up and I saw it found a new position and wasn't just re-reporting the old.

GPSFan commented 8 months ago

The fixes for the M8 series are not complete yet, but those that got into 2.2.20 will certainly help. As always, a good view of the sky is important especially with the tiny antennas that come with the t-beams.

BobsBlueNorth commented 8 months ago

It definitely is working. Both my malfunctioning T-Beams work as they should. For the past few hours they are updating their positions quickly and flawlessly as I drive around town.