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 not working on LILYGO T-Beam 1.2 #5893

Closed
PaoloneM opened this issue Jan 19, 2025 · 6 comments
Closed

[Bug]: GPS not working on LILYGO T-Beam 1.2 #5893

PaoloneM opened this issue Jan 19, 2025 · 6 comments
Labels
bug Something isn't working

Comments

@PaoloneM
Copy link

Category

Other

Hardware

T-Beam

Firmware Version

2.5.18.89ebafc Beta

Description

Hello, I filled a bug since I wasn't able to find a more suitable issue type... Might be I simply need some support on warming up my board. I just received a brand new LILYGO T-Beam V1.2 ESP32 LoRa 433MHz and I'm not able to have the GPS module working.

I left the board on open sky for a couple of hours but still no GPS signal.

On the iOS app no position log is available:

Image

Also tested firmware 2.5.19.f9876cf Alpha

My board's config:

─➤  meshtastic --export-config
# start of Meshtastic configure yaml

config:
  bluetooth:
    enabled: true
    fixedPin: xxxxxx
    mode: FIXED_PIN
  device:
    nodeInfoBroadcastSecs: 3600
    role: CLIENT_MUTE
  display:
    screenOnSecs: 600
  lora:
    hopLimit: 3
    region: EU_433
    sx126xRxBoostedGain: true
    txEnabled: true
    txPower: 30
    usePreset: true
  network:
    ntpServer: meshtastic.pool.ntp.org
  position:
    broadcastSmartMinimumDistance: 100
    broadcastSmartMinimumIntervalSecs: 30
    gpsUpdateInterval: 15
    positionBroadcastSecs: 900
    positionBroadcastSmartEnabled: true
    positionFlags: 811
  power:
    lsSecs: 300
    minWakeSecs: 10
    sdsSecs: 4294967295
    waitBluetoothSecs: 60
module_config:
  ambientLighting:
    blue: 188
    current: 10
    green: 44
    red: 131
  detectionSensor:
    detectionTriggeredHigh: true
    minimumBroadcastSecs: 45
  mqtt:
    address: mqtt.meshtastic.org
    encryptionEnabled: true
    password: large4cats
    root: msh/EU_433
    username: meshdev
owner: xxxx
owner_short: R_T1

From console logs seems that the timestamp was aquired, but position is not. So seems that the communication with the module is working. Might be some more time is needed to lock the position for the first time? Is there a way to debug deeper the GPS module?

Thanks for your support.

Relevant log output

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 (858) esp_core_dump_flash: No core dump??????????found!
E (858) esp_core_dump_flash: No core dump partition found!
[    11][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 480 / 2 = 240 Mhz, APB: 80000000 Hz
[   474][I][esp32-hal-psram.c:96] psramInit(): PSRAM enabled
WARNING file:mesh_interface.py _sendToRadio line:682 Not sending packet because protocol use is disabled by noProto
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 (16384/1048576 Bytes):
DEBUG | ??:??:?? 0  /prefs/channels.proto (66 Bytes)
DEBUG | ??:??:?? 0  /prefs/config.proto (105 Bytes)
DEBUG | ??:??:?? 0  /prefs/db.proto (405 Bytes)
DEBUG | ??:??:?? 0  /prefs/module.proto (96 Bytes)
[   615][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=21 scl=22 freq=100000
[   629][I][XPowersCommon.tpp:445] begin(): SDA:21 SCL:22
[   634][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
INFO  | ??:??:?? 0 AXP2101 PMU init succeeded
[   657][D][XPowersAXP2101.tpp:3036] setInterruptImpl(): DISABLE - HEX:0xffffffff

DEBUG | ??:??:?? 0 DC1  : +   Voltage:3300 mV
DEBUG | ??:??:?? 0 DC2  : -   Voltage:500 mV
DEBUG | ??:??:?? 0 DC3  : -   Voltage:500 mV
DEBUG | ??:??:?? 0 DC4  : -   Voltage:1800 mV
DEBUG | ??:??:?? 0 ALDO1: -   Voltage:1800 mV
DEBUG | ??:??:?? 0 ALDO2: +   Voltage:3300 mV
DEBUG | ??:??:?? 0 ALDO3: +   Voltage:3300 mV
DEBUG | ??:??:?? 0 ALDO4: -   Voltage:3300 mV
DEBUG | ??:??:?? 0 BLDO1: -   Voltage:1800 mV
DEBUG | ??:??:?? 0 BLDO2: -   Voltage:3300 mV
DEBUG | ??:??:?? 0 Battery: usbPower=1, isCharging=1, batMv=4154, batPct=90
INFO  | ??:??:?? 0 Scan for i2c devices
[   737][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
DEBUG | ??:??:?? 0 Scan for I2C devices on port 1
INFO  | ??:??:?? 0 AXP192/AXP2101 found at address 0x34
INFO  | ??:??:?? 0 SSD1306 found at address 0x3c
INFO  | ??:??:?? 0 SSD1306 found at address 0x3c
DEBUG | ??:??:?? 0 0x4 subtype probed in 2 tries
INFO  | ??:??:?? 0 2 I2C devices found
DEBUG | ??:??:?? 0 acc_info = 0
INFO  | ??:??:?? 0 S:B:4,2.5.18.89ebafc
INFO  | ??:??:?? 0 Build timestamp: 1735422939
DEBUG | ??:??:?? 0 Total heap: 191320
DEBUG | ??:??:?? 0 Free heap: 156788
DEBUG | ??:??:?? 0 Total PSRAM: 4192107
DEBUG | ??:??:?? 0 Free PSRAM: 4191863
DEBUG | ??:??:?? 0 NVS: UsedEntries 219, FreeEntries 411, AllEntries 630, NameSpaces 7
DEBUG | ??:??:?? 0 Setup Preferences in Flash Storage
DEBUG | ??:??:?? 0 Number of Device Reboots: 40
ESP_ERROR_CHECK_WITHOUT_ABORT failed: esp_err_t 0x105 (ESP_ERR_NOT_FOUND) at 0x4009894b
file: "src/platform/esp32/BleOta.cpp" line 16
func: static const esp_partition_t* BleOta::findEspOtaAppPartition()
expression: esp_ota_get_partition_description(part, &app_desc)
ESP_ERROR_CHECK_WITHOUT_ABORT failed: esp_err_t 0x102 (ESP_ERR_INVALID_ARG) at 0x4009894b
file: "src/platform/esp32/BleOta.cpp" line 30
func: static String BleOta::getOtaAppVersion()
expression: esp_ota_get_partition_description(part, &app_desc)
INFO  | ??:??:?? 0 No OTA firmware available
INFO  | ??:??:?? 0 Init NodeDB
[   903][E][vfs_api.cpp:105] open(): /littlefs/static/static does not exist, no permits for creation
INFO  | ??:??:?? 0 Load /prefs/db.proto
INFO  | ??:??:?? 0 Loaded /prefs/db.proto successfully
INFO  | ??:??:?? 0 Loaded saved devicestate version 23, with nodecount: 2
INFO  | ??:??:?? 0 Load /prefs/config.proto
INFO  | ??:??:?? 0 Loaded /prefs/config.proto successfully
INFO  | ??:??:?? 0 Loaded saved config version 23
INFO  | ??:??:?? 1 Load /prefs/module.proto
INFO  | ??:??:?? 1 Loaded /prefs/module.proto successfully
INFO  | ??:??:?? 1 Loaded saved moduleConfig version 23
INFO  | ??:??:?? 1 Load /prefs/channels.proto
INFO  | ??:??:?? 1 Loaded /prefs/channels.proto successfully
INFO  | ??:??:?? 1 Loaded saved channelFile version 23
[  1067][E][vfs_api.cpp:105] open(): /littlefs/prefs/uiconfig.proto does not exist, no permits for creation
ERROR | ??:??:?? 1 Could not open / read /prefs/uiconfig.proto
DEBUG | ??:??:?? 1 cleanupMeshDB purged 0 entries
DEBUG | ??:??:?? 1 Use nodenum 0xbb832cbc
DEBUG | ??:??:?? 1 Number of Device Reboots: 40
DEBUG | ??:??:?? 1 Expand short PSK #0
INFO  | ??:??:?? 1 Wanted region 2, using EU_433
INFO  | ??:??:?? 1 Save /prefs/db.proto
DEBUG | ??:??:?? 1 Use GPIO38 for button
DEBUG | ??:??:?? 1 SPI.begin(SCK=5, MISO=19, MOSI=27, NSS=18)
DEBUG | ??:??:?? 1 Use compiled/slipstreamed tzplaceholder
DEBUG | ??:??:?? 1 Set Timezone to GMT0
DEBUG | ??:??:?? 1 Read RTC time as 1
INFO  | ??:??:?? 1 GPS power state move from OFF to ACTIVE
DEBUG | ??:??:?? 1 Use GPIO34 for GPS RX
DEBUG | ??:??:?? 1 Use GPIO12 for GPS TX
DEBUG | ??:??:?? 1 NeighborInfoModule is disabled
DEBUG | ??:??:?? 1 Rescan for I2C keyboard
DEBUG | ??:??:?? 1 Scan for I2C devices on port 1
DEBUG | ??:??:?? 1 Scan address 0x1f
DEBUG | ??:??:?? 1 Scan address 0x55
DEBUG | ??:??:?? 1 Scan address 0x5a
DEBUG | ??:??:?? 1 Scan address 0x5f
INFO  | ??:??:?? 1 External Notification Module Disabled
INFO  | ??:??:?? 1 Turn on screen
DEBUG | ??:??:?? 1 haveGlyphs=1
DEBUG | ??:??:?? 1 waypoint wants a UI Frame
DEBUG | ??:??:?? 1 canned wants a UI Frame
DEBUG | ??:??:?? 1 RF95Interface(cs=18, irq=26, rst=23, busy=33)
INFO  | ??:??:?? 1 Start meshradio init
INFO  | ??:??:?? 1 Radio freq=433.125, config.lora.frequency_offset=0.000
INFO  | ??:??:?? 1 Set radio: region=EU_433, name=ResegUp, config=0, ch=0, power=30
INFO  | ??:??:?? 1 myRegion->freqStart -> myRegion->freqEnd: 433.000000 -> 434.000000 (1.000000 MHz)
INFO  | ??:??:?? 1 numChannels: 4 x 250.000kHz
INFO  | ??:??:?? 1 channel_num: 1
INFO  | ??:??:?? 1 frequency: 433.125000
INFO  | ??:??:?? 1 Slot time: 77 msec
INFO  | ??:??:?? 1 Set radio: final power level=20
DEBUG | ??:??:?? 1 Current limit set to 100.000000
DEBUG | ??:??:?? 1 Current limit set result 0
INFO  | ??:??:?? 1 RF95 init result 0
INFO  | ??:??:?? 1 Frequency set to 433.125000
INFO  | ??:??:?? 1 Bandwidth set to 250.000000
INFO  | ??:??:?? 1 Power output set to 20
INFO  | ??:??:?? 1 RF95 init success
INFO  | ??:??:?? 1 Not using WIFI
DEBUG | ??:??:?? 1 LoRA bitrate = 118.394310 bytes / sec
IN?[0m| ??:??:?? 1 PowerFSM init, USB power=1
DEBUG | ??:??:?? 1 State: BOOT
[  1503][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 320 / 4 = 80 Mhz, APB: 80000000 Hz
DEBUG | ??:??:?? 1 [Power] Battery: usbPower=1, isCharging=1, batMv=4155, batPct=90
DEBUG | ??:??:?? 1 [Screen] Screen: Started...
DEBUG | ??:??:?? 1 [Screen] haveGlyphs=1
DEBUG | ??:??:?? 1 [GPS] Probe for GPS at 9600
DEBUG | ??:??:?? 1 [GPS] Trying $PDTINFO (UC6580)...
DEBUG | ??:??:?? 2 [GPS] Trying $PDTINFO (UM600)...
DEBUG | ??:??:?? 2 [GPS] Trying $PCAS06,1*1A (ATGM336H)...
DEBUG | ??:??:?? 3 [GPS] Trying $PCAS06,1*1A (ATGM332D)...
DEBUG | ??:??:?? 3 [GPS] Trying $PAIR021*39 (AG3335)...
DEBUG | ??:??:?? 4 [GPS] Trying $PAIR021*39 (AG3352)...
DEBUG | ??:??:?? 4 [GPS] Trying $PQTMVERNO*58 (LC86)...
DEBUG | ??:??:?? 5 [GPS] Trying $PCAS06,0*1B (L76K)...
DEBUG | ??:??:?? 5 [GPS] Trying $PMTK605*31 (L76B)...
DEBUG | ??:??:?? 6 [GPS] Trying $PMTK605*31 (PA1616S)...
DEBUG | ??:??:?? 6 [GPS] Module Info :
DEBUG | ??:??:?? 6 [GPS] Soft version: 7.03 (45969)
DEBUG | ??:??:?? 6 [GPS] Hard version: 00040007
DEBUG | ??:??:?? 6 [GPS] Extensions:0
INFO  | ??:??:?? 6 [GPS] U-blox 6 detected, using 6 Module
INFO  | ??:??:?? 9 [GPS] GNSS module config saved!
DEBUG | ??:??:?? 9 [GPS] Publish pos@0:2, hasVal=0, Sats=0, GPSlock=0
DEBUG | ??:??:?? 9 [GPS] No GPS lock
DEBUG | ??:??:?? 9 [GPS] onGPSChanged() pos@0 time=0 lat=0 lon=0 alt=0
INFO  | ??:??:?? 9 [GPS] updatePosition LOCAL pos@0 time=0 lat=0 lon=0 alt=0
DEBUG | ??:??:?? 9 [GPS] Set local position: lat=0 lon=0 time=0 timestamp=0
DEBUG | ??:??:?? 9 [GPS] Node status update: 2 online, 2 total
INFO  | ??:??:?? 9 [RangeTest] Range Test Module - Disabled
INFO  | ??:??:?? 9 [PowerFSM] Init the NimBLE bluetooth module
INFO  | ??:??:?? 9 [Screen] Done with boot screen
DEBUG | ??:??:?? 9 [Screen] Show standard frames
DEBUG | ??:??:?? 9 [Screen] Show 0 module frames
DEBUG | ??:??:?? 9 [Screen] Total frame count: 103
DEBUG | ??:??:?? 9 [Screen] Added modules.  numframes: 0
DEBUG | ??:??:?? 9 [Screen] Finished build frames. numframes: 3
DEBUG | ??:??:?? 10 [GPS] NMEA GPS time 2025-01-19 21:18:13 age 4
DEBUG | ??:??:?? 10 [GPS] Upgrade time to quality GPS
DEBUG | 21:18:13 10 [GPS] Read RTC time as 1737321493
DEBUG | 21:18:13 10 [GPS] Publish pos@0:2, hasVal=0, Sats=0, GPSlock=0
DEBUG | 21:18:13 10 [GPS] onGPSChanged() pos@0 time=1737321493 lat=0 lon=0 alt=0
INFO  | 21:18:13 10 [GPS] updatePosition LOCAL pos@0 time=1737321493 lat=0 lon=0 alt=0
DEBUG | 21:18:13 10 [GPS] Set local position: lat=0 lon=0 time=1737321493 timestamp=0
DEBUG | 21:18:13 10 [GPS] Node status update: 1 online, 2 total
INFO  | 21:18:14 12 BLE authentication complete
INFO  | 21:18:15 12 To Radio onwrite
DEBUG | 21:18:15 12 New ToRadio packet
INFO  | 21:18:15 12 Client wants config, nonce=7
DEBUG | 21:18:15 12 Got 4 files in manifest
INFO  | 21:18:15 12 Start API client config
DEBUG | 21:18:15 12 FromRadio=STATE_SEND_MY_INFO
INFO  | 21:18:15 13 getFromRadio=STATE_SEND_UIDATA
DEBUG | 21:18:15 13 Send My NodeInfo
DEBUG | 21:18:15 13 Send device metadata
DEBUG | 21:18:16 13 Send channels 8
DEBUG | 21:18:16 13 Send config: device
DEBUG | 21:18:16 13 Send config: position
DEBUG | 21:18:16 13 Send config: power
DEBUG | 21:18:16 13 Send config: network
DEBUG | 21:18:16 13 Send config: display
DEBUG | 21:18:16 14 Send config: lora
DEBUG | 21:18:16 14 Send config: bluetooth
DEBUG | 21:18:16 14 Send config: security
DEBUG | 21:18:16 14 Send config: sessionkey
DEBUG | 21:18:17 14 Send module config: mqtt
DEBUG | 21:18:17 14 Send module config: serial
DEBUG | 21:18:17 14 Send module config: ext notification
DEBUG | 21:18:17 14 Send module config: store forward
DEBUG | 21:18:17 14 Send module config: range test
DEBUG | 21:18:17 14 Send module config: telemetry
DEBUG | 21:18:17 14 Send module config: canned message
DEBUG | 21:18:17 14 Send module config: audio
DEBUG | 21:18:17 14 Send module config: remote hardware
DEBUG | 21:18:17 14 [Blink] Send module config: neighbor info
DEBUG | 21:18:17 15 Send module config: ambient lighting
DEBUG | 21:18:17 15 Send module config: detection sensor
DEBUG | 21:18:17 15 Send module config: paxcounter
DEBUG | 21:18:17 15 Send known nodes
INFO  | 21:18:17 15 nodeinfo: num=0x1c2f13fc, lastseen=1737310162, id=!1c2f13fc, name=IU2RPI
DEBUG | 21:18:18 15 Send known nodes
DEBUG | 21:18:18 15 Done sending nodeinfo
DEBUG | 21:18:18 15 FromRadio=STATE_SEND_FILEMANIFEST
DEBUG | 21:18:18 15 File: /prefs/channels.proto (66) bytes
DEBUG | 21:18:18 15 [GPS] FromRadio=STATE_SEND_FILEMANIFEST
DEBUG | 21:18:18 15 File: /prefs/config.proto (105) bytes
DEBUG | 21:18:18 15 FromRadio=STATE_SEND_FILEMANIFEST
DEBUG | 21:18:18 15 File: /prefs/db.proto (405) bytes
DEBUG | 21:18:18 15 FromRadio=STATE_SEND_FILEMANIFEST
DEBUG | 21:18:18 15 File: /prefs/module.proto (96) bytes
DEBUG | 21:18:18 15 FromRadio=STATE_SEND_FILEMANIFEST
INFO  | 21:18:18 15 Config Send Complete
INFO  | 21:18:18 15 To Radio onwrite
DEBUG | 21:18:18 15 New ToRadio packet
DEBUG | 21:18:18 15 PACKET FROM PHONE (id=0x6de5e29a fr=0xbb832cbc to=0xbb832cbc, WantAck=1, HopLim=0 Ch=0x0 Portnum=6 priority=70)
DEBUG | 21:18:18 15 Enqueued local (id=0x6de5e29a fr=0x00000000 to=0xbb832cbc, WantAck=1, HopLim=0 Ch=0x0 Portnum=6 rxtime=1737321498 priority=70)
DEBUG | 21:18:18 15 [Router] Add packet record (id=0x6de5e29a fr=0x00000000 to=0xbb832cbc, WantAck=1, HopLim=0 Ch=0x0 Portnum=6 rxtime=1737321498 priority=70)
DEBUG | 21:18:18 15 [Router] handleReceived(REMOTE) (id=0x6de5e29a fr=0x00000000 to=0xbb832cbc, WantAck=1, HopLim=0 Ch=0x0 Portnum=6 rxtime=1737321498 priority=70)
DEBUG | 21:18:18 15 [Router] Module 'Admin' wantsPacket=1
INFO  | 21:18:18 15 [Router] Received Admin from=0x0, id=0x6de5e29a, portnum=6, payloadlen=6
INFO  | 21:18:18 15 [Router] Handle admin payload 43
INFO  | 21:18:18 15 [Router] Client received set_time_only command
DEBUG | 21:18:18 15 [Router] Current RTC quality: GPS. Ignore time of RTC quality of NTP
DEBUG | 21:18:18 15 [Router] Module 'Admin' considered
DEBUG | 21:18:18 15 [Router] Module 'routing' wantsPacket=1
INFO  | 21:18:18 15 [Router] Received routing from=0x0, id=0x6de5e29a, portnum=6, payloadlen=6
DEBUG | 21:18:18 15 [Router] Routing sniffing (id=0x6de5e29a fr=0x00000000 to=0xbb832cbc, WantAck=1, HopLim=0 Ch=0x0 Portnum=6 rxtime=1737321498 priority=70)
DEBUG | 21:18:18 15 [Router] Initial packet id 102849173
DEBUG | 21:18:18 15 [Router] Partially randomized packet id 3672568470
DEBUG | 21:18:18 15 [Router] Enqueued local (id=0xdae6f296 fr=0xbb832cbc to=0xbb832cbc, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=6de5e29a rxtime=1737321498 priority=120)
DEBUG | 21:18:18 15 [Router] Module 'routing' considered
DEBUG | 21:18:18 15 [Router] Rx someone rebroadcasting for us (id=0xdae6f296 fr=0xbb832cbc to=0xbb832cbc, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=6de5e29a rxtime=1737321498 priorit
DEBUG | 21:18:18 15 [Router] Didn't find pending packet
DEBUG | 21:18:18 15 [Router] Add packet record (id=0xdae6f296 fr=0xbb832cbc to=0xbb832cbc, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=6de5e29a rxtime=1737321498 priority=120)
DEBUG | 21:18:18 15 [Router] handleReceived(REMOTE) (id=0xdae6f296 fr=0xbb832cbc to=0xbb832cbc, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=6de5e29a rxtime=1737321498 priority=120)
DEBUG | 21:18:18 15 [Router] Module 'routing' wantsPacket=1
INFO  | 21:18:18 15 [Router] Received routing from=0xbb832cbc, id=0xdae6f296, portnum=5, payloadlen=2
DEBUG | 21:18:18 15 [Router] Routing sniffing (id=0xdae6f296 fr=0xbb832cbc to=0xbb832cbc, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=6de5e29a rxtime=1737321498 priority=120)
DEBUG | 21:18:18 15 [Router] Received a ACK for 0x6de5e29a, stopping retransmissions
DEBUG | 21:18:18 15 [Router] Delivering rx packet (id=0xdae6f296 fr=0xbb832cbc to=0xbb832cbc, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=6de5e29a rxtime=1737321498 priority=120)
DEBUG | 21:18:18 15 [Router] Update DB node 0xbb832cbc, rx_time=1737321498
DEBUG | 21:18:18 15 [Router] Forwarding to phone (id=0xdae6f296 fr=0xbb832cbc to=0xbb832cbc, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=6de5e29a rxtime=1737321498 priority=120)
DEBUG | 21:18:18 15 [Router] Module 'routing' considered
INFO  | 21:18:18 15 Tell client we have new packets 3
INFO  | 21:18:18 15 BLE notify fromNum
DEBUG | 21:18:18 15 FromRadio=STATE_SEND_PACKETS
DEBUG | 21:18:18 16 FromRadio=STATE_SEND_PACKETS
DEBUG | 21:18:18 16 FromRadio=STATE_SEND_PACKETS
DEBUG | 21:18:18 16 phone downloaded packet (id=0xdae6f296 fr=0xbb832cbc to=0xbb832cbc, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=6de5e29a rxtime=1737321498 priority=120)
DEBUG | 21:18:24 21 [Power] Battery: usbPower=1, isCharging=1, batMv=4159, batPct=90
DEBUG | 21:18:31 28 [RadioIf] Lora RX (id=0x15b5163d fr=0x1c2f13fc to=0xffffffff, WantAck=0, HopLim=3 Ch=0x63 encrypted len=45 rxSNR=12 rxRSSI=-72 hopStart=3)
DEBUG | 21:18:31 28 [RadioIf] Packet RX: 567ms
DEBUG | 21:18:31 28 [Router] Add packet record (id=0x15b5163d fr=0x1c2f13fc to=0xffffffff, WantAck=0, HopLim=3 Ch=0x63 encrypted len=45 rxSNR=12 rxRSSI=-72 hopStart=3)
DEBUG | 21:18:31 28 [Router] Use channel 0 (hash 0x63)
DEBUG | 21:18:31 28 [Router] Expand short PSK #0
DEBUG | 21:18:31 28 [Router] Use AES0 key!
DEBUG | 21:18:31 28 [Router] decoded message (id=0x15b5163d fr=0x1c2f13fc to=0xffffffff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1737321511 rxSNR=12 rxRSSI=-72 hopStart=3)
DEBUG | 21:18:31 28 [Router] handleReceived(REMOTE) (id=0x15b5163d fr=0x1c2f13fc to=0xffffffff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1737321511 rxSNR=12 rxRSSI=-72 hopStart=3)
DEBUG | 21:18:31 28 [Router] Module 'position' wantsPacket=1
INFO  | 21:18:31 28 [Router] Received position from=0x1c2f13fc, id=0x15b5163d, portnum=3, payloadlen=23
DEBUG | 21:18:31 28 [Router] POSITION node=1c2f13fc l=23 lat=458613732 lon=93854591 msl=216 hae=0 geo=0 pdop=0 hdop=0 vdop=0 siv=0 fxq=0 fxt=0 pts=0 time=1737321507
DEBUG | 21:18:31 28 [Router] Ignore time from mesh because we have a GPS, RTC, or Phone/NTP time source in the past day
INFO  | 21:18:31 28 [Router] updatePosition REMOTE node=0x1c2f13fc time=1737321507 lat=458613732 lon=93854591
DEBUG | 21:18:31 28 [Router] Node status update: 1 online, 2 total
DEBUG | 21:18:31 28 [Router] Module 'position' considered
DEBUG | 21:18:31 28 [Router] Module 'routing' wantsPacket=1
INFO  | 21:18:31 28 [Router] Received routing from=0x1c2f13fc, id=0x15b5163d, portnum=3, payloadlen=23
DEBUG | 21:18:31 28 [Router] Routing sniffing (id=0x15b5163d fr=0x1c2f13fc to=0xffffffff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1737321511 rxSNR=12 rxRSSI=-72 hopStart=3)
DEBUG | 21:18:31 28 [Router] No rebroadcast: Role = CLIENT_MUTE or Rebroadcast Mode = NONE
DEBUG | 21:18:31 28 [Router] Delivering rx packet (id=0x15b5163d fr=0x1c2f13fc to=0xffffffff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1737321511 rxSNR=12 rxRSSI=-72 hopStart=3)
DEBUG | 21:18:31 28 [Router] Update DB node 0x1c2f13fc, rx_time=1737321511
DEBUG | 21:18:31 28 [Router] Forwarding to phone (id=0x15b5163d fr=0x1c2f13fc to=0xffffffff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1737321511 rxSNR=12 rxRSSI=-72 hopStart=3)
DEBUG | 21:18:31 28 [Router] Module 'routing' considered
INFO  | 21:18:31 28 Tell client we have new packets 4
INFO  | 21:18:31 28 BLE notify fromNum
DEBUG | 21:18:31 28 FromRadio=STATE_SEND_PACKETS
DEBUG | 21:18:31 28 phone downloaded packet (id=0x15b5163d fr=0x1c2f13fc to=0xffffffff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 rxtime=1737321511 rxSNR=12 rxRSSI=-72 hopStart=3)
INFO  | 21:18:34 31 [NodeInfo] Send our nodeinfo to mesh (wantReplies=1)
INFO  | 21:18:34 31 [NodeInfo] Send owner !bb832cbc/IU2RPI/R_T1
DEBUG | 21:18:34 31 [NodeInfo] Partially randomized packet id 3267963543
DEBUG | 21:18:34 31 [NodeInfo] Update DB node 0xbb832cbc, rx_time=1737321514
DEBUG | 21:18:34 31 [NodeInfo] handleReceived(LOCAL) (id=0xc2c92a97 fr=0xbb832cbc to=0xffffffff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP rxtime=1737321514 priority=10)
DEBUG | 21:18:34 31 [NodeInfo] No modules interested in portnum=4, src=LOCAL
DEBUG | 21:18:34 31 [NodeInfo] Add packet record (id=0xc2c92a97 fr=0xbb832cbc to=0xffffffff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP rxtime=1737321514 priority=10)
DEBUG | 21:18:34 31 [NodeInfo] Expand short PSK #0
DEBUG | 21:18:34 31 [NodeInfo] Use AES0 key!
DEBUG | 21:18:34 31 [NodeInfo] enqueue for send (id=0xc2c92a97 fr=0xbb832cbc to=0xffffffff, WantAck=0, HopLim=3 Ch=0x63 encrypted len=63 rxtime=1737321514 hopStart=3 priority=10)
DEBUG | 21:18:34 31 [NodeInfo] txGood=0,txRelay=0,rxGood=1,rxBad=0
INFO  | 21:18:34 31 Tell client we have new packets 5
INFO  | 21:18:34 31 BLE notify fromNum
@PaoloneM PaoloneM added the bug Something isn't working label Jan 19, 2025
@fifieldt
Copy link
Contributor

Thanks for writing in in such detail. I can see from the logs that your GPS was detected successfully as a Ublox 6.

So, unless there's a bug, most likely a settings thing. Are you outdoor? How long did you wait?

@PaoloneM
Copy link
Author

HI, thnaks for you quick reply.

I left the bourd outdoor for 2 hour approximately on my car's dashboard, right behind the windshield. My car also has full glass ceiling, I generally have no issues with GPS devices in it.

I'll try a longer time / different location.

@fifieldt
Copy link
Contributor

Ok, so reception is probably not an issue then. Will have to look at the settings!

@PaoloneM
Copy link
Author

Hi, the position was finally locked after several hours. It's not accurate and signal is lost easily, it's probably an antenna issue, I'll try a different one. I'll then review the config and the source code for a better understanding of the settings.

I'm going to close this issue, adding some info about current config and related log for reference and personal future investigation.

I updated the module conf withe the following:

gpsUpdateInterval: 15
positionBroadcastSecs: 30

and the corresponding relevant log is

DEBUG | ??:??:?? 10 [GPS] NMEA GPS time 2025-01-20 21:46:26 age 4
DEBUG | ??:??:?? 10 [GPS] Upgrade time to quality GPS
DEBUG | 21:46:26 10 [GPS] Read RTC time as 1737409586
DEBUG | 21:46:26 10 [GPS] Publish pos@0:2, hasVal=0, Sats=0, GPSlock=0
DEBUG | 21:46:26 10 [GPS] onGPSChanged() pos@0 time=1737409586 lat=0 lon=0 alt=0
INFO  | 21:46:26 10 [GPS] updatePosition LOCAL pos@0 time=1737409586 lat=0 lon=0 alt=0
DEBUG | 21:46:26 10 [GPS] Set local position: lat=0 lon=0 time=1737409586 timestamp=0
DEBUG | 21:46:26 10 [GPS] Node status update: 1 online, 2 total

...


WARN  | 22:01:16 901 [GPS] Couldn't publish a valid location: didn't get a GPS lock in time
DEBUG | 22:01:16 901 [GPS] Took 900s to get lock
DEBUG | 22:01:16 901 [GPS] Predict 0s to get next lock
DEBUG | 22:01:16 901 [GPS] 14s until next search
DEBUG | 22:01:16 901 [GPS] gps_update_interval >= 0s needed to justify hardsleep
INFO  | 22:01:17 901 [GPS] GPS power state move from ACTIVE to HARDSLEEP
DEBUG | 22:01:17 901 [GPS] Publish pos@0:2, hasVal=0, Sats=0, GPSlock=0
DEBUG | 22:01:17 901 [GPS] onGPSChanged() pos@0 time=1737410477 lat=0 lon=0 alt=0
INFO  | 22:01:17 901 [GPS] updatePosition LOCAL pos@0 time=1737410477 lat=0 lon=0 alt=0
DEBUG | 22:01:17 901 [GPS] Set local position: lat=0 lon=0 time=1737410477 timestamp=0
DEBUG | 22:01:17 901 [GPS] Node status update: 1 online, 2 total
DEBUG | 22:01:17 902 [Power] Battery: usbPower=1, isCharging=1, batMv=4144, batPct=93
INFO  | 22:01:32 916 [GPS] GPS power state move from HARDSLEEP to ACTIVE

...


DEBUG | 22:06:48 1232 [Router] Set local position: lat=0 lon=0 time=1737410808 timestamp=0
WARN  | 22:06:48 1232 [Router] Skip position send because lat/lon are zero!
INFO  | 22:06:48 1232 [Router] Asked module 'position' to send a response
DEBUG | 22:06:48 1232 [Router] Module 'routing' wantsPacket=1

...

WARN  | 22:16:32 1816 [GPS] Couldn't publish a valid location: didn't get a GPS lock in time
DEBUG | 22:16:32 1816 [GPS] Took 900s to get lock
DEBUG | 22:16:32 1816 [GPS] Predict 900s to get next lock
DEBUG | 22:16:32 1816 [GPS] 0s until next search
INFO  | 22:16:32 1816 [GPS] GPS power state move from ACTIVE to IDLE
DEBUG | 22:16:32 1816 [GPS] Publish pos@0:2, hasVal=0, Sats=0, GPSlock=0
DEBUG | 22:16:32 1816 [GPS] onGPSChanged() pos@0 time=1737411392 lat=0 lon=0 alt=0
INFO  | 22:16:32 1816 [GPS] updatePosition LOCAL pos@0 time=1737411392 lat=0 lon=0 alt=0
DEBUG | 22:16:32 1816 [GPS] Set local position: lat=0 lon=0 time=1737411392 timestamp=0
DEBUG | 22:16:32 1816 [GPS] Node status update: 2 online, 2 total
INFO  | 22:16:37 1821 [GPS] GPS power state move from IDLE to ACTIVE

Whit those settings I was expecting to see more activity (every 15" some GPS info, every 30" some radio acrivity to send current position even if not locked).

The docs tell:

The GPS updates will be sent out every broadcast interval, either with the actual GPS location or an empty location if no GPS fix was achieved.

While the log says

WARN | 22:06:48 1232 [Router] Skip position send because lat/lon are zero!

and this seems to be in conflict.

Thanks again for the support! 👋

@fifieldt
Copy link
Contributor

Thanks so much for the update. Feel free to open another issue if you need it!

@fifieldt
Copy link
Contributor

It might be the refresh times are too low, too.

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
2 participants
@fifieldt @PaoloneM