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]: SX1276 behavior when Transmit Power set at 1dBm #5489

Open
todd-herbert opened this issue Dec 2, 2024 · 0 comments
Open

[Bug]: SX1276 behavior when Transmit Power set at 1dBm #5489

todd-herbert opened this issue Dec 2, 2024 · 0 comments
Labels
bug Something isn't working

Comments

@todd-herbert
Copy link
Contributor

Category

Hardware Compatibility

Hardware

T-Lora v2 1.6

Firmware Version

2.5.14.f2ee0df

Description

Context: new user playing with scavenged hardware. Experiment was "listen-but-not-transmit" on 915MHz using their existing 868MHz antenna.

Region set to ANZ, transmit power set to 1dBm. Intention was to protect radio during initial reboot after setting region, where tx will briefly re-enable itself.

On reboot, critical error 3 is shown, with radiolib error code -13 (invalid output power, minimum supposedly 2dBm).

Despite the error 3, the radio appears(?) to have continued on to transmit packets. Value of config.lora.tx_enabled seems to have been ignored, even after user made several attempts to toggle the setting.

Does anyone know what actually occurs with the radio in this situation? If an invalid output power is set, does the radio attempt to transmit at a much higher power than expected by the user? I realize this is an edge case.

Relevant log output

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

  INFO  | ??:??:?? 0  Booted, wake cause 0 (boot count 1), reset_reason=reset
  DEBUG | ??:??:?? 0  Filesystem files (905216/1048576 Bytes):
  DEBUG | ??:??:?? 0   /prefs/channels.proto (57 Bytes)
  DEBUG | ??:??:?? 0   /prefs/config.proto (164 Bytes)
  DEBUG | ??:??:?? 0   /prefs/db.proto (394 Bytes)
  DEBUG | ??:??:?? 0   /prefs/module.proto (91 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-b9vwykds.js.gz (222120 Bytes)
  DEBUG | ??:??:?? 0   /static/index-wV0rbIjU.css.gz (16573 Bytes)
  DEBUG | ??:??:?? 0   /static/index.html.gz (547 Bytes)
  DEBUG | ??:??:?? 0   /static/mapbox-gl-DY02hNYD.js.gz (391221 Bytes)
  DEBUG | ??:??:?? 0   /static/maplibre-gl-H6mDd47p.js.gz (210429 Bytes)
  DEBUG | ??:??:?? 0   /static/robots.txt.gz (42 Bytes)
  DEBUG | ??:??:?? 0   /static/site.webmanifest.gz (197 Bytes)
 [   544][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=21 scl=22 freq=100000
 DEBUG | ??:??:?? 0  Power::lipoInit lipo sensor is not ready yet
  DEBUG | ??:??:?? 0  Using analog input 35 for battery level
  INFO  | ??:??:?? 0  ADCmod: ADC characterization based on reference voltage stored in eFuse
  INFO  | ??:??:?? 0  Scanning for i2c devices...
 [   587][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
 DEBUG | ??:??:?? 0  Scanning for I2C devices on port 1
  DEBUG | ??:??:?? 0  I2C device found at address 0x3c
  INFO  | ??:??:?? 0  ssd1306 display found
  INFO  | ??:??:?? 0  ssd1306 display found
  DEBUG | ??:??:?? 0  0x4 subtype probed in 2 tries 
  INFO  | ??:??:?? 0  1 I2C devices found
  DEBUG | ??:??:?? 0  acc_info = 0
  INFO  | ??:??:?? 0  S:B:3,2.5.4.8d288d5
  INFO  | ??:??:?? 0  Build timestamp: 1727744785
  DEBUG | ??:??:?? 0  Total heap: 192384
  DEBUG | ??:??:?? 0  Free heap: 157472
  DEBUG | ??:??:?? 0  Total PSRAM: 0
  DEBUG | ??:??:?? 0  Free PSRAM: 0
  DEBUG | ??:??:?? 0  NVS: UsedEntries 78, FreeEntries 552, AllEntries 630, NameSpaces 4
  DEBUG | ??:??:?? 0  Setup Preferences in Flash Storage
  DEBUG | ??:??:?? 0  Number of Device Reboots: 6
  INFO  | ??:??:?? 0  OTA firmware version 0.3.0.87f0f2f
  INFO  | ??:??:?? 0  Initializing NodeDB
  INFO  | ??:??:?? 0  Loading /prefs/db.proto
  INFO  | ??:??:?? 0  Loaded /prefs/db.proto successfully
  INFO  | ??:??:?? 0  Loaded saved devicestate version 23, with nodecount: 1
  INFO  | ??:??:?? 0  Loading /prefs/config.proto
  INFO  | ??:??:?? 0  Loaded /prefs/config.proto successfully
  INFO  | ??:??:?? 0  Loaded saved config version 23
  INFO  | ??:??:?? 0  Loading /prefs/module.proto
  INFO  | ??:??:?? 0  Loaded /prefs/module.proto successfully
  INFO  | ??:??:?? 0  Loaded saved moduleConfig version 23
  INFO  | ??:??:?? 0  Loading /prefs/channels.proto
  INFO  | ??:??:?? 0  Loaded /prefs/channels.proto successfully
  INFO  | ??:??:?? 0  Loaded saved channelFile version 23
 [   877][E][vfs_api.cpp:105] open(): /littlefs/oem/oem.proto does not exist, no permits for creation
 ERROR | ??:??:?? 0  Could not open / read /oem/oem.proto
  DEBUG | ??:??:?? 0  cleanupMeshDB purged 0 entries
  DEBUG | ??:??:?? 0  Using nodenum  
  DEBUG | ??:??:?? 0  Number of Device Reboots: 6
  DEBUG | ??:??:?? 0  Expanding short PSK #1
  INFO  | ??:??:?? 0  Wanted region 6, using ANZ
  INFO  | ??:??:?? 1  Saving /prefs/db.proto
  DEBUG | ??:??:?? 1  Using GPIO12 for button
  DEBUG | ??:??:?? 1  SPI.begin(SCK=5, MISO=19, MOSI=27, NSS=18)
  DEBUG | ??:??:?? 1  Set Timezone to GMT0
  DEBUG | ??:??:?? 1  Read RTC time as 1
  DEBUG | ??:??:?? 1  NeighborInfoModule is disabled
  DEBUG | ??:??:?? 1  Rescanning for I2C keyboard
  DEBUG | ??:??:?? 1  Scanning for I2C devices on port 1
  DEBUG | ??:??:?? 1  Scanning address 0x1f
  DEBUG | ??:??:?? 1  Scanning address 0x55
  DEBUG | ??:??:?? 1  Scanning address 0x5f
  INFO  | ??:??:?? 1  External Notification Module Disabled
  INFO  | ??:??:?? 1  Turning on screen
  DEBUG | ??:??:?? 1  haveGlyphs=1
  DEBUG | ??:??:?? 1  Module wants a UI Frame
  DEBUG | ??:??:?? 1  Module wants a UI Frame
  DEBUG | ??:??:?? 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=919.875, config.lora.frequency_offset=0.000
  INFO  | ??:??:?? 1  Set radio: region=ANZ, name=LongFast, config=0, ch=19, power=1
  INFO  | ??:??:?? 1  Radio myRegion->freqStart -> myRegion->freqEnd: 915.000000 -> 928.000000 (13.000000 MHz)
  INFO  | ??:??:?? 1  Radio myRegion->numChannels: 52 x 250.000kHz
  INFO  | ??:??:?? 1  Radio channel_num: 20
  INFO  | ??:??:?? 1  Radio frequency: 919.875000
  INFO  | ??:??:?? 1  Slot time: 77 msec
  INFO  | ??:??:?? 1  Set radio: final power level=1
  DEBUG | ??:??:?? 1  Current limit set to 100.000000
  DEBUG | ??:??:?? 1  Current limit set result 0
  INFO  | ??:??:?? 1  RF95 init result -13
  INFO  | ??:??:?? 1  Frequency set to 919.875000
  INFO  | ??:??:?? 1  Bandwidth set to 250.000000
  INFO  | ??:??:?? 1  Power output set to 1
  WARN  | ??:??:?? 1  Failed to find RF95 radio
  INFO  | ??:??:?? 1  Not using WIFI
  ERROR | ??:??:?? 1  NOTE! Recording critical error 3 at src/main.cpp:1090
  INFO  | ??:??:?? 1  PowerFSM init, USB power=1
  DEBUG | ������������[34m Enter state: BOOT
 [  1499][D][esp32-hal-cpu.c:244] setCpuFrequencyMhz(): PLL: 320 / 4 = 80 Mhz, APB: 80000000 Hz
 DEBUG | ??:??:?? 1 [Power]  Battery: usbPower=0, isCharging=0, batMv=3100, batPct=0
  DEBUG | ??:??:?? 1 [Screen]  Screen: Started...
  DEBUG | ??:??:?? 1 [Screen]  Screen: Critical error 3!
  DEBUG | ??:??:?? 1 [Screen]  haveGlyphs=1
  INFO  | ??:??:?? 1 [RangeTestModule]  Range Test Module - Disabled
  DEBUG | ??:??:?? 2 [Screen]  haveGlyphs=1
  DEBUG | ??:??:?? 3 [Screen]  haveGlyphs=1
  INFO  | ??:??:?? 4 [PowerFSM]  Loss of power in Powered
  DEBUG | ??:??:?? 4 [Screen]  haveGlyphs=1
  INFO  | ??:??:?? 4 [PowerFSM]  Loss of power in Powered
  INFO  | ??:??:?? 4 [PowerFSM]  Initialise the NimBLE bluetooth module
  DEBUG | ??:??:?? 5 [PowerFSM]  Enter state: ON
  DEBUG | ??:??:?? 5 [Screen]  haveGlyphs=1
  DEBUG | ??:??:?? 6 [Screen]  haveGlyphs=1
  INFO  | ??:??:?? 7 [Screen]  Done with boot screen...
  DEBUG | ??:??:?? 7 [Screen]  showing standard frames
  DEBUG | ??:??:?? 7 [Screen]  Showing 0 module frames
  DEBUG | ??:??:?? 7 [Screen]  Total frame count: 103
  DEBUG | ??:??:?? 7 [Screen]  Added modules.  numframes: 0
  DEBUG | ??:??:?? 7 [Screen]  Finished building frames. numframes: 3
  DEBUG | ??:??:?? 21 [Power]  Battery: usbPower=1, isCharging=1, batMv=4348, batPct=100
  INFO  | ??:??:?? 31 [NodeInfoModule]  Sending our nodeinfo to mesh (wantReplies=1)
  INFO  | ??:??:?? 31 [NodeInfoModule]  sending owner 
  DEBUG | ??:??:?? 31 [NodeInfoModule]  Initial packet id 1083568766
  DEBUG | ??:??:?? 31 [NodeInfoModule]  Partially randomized packet id 2834306687
  DEBUG | ??:??:?? 31 [NodeInfoModule]  Update DB node , rx_time=0
  ERROR | ??:??:?? 31 [NodeInfoModule]  Error=4, returning NAK and dropping packet.
  DEBUG | ??:??:?? 31 [NodeInfoModule]  Partially randomized packet id 2544923264
  WARN  | ??:??:?? 31 [NodeInfoModule]  Alloc an err=4,to=,idFrom=,id=
  DEBUG | ??:??:?? 31 [NodeInfoModule]  Enqueued local (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=a8f0167f priority=120)
  DEBUG | ??:??:?? 31 [Router]  Rx someone rebroadcasting for us (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=a8f0167f priority=120)
  DEBUG | ??:??:?? 31 [Router]  didn't find pending packet
  DEBUG | ??:??:?? 31 [Router]  Add packet record (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=a8f0167f priority=120)
  DEBUG | ??:??:?? 31 [Router]  handleReceived(REMOTE) (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=a8f0167f priority=120)
  DEBUG | ??:??:?? 31 [Router]  Module 'routing' wantsPacket=1
  INFO  | ??:??:?? 31 [Router]  Received routing from=, id=, portnum=5, payloadlen=2
  DEBUG | ??:??:?? 31 [Router]  Routing sniffing (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=a8f0167f priority=120)
  DEBUG | ??:??:?? 31 [Router]  Received a nak for , stopping retransmissions
  DEBUG | ??:??:?? 31 [Router]  Delivering rx packet (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=a8f0167f priority=120)
  DEBUG | ??:??:?? 31 [Router]  Update DB node , rx_time=0
  DEBUG | ??:??:?? 31 [Router]  Forwarding to phone (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=a8f0167f priority=120)
  DEBUG | ??:??:?? 31 [Router]  Module 'routing' considered
  DEBUG | ??:??:?? 41 [Power]  Battery: usbPower=1, isCharging=1, batMv=4361, batPct=100
  INFO  | ??:??:?? 46 [DeviceTelemetryModule]  (Sending): air_util_tx=0.000000, channel_utilization=0.000000, battery_level=101, voltage=4.361000, uptime=46
  DEBUG | ??:??:?? 46 [DeviceTelemetryModule]  Partially randomized packet id 1263736449
  DEBUG | ??:??:?? 46 [DeviceTelemetryModule]  updateTelemetry LOCAL
  DEBUG | ??:??:?? 46 [DeviceTelemetryModule]  Node status update: 1 online, 1 total
  DEBUG | ??:??:?? 46 [DeviceTelemetryModule]  showing standard frames
  DEBUG | ??:??:?? 46 [DeviceTelemetryModule]  Showing 0 module frames
  DEBUG | ??:??:?? 46 [DeviceTelemetryModule]  Total frame count: 103
  DEBUG | ??:??:?? 46 [DeviceTelemetryModule]  Added modules.  numframes: 0
  DEBUG | ??:??:?? 46 [DeviceTelemetryModule]  Finished building frames. numframes: 3
  INFO  | ??:??:?? 46 [DeviceTelemetryModule]  Sending packet to mesh
  DEBUG | ??:??:?? 46 [DeviceTelemetryModule]  Update DB node , rx_time=0
  ERROR | ??:??:?? 46 [DeviceTelemetryModule]  Error=4, returning NAK and dropping packet.
  DEBUG | ??:??:?? 46 [DeviceTelemetryModule]  Partially randomized packet id 1733455490
  WARN  | ??:??:?? 46 [DeviceTelemetryModule]  Alloc an err=4,to=,idFrom=,id=
  DEBUG | ??:??:?? 46 [DeviceTelemetryModule]  Enqueued local (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=4b531681 priority=120)
  DEBUG | ??:??:?? 46 [Router]  Rx someone rebroadcasting for us (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=4b531681 priority=120)
  DEBUG | ??:??:?? 46 [Router]  didn't find pending packet
  DEBUG | ??:??:?? 46 [Router]  Add packet record (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=4b531681 priority=120)
  DEBUG | ??:??:?? 46 [Router]  handleReceived(REMOTE) (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=4b531681 priority=120)
  DEBUG | ??:??:?? 46 [Router]  Module 'routing' wantsPacket=1
  INFO  | ??:??:?? 46 [Router]  Received routing from=, id=, portnum=5, payloadlen=2
  DEBUG | ??:??:?? 46 [Router]  Routing sniffing (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=4b531681 priority=120)
  DEBUG | ??:??:?? 46 [Router]  Received a nak for , stopping retransmissions
  DEBUG | ??:??:?? 46 [Router]  Delivering rx packet (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=4b531681 priority=120)
  DEBUG | ??:??:?? 46 [Router]  Update DB node , rx_time=0
  DEBUG | ??:??:?? 46 [Router]  Forwarding to phone (id= fr=0x1c to=0x1c, WantAck=0, HopLim=3 Ch=0x0 Portnum=5 requestId=4b531681 priority=120)
  DEBUG | ??:??:?? 46 [Router]  Module 'routing' considered
  DEBUG | ??:??:?? 61 [Power]  Battery: usbPower=1, isCharging=1, batMv=4356, batPct=100
  DEBUG | ??:??:?? 81 [Power]  Battery: usbPower=1, isCharging=1, batMv=4356, batPct=100
  DEBUG | ??:??:?? 101 [Power]  Battery: usbPower=1, isCharging=1, batMv=4335, batPct=100
  DEBUG | ??:??:?? 121 [Power]  Battery: usbPower=1, isCharging=1, batMv=4333, batPct=100
  DEBUG | ??:??:?? 141 [Power]  Battery: usbPower=1, isCharging=1, batMv=4320, batPct=100
  DEBUG | ??:??:?? 161 [Power]  Battery: usbPower=1, isCharging=1, batMv=4247, batPct=100
  DEBUG | ??:??:?? 181 [Power]  Battery: usbPower=1, isCharging=1, batMv=4260, batPct=100
  DEBUG | ??:??:?? 201 [Power]  Battery: usbPower=1, isCharging=1, batMv=4272, batPct=100
  DEBUG | ??:??:?? 221 [Power]  Battery: usbPower=1, isCharging=1, batMv=4249, batPct=100
  DEBUG | ??:??:?? 241 [Power]  Battery: usbPower=1, isCharging=1, batMv=4264, batPct=100
  DEBUG | ??:??:?? 261 [Power]  Battery: usbPower=1, isCharging=1, batMv=4275, batPct=100
  DEBUG | ??:??:?? 281 [Power]  Battery: usbPower=1, isCharging=1, batMv=4285, batPct=100
  DEBUG | ??:??:?? 301 [Power]  Battery: usbPower=1, isCharging=1, batMv=4307, batPct=100
@todd-herbert todd-herbert added the bug Something isn't working label Dec 2, 2024
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
1 participant
@todd-herbert