firmware
firmware copied to clipboard
meshtastic
[Bug]: Telemetry Module disables left and right keys on CardKB
Category
Other
Hardware
Heltec V3
Firmware Version
2.3.6.7a3570a Alpha
Description
I have a Heltec V3 with a cardKB and a BME680 (Temperature+Pressure+Humidity+Gas Sensor). When I enable the Telemetry module it no longer lets the Left and Right buttons to work on the CardKB. Everything else works and the module shows the temetry data as it should but just those keys stop working.
DEBUG | 22:43:45 66 [cardKB] Enter state: ON (is when I press the left and right keys.)
Relevant log output
E (9744) esp_core_dump_flash: Core dump flash config is corrupted! CRC=0x7bd5c66f instead of 0x0
Rebooting...
ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0xc (RTC_SW_CPU_RST),boot:0x8 (SPI_FAST_FLASH_BOOT)
Saved PC:0x421440ee
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fce3808,len:0x44c
load:0x403c9700,len:0xbe4
load:0x403cc700,len:0x2a38
entry 0x403c98d4
E (357) esp_core_dump_flash: No core dump partition found!
E (357) esp_core_dump_flash: No core dump partition found!
��@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 (491520/1048576 Bytes):
DEBUG | ??:??:?? 0 /prefs/bsec.dat (221 Bytes)
DEBUG | ??:??:?? 0 /prefs/cannedConf.proto (30 Bytes)
DEBUG | ??:??:?? 0 /prefs/channels.proto (57 Bytes)
DEBUG | ??:??:?? 0 /prefs/config.proto (104 Bytes)
DEBUG | ??:??:?? 0 /prefs/db.proto (504 Bytes)
DEBUG | ??:??:?? 0 /prefs/module.proto (131 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-BMhLjTmL.css.gz (16270 Bytes)
DEBUG | ??:??:?? 0 /static/index-Bt2MYVVB.js.gz (203021 Bytes)
DEBUG | ??:??:?? 0 /static/index-CGqDWSD_.js.gz (348 Bytes)
DEBUG | ??:??:?? 0 /static/index.html.gz (548 Bytes)
DEBUG | ??:??:?? 0 /static/maplibre-gl-BXZZhNda.js.gz (210451 Bytes)
DEBUG | ??:??:?? 0 /static/robots.txt.gz (42 Bytes)
DEBUG | ??:??:?? 0 /static/site.webmanifest.gz (197 Bytes)
[ 790][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=41 scl=42 freq=100000
[ 792][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=17 scl=18 freq=100000
DEBUG | ??:??:?? 0 Using analog input 1 for battery level
INFO | ??:??:?? 0 ADCmod: ADC Characterization based on Two Point values and fitting curve coefficients stored in eFuse
INFO | ??:??:?? 0 Scanning for i2c devices...
[ 824][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
DEBUG | ??:??:?? 0 Scanning for i2c devices on port 2
DEBUG | ??:??:?? 0 I2C device found at address 0x5f
DEBUG | ??:??:?? 0 Wire.available() = 1
INFO | ??:??:?? 0 m5 cardKB found
DEBUG | ??:??:?? 1 I2C device found at address 0x77
DEBUG | ??:??:?? 1 Wire.available() = 1
INFO | ??:??:?? 1 BME-680 sensor found at address 0x77
[ 2900][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
DEBUG | ??:??:?? 2 Scanning for i2c devices on port 1
DEBUG | ??:??:?? 2 I2C device found at address 0x3c
INFO | ??:??:?? 2 ssd1306 display found
INFO | ??:??:?? 2 ssd1306 display found
DEBUG | ??:??:?? 2 0x3 subtype probed in 2 tries
INFO | ??:??:?? 2 3 I2C devices found
DEBUG | ??:??:?? 2 acc_info = 0
DEBUG | ??:??:?? 2 found i2c sensor meshtastic_TelemetrySensorType_BME680
INFO | ??:??:?? 2 Meshtastic hwvendor=43, swver=2.3.6.7a3570a
DEBUG | ??:??:?? 2 Setting random seed 3941282790
DEBUG | ??:??:?? 2 Total heap: 293960
DEBUG | ??:??:?? 2 Free heap: 257460
DEBUG | ??:??:?? 2 Total PSRAM: 0
DEBUG | ??:??:?? 2 Free PSRAM: 0
DEBUG | ??:??:?? 2 NVS: UsedEntries 86, FreeEntries 544, AllEntries 630, NameSpaces 3
DEBUG | ??:??:?? 2 Setup Preferences in Flash Storage
DEBUG | ??:??:?? 2 Number of Device Reboots: 35
DEBUG | ??:??:?? 2 OTA firmware version 0.2.1.ceca52c
INFO | ??:??:?? 2 Initializing NodeDB
INFO | ??:??:?? 3 Loading /prefs/db.proto
INFO | ??:??:?? 3 Loaded /prefs/db.proto successfully
INFO | ??:??:?? 3 Loaded saved devicestate version 22, with nodecount: 3
INFO | ??:??:?? 3 Loading /prefs/config.proto
INFO | ??:??:?? 3 Loaded /prefs/config.proto successfully
INFO | ??:??:?? 3 Loaded saved config version 22
INFO | ??:??:?? 3 Loading /prefs/module.proto
INFO | ??:??:?? 3 Loaded /prefs/module.proto successfully
INFO | ??:??:?? 3 Loaded saved moduleConfig version 22
INFO | ??:??:?? 3 Loading /prefs/channels.proto
INFO | ??:??:?? 3 Loaded /prefs/channels.proto successfully
INFO | ??:??:?? 3 Loaded saved channelFile version 22
[ 3216][E][vfs_api.cpp:105] open(): /littlefs/oem/oem.proto does not exist, no permits for creation
INFO | ??:??:?? 3 File /oem/oem.proto not found
DEBUG | ??:??:?? 3 cleanupMeshDB purged 0 entries
DEBUG | ??:??:?? 3 Using nodenum 0x336657cc
DEBUG | ??:??:?? 3 Number of Device Reboots: 35
DEBUG | ??:??:?? 3 Expanding short PSK #1
INFO | ??:??:?? 3 Wanted region 1, using US
INFO | ??:??:?? 3 Saving /prefs/db.proto
DEBUG | ??:??:?? 3 Using GPIO00 for button
[ 3525][D][Tone.cpp:124] tone(): _pin=6, frequency=185 Hz, duration=125 ms
[ 3525][D][Tone.cpp:31] tone_task(): Task received from queue TONE_START: _pin=6, frequency=185 Hz, duration=125 ms
[ 3534][D][Tone.cpp:33] tone_task(): Setup LED controll on channel 0
E (6722) ledc: ledc_get_duty(739): LEDC is not initialized
[ 3688][D][Tone.cpp:124] tone(): _pin=6, frequency=233 Hz, duration=125 ms
[ 3688][D][Tone.cpp:31] tone_task(): Task received from queue TONE_START: _pin=6, frequency=233 Hz, duration=125 ms
[ 3694][D][Tone.cpp:33] tone_task(): Setup LED controll on channel 0
[ 3850][D][Tone.cpp:124] tone(): _pin=6, frequency=277 Hz, duration=250 ms
[ 3850][D][Tone.cpp:31] tone_task(): Task received from queue TONE_START: _pin=6, frequency=277 Hz, duration=250 ms
[ 3856][D][Tone.cpp:33] tone_task(): Setup LED controll on channel 0
DEBUG | ??:??:?? 4 SPI.begin(SCK=9, MISO=11, MOSI=10, NSS=8)
DEBUG | ??:??:?? 4 Set Timezone to GMT0
DEBUG | ??:??:?? 4 Read RTC time as 10
DEBUG | ??:??:?? 4 Setting 46 to output.
INFO | ??:??:?? 4 Setting GPS power=1
DEBUG | ??:??:?? 4 GPS powerdown using GPS_EN_ACTIVE
DEBUG | ??:??:?? 4 WANT GPS=1
INFO | ??:??:?? 4 Setting GPS power=1
DEBUG | ??:??:?? 4 Using GPIO48 for GPS RX
DEBUG | ??:??:?? 4 Using GPIO47 for GPS TX
DEBUG | ??:??:?? 4 NeighborInfoModule is disabled
INFO | ??:??:?? 4 Loading /prefs/cannedConf.proto
INFO | ??:??:?? 4 Loaded /prefs/cannedConf.proto successfully
DEBUG | ??:??:?? 4 CannedMessage 0 is: 'Test'
DEBUG | ??:??:?? 4 CannedMessage 1 is: 'Help'
DEBUG | ??:??:?? 4 CannedMessage 2 is: 'Share Location'
DEBUG | ??:??:?? 4 CannedMessage 3 is: 'SOS'
INFO | ??:??:?? 4 CannedMessageModule is enabled
[ 4278][E][vfs_api.cpp:105] open(): /littlefs/prefs/ringtone.proto does not exist, no permits for creation
INFO | ??:??:?? 4 File /prefs/ringtone.proto not found
INFO | ??:??:?? 4 Initializing External Notification Module
INFO | ??:??:?? 4 Using Pin 6 in PWM mode
INFO | ??:??:?? 4 Turning on screen
DEBUG | ??:??:?? 4 Module wants a UI Frame
DEBUG | ??:??:?? 4 SX126xInterface(cs=8, irq=14, rst=12, busy=13)
DEBUG | ??:??:?? 4 SX126X_DIO3_TCXO_VOLTAGE defined, using DIO3 as TCXO reference voltage at 1.800000 V
INFO | ??:??:?? 4 Starting meshradio init...
DEBUG | ??:??:?? 4 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=0, time 231 ms
DEBUG | ??:??:?? 4 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=253, time 2115 ms
INFO | ??:??:?? 4 Radio freq=906.875, config.lora.frequency_offset=0.000
INFO | ??:??:?? 4 Set radio: region=US, name=LongFast, config=0, ch=19, power=30
INFO | ??:??:?? 4 Radio myRegion->freqStart -> myRegion->freqEnd: 902.000000 -> 928.000000 (26.000000 mhz)
INFO | ??:??:?? 4 Radio myRegion->numChannels: 104 x 250.000kHz
INFO | ??:??:?? 4 Radio channel_num: 20
INFO | ??:??:?? 4 Radio frequency: 906.875000
INFO | ??:??:?? 4 Slot time: 42 msec
INFO | ??:??:?? 4 Set radio: final power level=22
INFO | ??:??:?? 4 SX126x init result 0
INFO | ??:??:?? 4 Frequency set to 906.875000
INFO | ??:??:?? 4 Bandwidth set to 250.000000
INFO | ??:??:?? 4 Power output set to 22
DEBUG | ??:??:?? 4 Current limit set to 140.000000
DEBUG | ??:??:?? 4 Current limit set result 0
DEBUG | ??:??:?? 4 Setting DIO2 as RF switch
DEBUG | ??:??:?? 4 SX126X_RXEN not defined, defaulting to RADIOLIB_NC
DEBUG | ??:??:?? 4 SX126X_TXEN not defined, defaulting to RADIOLIB_NC
DEBUG | ??:??:?? 4 Using MCU pin -1 as RXEN and pin -1 as TXEN to control RF switching
INFO | ??:??:?? 4 Set RX gain to boosted mode; result: 0
INFO | ??:??:?? 4 SX1262 Radio init succeeded, using SX1262 radio
INFO | ??:??:?? 4 Not using WIFI
DEBUG | ??:??:?? 4 (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=237, time 2000 ms
DEBUG | ??:??:?? 4 LoRA bitrate = 118.500000 bytes / sec
INFO | ??:??:?? 4 PowerFSM init, USB power=0
DEBUG | ??:??:?? 4 Enter state: BOOT
[ 4547][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 480 / 6 = 80 Mhz, APB: 80000000 Hz
DEBUG | ??:??:?? 4 [Power] Battery: usbPower=0, isCharging=0, batMv=3100, batPct=0
DEBUG | ??:??:?? 4 [Screen] Screen: Started...
DEBUG | ??:??:?? 4 [GPS] Probing for GPS at 9600
INFO | ??:??:?? 6 [GPS] Found a UBlox Module using baudrate 9600
DEBUG | ??:??:?? 6 [GPS] Module Info :
DEBUG | ??:??:?? 6 [GPS] Soft version: 1.00 (59842)
DEBUG | ??:??:?? 6 [GPS] Hard version: 00070000
DEBUG | ??:??:?? 6 [GPS] Extensions:2
DEBUG | ??:??:?? 6 [GPS] PROTVER 14.00
DEBUG | ??:??:?? 6 [GPS] GPS;SBAS;GLO;QZSS
DEBUG | ??:??:?? 6 [GPS] Protocol Version:14.00
DEBUG | ??:??:?? 6 [GPS] ProtVer=14
DEBUG | ??:??:?? 6 [GPS] Setting GPS+SBAS
INFO | ??:??:?? 6 [GPS] GNSS configured for GPS+SBAS. Pause for 0.75s before sending next command.
INFO | ??:??:?? 8 [GPS] GNSS module configuration saved!
DEBUG | ??:??:?? 8 [cardKB] Using I2C Bus 1 (the second one)
INFO | ??:??:?? 8 [RangeTestModule] Range Test Module - Disabled
DEBUG | ??:??:?? 8 [PowerFSM] Enter state: ON
INFO | ??:??:?? 8 [PowerFSM] Initialise the NimBLE bluetooth module
DEBUG | ??:??:?? 8 [GPS] NMEA GPS time 2024-04-20 22:42:47
DEBUG | ??:??:?? 8 [GPS] Upgrading time to quality 4
DEBUG | 22:42:47 8 [GPS] Read RTC time as 1713652967
DEBUG | 22:42:47 8 [GPS] publishing pos@0:2, hasVal=0, Sats=0, GPSlock=0
DEBUG | 22:42:47 8 [GPS] No GPS lock
DEBUG | 22:42:47 8 [GPS] onGPSChanged() pos@0, time=1713652967, lat=0, lon=0, alt=0
INFO | 22:42:47 8 [GPS] updatePosition LOCAL pos@0, time=1713652967, latI=0, lonI=0, alt=0
DEBUG | 22:42:47 8 [GPS] Setting local position: latitude=0, longitude=0, time=1713652967
DEBUG | 22:42:47 8 [GPS] Node status update: 3 online, 3 total
INFO | 22:42:47 9 [Screen] Done with boot screen...
DEBUG | 22:42:47 9 [Screen] showing standard frames
DEBUG | 22:42:47 9 [Screen] Module wants a UI Frame
DEBUG | 22:42:47 9 [Screen] Showing 1 module frames
DEBUG | 22:42:47 9 [Screen] Total frame count: 104
DEBUG | 22:42:47 9 [Screen] Added modules. numframes: 1
DEBUG | 22:42:47 9 [Screen] Finished building frames. numframes: 5
INFO | 22:42:52 14 [EnvironmentTelemetryModule] Environment Telemetry: Initializing
INFO | 22:42:52 14 [EnvironmentTelemetryModule] BME680 state read from /prefs/bsec.dat.
INFO | 22:42:52 14 [EnvironmentTelemetryModule] Init sensor: BME680 with the BSEC Library version 2.4.0.0
INFO | 22:42:52 14 [EnvironmentTelemetryModule] Opened BME680 sensor on i2c bus
DEBUG | 22:43:01 23 [GPS] hasValidLocation RISING EDGE
DEBUG | 22:43:01 23 [GPS] WANT GPS=0
DEBUG | 22:43:01 23 [GPS] GPS Lock took 18, average 0
INFO | 22:43:01 23 [GPS] Setting GPS power=0
DEBUG | 22:43:01 23 [GPS] Sleep Time: 120000
DEBUG | 22:43:01 23 [GPS] publishing pos@662444f5:2, hasVal=1, Sats=4, GPSlock=1
DEBUG | 22:43:01 23 [GPS] New GPS pos@662444f5:3 lat=40.917043, lon=-74.196087, alt=67, pdop=4.86, track=0.00, speed=0.02, sats=4
DEBUG | 22:43:01 23 [GPS] onGPSChanged() pos@662444f5, time=1713652981, lat=409170430, lon=-741960866, alt=67
INFO | 22:43:01 23 [GPS] updatePosition LOCAL pos@662444f5, time=1713652981, latI=409170430, lonI=-741960866, alt=67
DEBUG | 22:43:01 23 [GPS] Setting local position: latitude=409170430, longitude=-741960866, time=1713652981
DEBUG | 22:43:01 23 [GPS] Node status update: 3 online, 3 total
INFO | 22:43:01 23 [GPS] Sending smart pos@662444f5:6 to mesh (distanceTraveled=8680134.000000m, minDistanceThreshold=100m, timeElapsed=23215ms)
DEBUG | 22:43:01 23 [GPS] Sending location with precision 32
INFO | 22:43:01 23 [GPS] Providing time to mesh 1713652981
INFO | 22:43:01 23 [GPS] Position reply: time=1713652981, latI=409170430, lonI=-741960866
DEBUG | 22:43:01 23 [GPS] Initial packet id 1398573809, numPacketId 4294967295
DEBUG | 22:43:01 23 [GPS] Update DB node 0x336657cc, rx_time=1713652981
DEBUG | 22:43:01 23 [GPS] handleReceived(LOCAL) (id=0x535c8af3 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1713652981 priority=10)
DEBUG | 22:43:01 23 [GPS] No modules interested in portnum=3, src=LOCAL
DEBUG | 22:43:01 23 [GPS] localSend to channel 0
DEBUG | 22:43:01 23 [GPS] Add packet record (id=0x535c8af3 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1713652981 priority=10)
DEBUG | 22:43:01 23 [GPS] Expanding short PSK #1
DEBUG | 22:43:01 23 [GPS] Using AES128 key!
DEBUG | 22:43:01 23 [GPS] ESP32 crypt fr=336657cc, num=535c8af3, numBytes=34!
DEBUG | 22:43:01 23 [GPS] enqueuing for send (id=0x535c8af3 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1713652981 hopStart=3 priority=10)
DEBUG | 22:43:01 23 [GPS] txGood=0,rxGood=0,rxBad=0
DEBUG | 22:43:01 23 [GPS] Using channel 0 (hash 0x8)
DEBUG | 22:43:01 23 [GPS] Expanding short PSK #1
DEBUG | 22:43:01 23 [GPS] Using AES128 key!
DEBUG | 22:43:01 23 [GPS] ESP32 crypt fr=336657cc, num=535c8af3, numBytes=34!
DEBUG | 22:43:01 23 [GPS] decoded message (id=0x535c8af3 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=3 WANTRESP rxtime=1713652981 hopStart=3 priority=10)
DEBUG | 22:43:01 23 [RadioIf] Starting low level send (id=0x535c8af3 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1713652981 hopStart=3 priority=10)
DEBUG | 22:43:01 23 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=50, time 608 ms
DEBUG | 22:43:01 23 [RadioIf] AirTime - Packet transmitted : 608ms
DEBUG | 22:43:02 24 [RadioIf] Completed sending (id=0x535c8af3 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1713652981 hopStart=3 priority=10)
DEBUG | 22:43:03 24 [Power] Battery: usbPower=0, isCharging=0, batMv=3605, batPct=37
DEBUG | 22:43:06 28 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=27, time 436 ms
DEBUG | 22:43:06 28 [RadioIf] Lora RX (id=0x0e55fbdc fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7.25 rxRSSI=-29 hopStart=2)
DEBUG | 22:43:06 28 [RadioIf] AirTime - Packet received : 436ms
DEBUG | 22:43:06 28 [Router] Add packet record (id=0x0e55fbdc fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7.25 rxRSSI=-29 hopStart=2)
DEBUG | 22:43:06 28 [Router] Using channel 0 (hash 0x8)
DEBUG | 22:43:06 28 [Router] Expanding short PSK #1
DEBUG | 22:43:06 28 [Router] Using AES128 key!
DEBUG | 22:43:06 28 [Router] ESP32 crypt fr=da56bfe0, num=e55fbdc, numBytes=11!
DEBUG | 22:43:06 28 [Router] decoded message (id=0x0e55fbdc fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x0 Portnum=5 requestId=535c8af3 rxtime=1713652986 rxSNR=7.25 rxRSSI=-29 hopStart=2)
DEBUG | 22:43:06 28 [Router] handleReceived(REMOTE) (id=0x0e55fbdc fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x0 Portnum=5 requestId=535c8af3 rxtime=1713652986 rxSNR=7.25 rxRSSI=-29 hopStar
DEBUG | 22:43:06 28 [Router] Module 'canned' wantsPacket=1
DEBUG | 22:43:06 28 [Router] showing standard frames
DEBUG | 22:43:06 28 [Router] Module wants a UI Frame
DEBUG | 22:43:06 28 [Router] Module wants a UI Frame
DEBUG | 22:43:06 28 [Router] Showing 2 module frames
DEBUG | 22:43:06 28 [Router] Total frame count: 105
DEBUG | 22:43:06 28 [Router] Added modules. numframes: 2
DEBUG | 22:43:06 28 [Router] Finished building frames. numframes: 6
DEBUG | 22:43:06 28 [Router] Module 'canned' considered
DEBUG | 22:43:06 28 [Router] Module 'routing' wantsPacket=1
INFO | 22:43:06 28 [Router] Received routing from=0xda56bfe0, id=0xe55fbdc, portnum=5, payloadlen=2
DEBUG | 22:43:06 28 [Router] Routing sniffing (id=0x0e55fbdc fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x0 Portnum=5 requestId=535c8af3 rxtime=1713652986 rxSNR=7.25 rxRSSI=-29 hopStart=2)
DEBUG | 22:43:06 28 [Router] Received a nak for 0x535c8af3, stopping retransmissions
DEBUG | 22:43:06 28 [Router] Delivering rx packet (id=0x0e55fbdc fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x0 Portnum=5 requestId=535c8af3 rxtime=1713652986 rxSNR=7.25 rxRSSI=-29 hopStart=
DEBUG | 22:43:06 28 [Router] Update DB node 0xda56bfe0, rx_time=1713652986
DEBUG | 22:43:06 28 [Router] Forwarding to phone (id=0x0e55fbdc fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x0 Portnum=5 requestId=535c8af3 rxtime=1713652986 rxSNR=7.25 rxRSSI=-29 hopStart=2
DEBUG | 22:43:06 28 [Router] Module 'routing' considered
DEBUG | 22:43:07 28 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=50, time 608 ms
DEBUG | 22:43:07 28 [RadioIf] Lora RX (id=0x535c8af3 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:07 28 [RadioIf] AirTime - Packet received : 608ms
DEBUG | 22:43:07 28 [Router] Rx someone rebroadcasting for us (id=0x535c8af3 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:07 28 [Router] didn't find pending packet
DEBUG | 22:43:07 28 [Router] Found existing packet record for fr=0x336657cc,to=0xffffffff,id=0x535c8af3
DEBUG | 22:43:07 28 [Router] Found existing packet record for fr=0x336657cc,to=0xffffffff,id=0x535c8af3
DEBUG | 22:43:07 28 [Router] Add packet record (id=0x535c8af3 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:07 28 [Router] Ignoring incoming msg, because we've already seen it (id=0x535c8af3 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:07 28 [Router] cancelSending id=0x535c8af3, removed=0
DEBUG | 22:43:07 28 [Router] Incoming message was filtered 0x336657cc
DEBUG | 22:43:08 30 [CannedMessageModule] showing standard frames
DEBUG | 22:43:08 30 [CannedMessageModule] Module wants a UI Frame
DEBUG | 22:43:08 30 [CannedMessageModule] Showing 1 module frames
DEBUG | 22:43:08 30 [CannedMessageModule] Total frame count: 104
DEBUG | 22:43:08 30 [CannedMessageModule] Added modules. numframes: 1
DEBUG | 22:43:08 30 [CannedMessageModule] Finished building frames. numframes: 5
INFO | 22:43:12 34 [NodeInfoModule] Sending our nodeinfo to mesh (wantReplies=1)
INFO | 22:43:12 34 [NodeInfoModule] sending owner !336657cc/Meshtastic 57cc/57cc
DEBUG | 22:43:12 34 [NodeInfoModule] Update DB node 0x336657cc, rx_time=1713652992
DEBUG | 22:43:12 34 [NodeInfoModule] handleReceived(LOCAL) (id=0x535c8af4 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP rxtime=1713652992 priority=10)
DEBUG | 22:43:12 34 [NodeInfoModule] No modules interested in portnum=4, src=LOCAL
DEBUG | 22:43:12 34 [NodeInfoModule] localSend to channel 0
DEBUG | 22:43:12 34 [NodeInfoModule] Add packet record (id=0x535c8af4 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=4 WANTRESP rxtime=1713652992 priority=10)
DEBUG | 22:43:12 34 [NodeInfoModule] Expanding short PSK #1
DEBUG | 22:43:12 34 [NodeInfoModule] Using AES128 key!
DEBUG | 22:43:12 34 [NodeInfoModule] ESP32 crypt fr=336657cc, num=535c8af4, numBytes=50!
DEBUG | 22:43:12 34 [NodeInfoModule] enqueuing for send (id=0x535c8af4 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1713652992 hopStart=3 priority=10)
DEBUG | 22:43:12 34 [NodeInfoModule] txGood=1,rxGood=2,rxBad=0
DEBUG | 22:43:12 34 [RadioIf] Starting low level send (id=0x535c8af4 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1713652992 hopStart=3 priority=10)
DEBUG | 22:43:12 34 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=66, time 722 ms
DEBUG | 22:43:12 34 [RadioIf] AirTime - Packet transmitted : 722ms
DEBUG | 22:43:13 35 [RadioIf] Completed sending (id=0x535c8af4 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1713652992 hopStart=3 priority=10)
DEBUG | 22:43:15 36 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=69, time 747 ms
DEBUG | 22:43:15 36 [RadioIf] Lora RX (id=0x0e55fbdd fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7 rxRSSI=-29 hopStart=2)
DEBUG | 22:43:15 36 [RadioIf] AirTime - Packet received : 747ms
DEBUG | 22:43:15 36 [Router] Add packet record (id=0x0e55fbdd fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7 rxRSSI=-29 hopStart=2)
DEBUG | 22:43:15 36 [Router] Using channel 0 (hash 0x8)
DEBUG | 22:43:15 36 [Router] Expanding short PSK #1
DEBUG | 22:43:15 36 [Router] Using AES128 key!
DEBUG | 22:43:15 37 [Router] ESP32 crypt fr=da56bfe0, num=e55fbdd, numBytes=53!
DEBUG | 22:43:15 37 [Router] decoded message (id=0x0e55fbdd fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x0 Portnum=4 requestId=535c8af4 rxtime=1713652995 rxSNR=7 rxRSSI=-29 hopStart=2)
DEBUG | 22:43:15 37 [Router] handleReceived(REMOTE) (id=0x0e55fbdd fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x0 Portnum=4 requestId=535c8af4 rxtime=1713652995 rxSNR=7 rxRSSI=-29 hopStart=2
DEBUG | 22:43:15 37 [Router] Module 'nodeinfo' wantsPacket=1
INFO | 22:43:15 37 [Router] Received nodeinfo from=0xda56bfe0, id=0xe55fbdd, portnum=4, payloadlen=44
DEBUG | 22:43:15 37 [Router] old user !da56bfe0/Meshtastic bfe0/bfe0, channel=0
DEBUG | 22:43:15 37 [Router] updating changed=0 user !da56bfe0/Meshtastic bfe0/bfe0, channel=0
DEBUG | 22:43:15 37 [Router] Module 'nodeinfo' considered
DEBUG | 22:43:15 37 [Router] Module 'routing' wantsPacket=1
INFO | 22:43:15 37 [Router] Received routing from=0xda56bfe0, id=0xe55fbdd, portnum=4, payloadlen=44
DEBUG | 22:43:15 37 [Router] Routing sniffing (id=0x0e55fbdd fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x0 Portnum=4 requestId=535c8af4 rxtime=1713652995 rxSNR=7 rxRSSI=-29 hopStart=2)
DEBUG | 22:43:15 37 [Router] Received an ack for 0x535c8af4, stopping retransmissions
DEBUG | 22:43:15 37 [Router] Delivering rx packet (id=0x0e55fbdd fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x0 Portnum=4 requestId=535c8af4 rxtime=1713652995 rxSNR=7 rxRSSI=-29 hopStart=2)
DEBUG | 22:43:15 37 [Router] Update DB node 0xda56bfe0, rx_time=1713652995
DEBUG | 22:43:15 37 [Router] Forwarding to phone (id=0x0e55fbdd fr=0xe0 to=0xcc, WantAck=0, HopLim=2 Ch=0x0 Portnum=4 requestId=535c8af4 rxtime=1713652995 rxSNR=7 rxRSSI=-29 hopStart=2)
DEBUG | 22:43:15 37 [Router] Module 'routing' considered
DEBUG | 22:43:16 37 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=66, time 722 ms
DEBUG | 22:43:16 37 [RadioIf] Lora RX (id=0x535c8af4 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7.25 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:16 37 [RadioIf] AirTime - Packet received : 722ms
DEBUG | 22:43:16 37 [Router] Rx someone rebroadcasting for us (id=0x535c8af4 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7.25 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:16 37 [Router] didn't find pending packet
DEBUG | 22:43:16 37 [Router] Found existing packet record for fr=0x336657cc,to=0xffffffff,id=0x535c8af4
DEBUG | 22:43:16 37 [Router] Found existing packet record for fr=0x336657cc,to=0xffffffff,id=0x535c8af4
DEBUG | 22:43:16 37 [Router] Add packet record (id=0x535c8af4 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7.25 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:16 37 [Router] Ignoring incoming msg, because we've already seen it (id=0x535c8af4 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=7.25 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:16 37 [Router] cancelSending id=0x535c8af4, removed=0
DEBUG | 22:43:16 37 [Router] Incoming message was filtered 0x336657cc
DEBUG | 22:43:23 44 [Power] Battery: usbPower=0, isCharging=0, batMv=3885, batPct=69
INFO | 22:43:27 49 [DeviceTelemetryModule] (Sending): air_util_tx=0.036944, channel_utilization=6.405000, battery_level=69, voltage=3.885000, uptime=49
DEBUG | 22:43:27 49 [DeviceTelemetryModule] updateTelemetry LOCAL
DEBUG | 22:43:27 49 [DeviceTelemetryModule] Node status update: 3 online, 3 total
INFO | 22:43:27 49 [DeviceTelemetryModule] Sending packet to mesh
DEBUG | 22:43:27 49 [DeviceTelemetryModule] Update DB node 0x336657cc, rx_time=1713653007
DEBUG | 22:43:27 49 [DeviceTelemetryModule] handleReceived(LOCAL) (id=0x535c8af5 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1713653007 priority=10)
DEBUG | 22:43:27 49 [DeviceTelemetryModule] No modules interested in portnum=67, src=LOCAL
DEBUG | 22:43:27 49 [DeviceTelemetryModule] localSend to channel 0
DEBUG | 22:43:27 49 [DeviceTelemetryModule] Add packet record (id=0x535c8af5 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1713653007 priority=10)
DEBUG | 22:43:27 49 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | 22:43:27 49 [DeviceTelemetryModule] Using AES128 key!
DEBUG | 22:43:27 49 [DeviceTelemetryModule] ESP32 crypt fr=336657cc, num=535c8af5, numBytes=30!
DEBUG | 22:43:27 49 [DeviceTelemetryModule] enqueuing for send (id=0x535c8af5 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1713653007 hopStart=3 priority=10)
DEBUG | 22:43:27 49 [DeviceTelemetryModule] txGood=2,rxGood=4,rxBad=0
DEBUG | 22:43:27 49 [DeviceTelemetryModule] Using channel 0 (hash 0x8)
DEBUG | 22:43:27 49 [DeviceTelemetryModule] Expanding short PSK #1
DEBUG | 22:43:27 49 [DeviceTelemetryModule] Using AES128 key!
DEBUG | 22:43:27 49 [DeviceTelemetryModule] ESP32 crypt fr=336657cc, num=535c8af5, numBytes=30!
DEBUG | 22:43:27 49 [DeviceTelemetryModule] decoded message (id=0x535c8af5 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x0 Portnum=67 rxtime=1713653007 hopStart=3 priority=10)
DEBUG | 22:43:27 49 [cardKB] Enter state: ON
WARN | 22:43:28 49 [RadioIf] Can not send yet, busyRx
WARN | 22:43:28 49 [RadioIf] Can not send yet, busyRx
WARN | 22:43:28 49 [RadioIf] Can not send yet, busyRx
WARN | 22:43:28 49 [RadioIf] Can not send yet, busyRx
WARN | 22:43:28 49 [RadioIf] Can not send yet, busyRx
WARN | 22:43:28 49 [RadioIf] Can not send yet, busyRx
DEBUG | 22:43:28 50 [RadioIf] Ignore false preamble detection.
DEBUG | 22:43:28 50 [RadioIf] Starting low level send (id=0x535c8af5 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1713653007 hopStart=3 priority=10)
DEBUG | 22:43:28 50 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=46, time 575 ms
DEBUG | 22:43:28 50 [RadioIf] AirTime - Packet transmitted : 575ms
DEBUG | 22:43:29 50 [RadioIf] Completed sending (id=0x535c8af5 fr=0xcc to=0xff, WantAck=0, HopLim=3 Ch=0x8 encrypted rxtime=1713653007 hopStart=3 priority=10)
DEBUG | 22:43:31 53 [RadioIf] (bw=250, sf=11, cr=4/5) packet symLen=8 ms, payloadSize=46, time 575 ms
DEBUG | 22:43:31 53 [RadioIf] Lora RX (id=0x535c8af5 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=6.25 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:31 53 [RadioIf] AirTime - Packet received : 575ms
DEBUG | 22:43:31 53 [Router] Rx someone rebroadcasting for us (id=0x535c8af5 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=6.25 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:31 53 [Router] didn't find pending packet
DEBUG | 22:43:31 53 [Router] Found existing packet record for fr=0x336657cc,to=0xffffffff,id=0x535c8af5
DEBUG | 22:43:31 53 [Router] Found existing packet record for fr=0x336657cc,to=0xffffffff,id=0x535c8af5
DEBUG | 22:43:31 53 [Router] Add packet record (id=0x535c8af5 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=6.25 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:32 53 [Router] Ignoring incoming msg, because we've already seen it (id=0x535c8af5 fr=0xcc to=0xff, WantAck=0, HopLim=2 Ch=0x8 encrypted rxSNR=6.25 rxRSSI=-29 hopStart=3)
DEBUG | 22:43:32 53 [Router] cancelSending id=0x535c8af5, removed=0
DEBUG | 22:43:32 53 [Router] Incoming message was filtered 0x336657cc
DEBUG | 22:43:39 61 [cardKB] Enter state: ON
DEBUG | 22:43:43 64 [Power] Battery: usbPower=0, isCharging=0, batMv=4002, batPct=82
DEBUG | 22:43:45 66 [cardKB] Enter state: ON
DEBUG | 22:43:46 67 [cardKB] Enter state: ON
