Skip to content

Instantly share code, notes, and snippets.

@t-huyeng
Created November 14, 2022 14:23
Show Gist options
  • Save t-huyeng/69a90647c8be9e33ef49ee4251488082 to your computer and use it in GitHub Desktop.
Save t-huyeng/69a90647c8be9e33ef49ee4251488082 to your computer and use it in GitHub Desktop.
Logs of paxcounter - errors
15:17:35.961 > [ 17][I][configmanager.cpp:96] loadConfig(): [src/configmanager.cpp] Loading device configuration from NVRAM...
15:17:35.961 > [ 19][I][configmanager.cpp:122] loadConfig(): [src/configmanager.cpp] Runtime configuration v3.4.3 loaded
15:17:35.961 > [ 27][I][main.cpp:115] setup(): [src/main.cpp] Starting paxcounter_81a17fe3 v3.4.3 (runmode=0 / restarts=0)
15:17:35.961 > [ 36][I][main.cpp:116] setup(): [src/main.cpp] code build date: 1668435351
15:17:38.673 > [ 11535][I][main.cpp:309] setup(): [src/main.cpp] Starting rcommand interpreter...
15:17:38.682 > [ 11539][I][rcommand.cpp:515] rcmd_init(): [src/rcommand.cpp] Rcommand send queue created, size 55 Bytes
15:17:38.690 > [ 11548][I][lorawan.cpp:248] lmic_init(): [src/lorawan.cpp] LORA send queue created, size 530 Bytes
15:17:38.704 > [ 11573][I][lorawan.cpp:297] lmic_init(): [src/lorawan.cpp] Starting LMIC...
15:17:38.711 > [ 11574][I][main.cpp:412] setup(): [src/main.cpp] Starting Interrupt Handler...
15:17:38.718 > [ 11576][I][main.cpp:440] setup(): [src/main.cpp] Starting Timers...
15:17:38.746 > [ 11615][I][button.cpp:37] button_init(): [src/button.cpp] Starting button Controller...
15:17:38.754 > [ 11617][I][timekeeper.cpp:391] time_init(): [src/timekeeper.cpp] Starting time pulse...
15:17:38.762 > [ 11620][I][timekeeper.cpp:194] timepulse_init(): [src/timekeeper.cpp] Timepulse: internal (ESP32 hardware timer)
15:17:39.761 > [ 12630][I][main.cpp:485] setup(): [src/main.cpp] Features: VEXT DISP LED BATT BLE LORA PACKED WIFI BTN_PU TIME
15:17:39.780 > [ 12648][I][timesync.cpp:52] timesync_request(): [src/timesync.cpp] [12.648] Timeserver sync request started, seqNo#49
15:17:48.196 > [ 21065][I][lorawan.cpp:73] lora_setupForNetwork(): [src/lorawan.cpp] DEVaddr: 0x0xxxxxx | Network ID: 0x000000 | Network Type: 0
15:17:48.209 > [ 21066][I][lorawan.cpp:74] lora_setupForNetwork(): [src/lorawan.cpp] RSSI: 25 | SNR: 7
15:17:48.216 > [ 21074][I][lorawan.cpp:78] lora_setupForNetwork(): [src/lorawan.cpp] Radio parameters: SF7 | BW125 | CR 4/5
15:17:50.320 > [ 23187][I][timekeeper.cpp:134] setMyTime(): [src/timekeeper.cpp] [23.188] UTC time: 1668435468.000 sec
15:18:10.800 > [ 43670][I][lorawan.cpp:219] lora_send(): [src/lorawan.cpp] 1 byte(s) sent to LORA
15:18:37.701 > [ 70569][I][lorawan.cpp:219] lora_send(): [src/lorawan.cpp] 4 byte(s) sent to LORA
15:18:38.808 > [ 71677][I][lorawan.cpp:408] myRxCallback(): [src/lorawan.cpp] Received 4 byte(s) of payload on port 2
15:18:38.818 > [ 71677][I][rcommand.cpp:89] set_sleepcycle(): [src/rcommand.cpp] Remote command: set sleep cycle to 30 seconds
15:18:38.827 > [ 71685][I][rcommand.cpp:407] set_saveconfig(): [src/rcommand.cpp] Remote command: save config to NVRAM
15:18:38.836 > [ 71694][I][configmanager.cpp:69] saveConfig(): [src/configmanager.cpp] Storing settings to NVRAM...
15:18:38.846 > [ 71711][I][configmanager.cpp:85] saveConfig(): [src/configmanager.cpp] Device settings saved
15:19:07.700 > [100568][I][reset.cpp:88] enter_deepsleep(): [src/reset.cpp] Preparing to sleep...
15:19:07.708 > [100571][I][lorawan.cpp:219] lora_send(): [src/lorawan.cpp] 4 byte(s) sent to LORA
15:19:07.714 > [100572][I][blescan.cpp:277] stop_BLE_scan(): [bluetooth] Shutting down bluetooth scanner ...
15:19:07.722 > [100585][I][blescan.cpp:286] stop_BLE_scan(): [bluetooth] Bluetooth scanner stopped
15:19:07.731 > [100587][I][reset.cpp:110] enter_deepsleep(): [src/reset.cpp] Waiting until send queues are empty...
15:19:07.739 > [100596][I][reset.cpp:119] enter_deepsleep(): [src/reset.cpp] Waiting until LMIC is idle...
15:19:10.736 > [103604][I][lorawan.cpp:532] SaveLMICToRTC(): [src/lorawan.cpp] LMIC state saved
15:19:10.743 > [103605][I][reset.cpp:179] enter_deepsleep(): [src/reset.cpp] Going to sleep, good bye.
15:19:40.774 > [ 20][I][configmanager.cpp:96] loadConfig(): [src/configmanager.cpp] Loading device configuration from NVRAM...
15:19:40.785 > [ 22][I][configmanager.cpp:122] loadConfig(): [src/configmanager.cpp] Runtime configuration v3.4.3 loaded
15:19:40.794 > [ 30][I][reset.cpp:72] do_after_reset(): [src/reset.cpp] Time spent in deep sleep: 30077 ms
15:19:40.803 > [ 38][I][main.cpp:115] setup(): [src/main.cpp] Starting paxcounter_81a17fe3 v3.4.3 (runmode=2 / restarts=0)
15:19:40.812 > [ 47][I][main.cpp:116] setup(): [src/main.cpp] code build date: 1668435351
15:19:40.819 > [ 54][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=4 scl=15 freq=100000
15:19:40.936 > [ 64][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
15:19:40.967 > [ 213][I][main.cpp:229] setup(): [src/main.cpp] Starting LED Controller...
15:19:40.974 > [ 214][I][power.cpp:194] calibrate_voltage(): [src/power.cpp] ADC characterization based on reference voltage stored in eFuse
15:19:40.985 > [ 224][I][main.cpp:275] setup(): [src/main.cpp] Starting libpax...
15:19:40.991 > [ 227][I][main.cpp:285] setup(): [src/main.cpp] WIFISCAN: on
15:19:40.998 > [ 232][I][main.cpp:291] setup(): [src/main.cpp] BLESCAN: on
15:19:41.011 > [ 258][I][blescan.cpp:203] start_BLE_scan(): [bluetooth] Initializing bluetooth scanner ...
15:19:41.638 > [ 884][I][blescan.cpp:113] hci_cmd_send_ble_scan_start(): [bluetooth] BLE Scanning started
15:19:41.838 > [ 1085][I][blescan.cpp:264] start_BLE_scan(): [bluetooth] Bluetooth scanner started
15:19:41.846 > [ 1085][I][main.cpp:309] setup(): [src/main.cpp] Starting rcommand interpreter...
15:19:41.854 > [ 1089][I][rcommand.cpp:515] rcmd_init(): [src/rcommand.cpp] Rcommand send queue created, size 55 Bytes
15:19:41.862 > [ 1098][I][lorawan.cpp:248] lmic_init(): [src/lorawan.cpp] LORA send queue created, size 530 Bytes
15:19:41.875 > [ 1122][I][lorawan.cpp:537] LoadLMICFromRTC(): [src/lorawan.cpp] LMIC state loaded
15:19:41.883 > [ 1122][I][lorawan.cpp:293] lmic_init(): [src/lorawan.cpp] Already joined
15:19:41.890 > [ 1125][I][lorawan.cpp:297] lmic_init(): [src/lorawan.cpp] Starting LMIC...
15:19:41.897 > [ 1132][I][main.cpp:412] setup(): [src/main.cpp] Starting Interrupt Handler...
15:19:41.904 > [ 1139][I][main.cpp:440] setup(): [src/main.cpp] Starting Timers...
15:19:41.933 > [ 1179][I][button.cpp:37] button_init(): [src/button.cpp] Starting button Controller...
15:19:41.941 > [ 1180][I][timekeeper.cpp:391] time_init(): [src/timekeeper.cpp] Starting time pulse...
15:19:41.948 > [ 1184][I][timekeeper.cpp:194] timepulse_init(): [src/timekeeper.cpp] Timepulse: internal (ESP32 hardware timer)
15:19:41.959 > [ 1193][I][main.cpp:485] setup(): [src/main.cpp] Features: VEXT DISP LED BATT BLE LORA PACKED WIFI BTN_PU TIME
15:20:11.005 > [ 30251][I][reset.cpp:88] enter_deepsleep(): [src/reset.cpp] Preparing to sleep...
15:20:11.013 > [ 30255][I][lorawan.cpp:219] lora_send(): [src/lorawan.cpp] 4 byte(s) sent to LORA
15:20:11.021 > [ 30256][I][blescan.cpp:277] stop_BLE_scan(): [bluetooth] Shutting down bluetooth scanner ...
15:20:11.033 > [ 30279][I][blescan.cpp:286] stop_BLE_scan(): [bluetooth] Bluetooth scanner stopped
15:20:11.041 > [ 30279][I][reset.cpp:110] enter_deepsleep(): [src/reset.cpp] Waiting until send queues are empty...
15:20:11.049 > [ 30284][I][reset.cpp:119] enter_deepsleep(): [src/reset.cpp] Waiting until LMIC is idle...
15:20:12.117 > [ 31363][I][lorawan.cpp:408] myRxCallback(): [src/lorawan.cpp] Received 3 byte(s) of payload on port 2
15:20:12.127 > [ 31363][I][rcommand.cpp:273] set_wifiscan(): [src/rcommand.cpp] Remote command: set WIFI scanner to off
15:20:12.135 >
15:20:12.135 > assert failed: xTimerGenericCommand timers.c:404 (xTimer)
15:20:12.140 >
15:20:12.140 >
15:20:12.142 > Backtrace:0x4008406d:0x3ffe9b200x4009612d:0x3ffe9b40 0x4009c18d:0x3ffe9b60 0x40099701:0x3ffe9c90 0x400d8665:0x3ffe9cc0 0x400d6cc6:0x3ffe9ce0 0x400d747d:0x3ffe9d30 0x400d75a2:0x3ffe9d90
15:20:12.157 >
15:20:12.157 >
15:20:12.157 >
15:20:12.157 >
15:20:12.157 > ELF file SHA256: 0000000000000000
15:20:12.160 >
15:20:12.161 > Rebooting...
15:20:12.630 > [ 20][I][configmanager.cpp:96] loadConfig(): [src/configmanager.cpp] Loading device configuration from NVRAM...
15:20:12.641 > [ 23][I][configmanager.cpp:122] loadConfig(): [src/configmanager.cpp] Runtime configuration v3.4.3 loaded
15:20:12.651 > [ 30][I][main.cpp:115] setup(): [src/main.cpp] Starting paxcounter_81a17fe3 v3.4.3 (runmode=4 / restarts=1)
15:20:12.660 > [ 39][I][main.cpp:116] setup(): [src/main.cpp] code build date: 1668435351
15:20:12.667 > [ 46][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=4 scl=15 freq=100000
15:20:12.784 > [ 55][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
15:20:12.815 > [ 205][I][main.cpp:229] setup(): [src/main.cpp] Starting LED Controller...
15:20:12.823 > [ 206][I][power.cpp:194] calibrate_voltage(): [src/power.cpp] ADC characterization based on reference voltage stored in eFuse
15:20:12.833 > [ 215][I][main.cpp:275] setup(): [src/main.cpp] Starting libpax...
15:20:12.839 > [ 218][I][main.cpp:285] setup(): [src/main.cpp] WIFISCAN: on
15:20:12.845 > [ 224][I][main.cpp:291] setup(): [src/main.cpp] BLESCAN: on
15:20:12.933 > [ 323][I][blescan.cpp:203] start_BLE_scan(): [bluetooth] Initializing bluetooth scanner ...
15:20:13.563 > [ 953][I][blescan.cpp:113] hci_cmd_send_ble_scan_start(): [bluetooth] BLE Scanning started
15:20:13.763 > [ 1154][I][blescan.cpp:264] start_BLE_scan(): [bluetooth] Bluetooth scanner started
15:20:13.772 > [ 1154][I][main.cpp:309] setup(): [src/main.cpp] Starting rcommand interpreter...
15:20:13.779 > [ 1158][I][rcommand.cpp:515] rcmd_init(): [src/rcommand.cpp] Rcommand send queue created, size 55 Bytes
15:20:13.788 > [ 1167][I][lorawan.cpp:248] lmic_init(): [src/lorawan.cpp] LORA send queue created, size 530 Bytes
15:20:13.802 > [ 1192][I][lorawan.cpp:297] lmic_init(): [src/lorawan.cpp] Starting LMIC...
15:20:13.810 > [ 1193][I][main.cpp:412] setup(): [src/main.cpp] Starting Interrupt Handler...
15:20:13.817 > [ 1195][I][main.cpp:440] setup(): [src/main.cpp] Starting Timers...
15:20:13.844 > [ 1235][I][button.cpp:37] button_init(): [src/button.cpp] Starting button Controller...
15:20:13.853 > [ 1237][I][timekeeper.cpp:391] time_init(): [src/timekeeper.cpp] Starting time pulse...
15:20:13.861 > [ 1240][I][timekeeper.cpp:194] timepulse_init(): [src/timekeeper.cpp] Timepulse: internal (ESP32 hardware timer)
15:20:14.859 > [ 2250][I][main.cpp:485] setup(): [src/main.cpp] Features: VEXT DISP LED BATT BLE LORA PACKED WIFI BTN_PU TIME
15:20:14.877 > [ 2268][I][timesync.cpp:52] timesync_request(): [src/timesync.cpp] [2.268] Timeserver sync request started, seqNo#80
15:20:26.039 > [ 13429][I][lorawan.cpp:73] lora_setupForNetwork(): [src/lorawan.cpp] DEVaddr: 0x0xxxxx | Network ID: 0x000000 | Network Type: 0
15:20:26.050 > [ 13430][I][lorawan.cpp:74] lora_setupForNetwork(): [src/lorawan.cpp] RSSI: 26 | SNR: 6
15:20:26.058 > [ 13437][I][lorawan.cpp:78] lora_setupForNetwork(): [src/lorawan.cpp] Radio parameters: SF7 | BW125 | CR 4/5
15:20:28.238 > [ 15627][I][timekeeper.cpp:134] setMyTime(): [src/timekeeper.cpp] [15.628] UTC time: 1668435626.000 sec
15:20:42.839 > [ 30230][I][reset.cpp:88] enter_deepsleep(): [src/reset.cpp] Preparing to sleep...
15:20:42.847 > [ 30233][I][blescan.cpp:277] stop_BLE_scan(): [bluetooth] Shutting down bluetooth scanner ...
15:20:42.855 > [ 30237][I][blescan.cpp:286] stop_BLE_scan(): [bluetooth] Bluetooth scanner stopped
15:20:42.863 > [ 30242][I][reset.cpp:110] enter_deepsleep(): [src/reset.cpp] Waiting until send queues are empty...
15:20:48.254 > [ 35645][I][lorawan.cpp:219] lora_send(): [src/lorawan.cpp] 1 byte(s) sent to LORA
15:20:53.188 > [ 40579][I][lorawan.cpp:219] lora_send(): [src/lorawan.cpp] 4 byte(s) sent to LORA
15:20:53.860 > [ 41251][I][reset.cpp:119] enter_deepsleep(): [src/reset.cpp] Waiting until LMIC is idle...
15:20:57.860 > [ 45251][I][lorawan.cpp:532] SaveLMICToRTC(): [src/lorawan.cpp] LMIC state saved
15:20:57.868 > [ 45252][I][reset.cpp:179] enter_deepsleep(): [src/reset.cpp] Going to sleep, good bye.
15:21:27.497 > [ 20][I][configmanager.cpp:96] loadConfig(): [src/configmanager.cpp] Loading device configuration from NVRAM...
15:21:27.507 > [ 22][I][configmanager.cpp:122] loadConfig(): [src/configmanager.cpp] Runtime configuration v3.4.3 loaded
15:21:27.518 > [ 30][I][reset.cpp:72] do_after_reset(): [src/reset.cpp] Time spent in deep sleep: 30078 ms
15:21:27.526 > [ 38][I][main.cpp:115] setup(): [src/main.cpp] Starting paxcounter_81a17fe3 v3.4.3 (runmode=2 / restarts=1)
15:21:27.535 > [ 47][I][main.cpp:116] setup(): [src/main.cpp] code build date: 1668435351
15:21:27.542 > [ 54][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=4 scl=15 freq=100000
15:21:27.659 > [ 64][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
15:21:27.690 > [ 213][I][main.cpp:229] setup(): [src/main.cpp] Starting LED Controller...
15:21:27.697 > [ 214][I][power.cpp:194] calibrate_voltage(): [src/power.cpp] ADC characterization based on reference voltage stored in eFuse
15:21:27.709 > [ 224][I][main.cpp:275] setup(): [src/main.cpp] Starting libpax...
15:21:27.714 > [ 227][I][main.cpp:285] setup(): [src/main.cpp] WIFISCAN: on
15:21:27.720 > [ 232][I][main.cpp:291] setup(): [src/main.cpp] BLESCAN: on
15:21:27.734 > [ 258][I][blescan.cpp:203] start_BLE_scan(): [bluetooth] Initializing bluetooth scanner ...
15:21:28.362 > [ 885][I][blescan.cpp:113] hci_cmd_send_ble_scan_start(): [bluetooth] BLE Scanning started
15:21:28.563 > [ 1086][I][blescan.cpp:264] start_BLE_scan(): [bluetooth] Bluetooth scanner started
15:21:28.570 > [ 1086][I][main.cpp:309] setup(): [src/main.cpp] Starting rcommand interpreter...
15:21:28.578 > [ 1090][I][rcommand.cpp:515] rcmd_init(): [src/rcommand.cpp] Rcommand send queue created, size 55 Bytes
15:21:28.586 > [ 1099][I][lorawan.cpp:248] lmic_init(): [src/lorawan.cpp] LORA send queue created, size 530 Bytes
15:21:28.601 > [ 1124][I][lorawan.cpp:537] LoadLMICFromRTC(): [src/lorawan.cpp] LMIC state loaded
15:21:28.608 > [ 1124][I][lorawan.cpp:293] lmic_init(): [src/lorawan.cpp] Already joined
15:21:28.615 > [ 1127][I][lorawan.cpp:297] lmic_init(): [src/lorawan.cpp] Starting LMIC...
15:21:28.623 > [ 1134][I][main.cpp:412] setup(): [src/main.cpp] Starting Interrupt Handler...
15:21:28.629 > [ 1141][I][main.cpp:440] setup(): [src/main.cpp] Starting Timers...
15:21:28.657 > [ 1181][I][button.cpp:37] button_init(): [src/button.cpp] Starting button Controller...
15:21:28.665 > [ 1182][I][timekeeper.cpp:391] time_init(): [src/timekeeper.cpp] Starting time pulse...
15:21:28.673 > [ 1186][I][timekeeper.cpp:194] timepulse_init(): [src/timekeeper.cpp] Timepulse: internal (ESP32 hardware timer)
15:21:28.684 > [ 1196][I][main.cpp:485] setup(): [src/main.cpp] Features: VEXT DISP LED BATT BLE LORA PACKED WIFI BTN_PU TIME
15:21:57.730 > [ 30253][I][reset.cpp:88] enter_deepsleep(): [src/reset.cpp] Preparing to sleep...
15:21:57.738 > [ 30257][I][lorawan.cpp:219] lora_send(): [src/lorawan.cpp] 4 byte(s) sent to LORA
15:21:57.745 > [ 30258][I][blescan.cpp:277] stop_BLE_scan(): [bluetooth] Shutting down bluetooth scanner ...
15:21:57.805 > [ 30328][I][blescan.cpp:286] stop_BLE_scan(): [bluetooth] Bluetooth scanner stopped
15:21:57.812 > [ 30328][I][reset.cpp:110] enter_deepsleep(): [src/reset.cpp] Waiting until send queues are empty...
15:21:57.821 > [ 30333][I][reset.cpp:119] enter_deepsleep(): [src/reset.cpp] Waiting until LMIC is idle...
15:22:00.818 > [ 33341][I][lorawan.cpp:532] SaveLMICToRTC(): [src/lorawan.cpp] LMIC state saved
15:22:00.826 > [ 33342][I][reset.cpp:179] enter_deepsleep(): [src/reset.cpp] Going to sleep, good bye.
@cyberman54
Copy link

@t-huyeng could you please repeat writing this log using platformio device monitor with exception filter:
pio device monitor --filter esp32_exception_decoder
This should show the function and line in code where the crash happenes. This hopefully will point us to the root cause.

@t-huyeng
Copy link
Author

I thought this was already activated via the platformio.ini - monitor_filters but explicit calling it shows more information:

....
[ 70621][I][blescan.cpp:286] stop_BLE_scan(): [bluetooth] Bluetooth scanner stopped
[ 70621][I][reset.cpp:110] enter_deepsleep(): [src/reset.cpp] Waiting until send queues are empty...
[ 70626][I][reset.cpp:119] enter_deepsleep(): [src/reset.cpp] Waiting until LMIC is idle...
[ 71709][I][lorawan.cpp:408] myRxCallback(): [src/lorawan.cpp] Received 3 byte(s) of payload on port 2
[ 71709][I][rcommand.cpp:259] set_blescan(): [src/rcommand.cpp] Remote command: set BLE scanner to off

assert failed: xTimerGenericCommand timers.c:404 (xTimer)


Backtrace:0x400840a1:0x3ffd67300x400969c9:0x3ffd6750 0x4009ca29:0x3ffd6770 0x40099f9d:0x3ffd68a0 0x400da379:0x3ffd68d0 0x400d895a:0x3ffd68f0 0x400d918d:0x3ffd6940 0x400d92b2:0x3ffd69a0 

  #0  0x400840a1:0x3ffd6730 in panic_abort at /Users/ficeto/Desktop/ESP32/ESP32S2/esp-idf-public/components/esp_system/panic.c:402
  #1  0x400969c9:0x3ffd6750 in esp_system_abort at /Users/ficeto/Desktop/ESP32/ESP32S2/esp-idf-public/components/esp_system/esp_system.c:128
  #2  0x4009ca29:0x3ffd6770 in __assert_func at /Users/ficeto/Desktop/ESP32/ESP32S2/esp-idf-public/components/newlib/assert.c:85
  #3  0x40099f9d:0x3ffd68a0 in xTimerGenericCommand at /Users/ficeto/Desktop/ESP32/ESP32S2/esp-idf-public/components/freertos/timers.c:404
      (inlined by) xTimerGenericCommand at /Users/ficeto/Desktop/ESP32/ESP32S2/esp-idf-public/components/freertos/timers.c:395
  #4  0x400da379:0x3ffd68d0 in libpax_counter_stop() at .pio/libdeps/usb/libpax/lib/libpax/libpax_api.cpp:201
  #5  0x400d895a:0x3ffd68f0 in set_blescan(unsigned char*) at src/rcommand.cpp:262 (discriminator 6)
  #6  0x400d918d:0x3ffd6940 in rcmd_execute(unsigned char const*, unsigned char) at src/rcommand.cpp:452
  [    46][I][esp32-hal-i2c.c:75] i2cInit(): Initialising I2C Master: sda=4 scl=15 freq=100000
[    56][W][Wire.cpp:301] begin(): Bus already started in Master Mode.
[   206][I][main.cpp:229] setup(): [src/main.cpp] Starting LED Controller...
[   207][I][power.cpp:194] calibrate_voltage(): [src/power.cpp] ADC characterization based on reference voltage stored in eFuse
....

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment