Does NVS subsys sample / zephyr SETTINGS require pre erased memory region?

[zafersn]

I’m working with the NVS subsystem sample, and I’m seeing NVS write operations fail with error -5 (EIO) and the following message: flash_stm32_check_status: Status: 0x00000088 This happens when the storage partition sectors already contain data. It seems that the NVS sample requires the flash area to be pre-erased before use.

• Is this the expected behaviour for the out-of-box NVS sample?
• Should i add additional code that erases the relevant sectors before using this sample?
• Should NVS detect write failures and automatically trigger sector erase + retry?

NOTE THAT: I am also seeing the same problem/behaivor when I use the zephyr SETTINGS(CONFIG_SETTINGS), it returns -5 when it calls: settings_save_one.

HW: STM32U575VI
Zephyr: v4.3.0-branch
Here is my config:

Storage partition: 0x1EC000, size 64KB (8 sectors × 8KB)
CONFIG_SETTINGS_NVS_SECTOR_COUNT=8
CONFIG_SETTINGS_NVS_SECTOR_SIZE_MULT=1

here is the log:

[00:00:00.000,000] <dbg> flash_stm32: stm32_flash_init: Flash @0x8000000 initialized. BS: 16
[00:00:00.008,000] <dbg> flash_stm32: stm32_flash_init: Block 0: bs: 8192 count: 256
*** Booting Zephyr OS build v4.3.0-rc3-13-g721ffc614fe7 ***
[00:00:00.022,000] <dbg> fs_nvs: nvs_recover_last_ate: Recovering last ate from sector 2
[00:00:00.034,000] <inf> fs_nvs: 3 Sectors of 8192 bytes
[00:00:00.040,000] <inf> fs_nvs: alloc wra: 2, 1fc0
[00:00:00.045,000] <inf> fs_nvs: data wra: 2, 90
Erasing storage partition at offset 0x1ec000, size 0x2000 count: 3
No address found, adding 192.168.1.1 at id 1
[00:00:00.061,000] <dbg> fs_nvs: nvs_write: 1083 Writing NVS entry: id=1, len=12
[00:00:00.068,000] <dbg> fs_nvs: nvs_write: 1088 NVS write: id=1, len=12
[00:00:00.076,000] <dbg> fs_nvs: nvs_write: 1102 NVS write after len check: id=1, len=12
[00:00:00.084,000] <dbg> fs_nvs: nvs_write: 1114 NVS write before while: id=1, len=12
[00:00:00.093,000] <dbg> fs_nvs: nvs_write: 1130 NVS write after while: id=1, len=12
[00:00:00.101,000] <dbg> fs_nvs: nvs_write: 1166 NVS write before required space calc: id=1, len=12
[00:00:00.110,000] <dbg> fs_nvs: nvs_write: 1184 NVS write before space check: id=1, len=12
[00:00:00.119,000] <dbg> fs_nvs: nvs_flash_wrt_entry: 458 Writing NVS entry: id=1, offset=144, len=12
[00:00:00.129,000] <dbg> fs_nvs: nvs_flash_al_wrt: 115 NVS data write: len=12
[00:00:00.136,000] <dbg> flash_stm32: flash_stm32_write: 214 Write requested offset: 2031760, len: 16
[00:00:00.146,000] <dbg> flash_stm32: flash_stm32_write: 221 Write validated offset: 2031760, len: 16
[00:00:00.156,000] <dbg> flash_stm32: flash_stm32_write: Write offset: 2031760, len: 16
[00:00:00.164,000] <dbg> flash_stm32: flash_stm32_cr_lock: Disable write protection
[00:00:00.172,000] <dbg> flash_stm32: flash_stm32_write: 232 Write after unlock offset: 2031760, len: 16 RC=0
[00:00:00.182,000] <dbg> flash_stm32l5: flash_stm32_write_range: 270 Write range offset: 2031760, len: 16 cache_enabled=1
[00:00:00.194,000] <dbg> flash_stm32l5: flash_stm32_write_range: 273 Write range after icache disable offset: 2031760, len: 16
[00:00:00.206,000] <dbg> flash_stm32l5: write_nwords: 86 Write nwords offset: 2031760, n: 4
[00:00:00.214,000] <dbg> flash_stm32l5: write_nwords: 93 Write nwords after NSCR check offset: 2031760, n: 4
[00:00:00.225,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:00.234,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=0
[00:00:00.244,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 96 Wait flash idle after check status RC=0
[00:00:00.254,000] <dbg> flash_stm32l5: write_nwords: 97 Write nwords after wait idle offset: 2031760, n: 4 RC=0
[00:00:00.265,000] <dbg> flash_stm32l5: write_nwords: 102 Write nwords after wait idle offset: 2031760, n: 4
[00:00:00.275,000] <dbg> flash_stm32l5: write_nwords: 117 Write nwords after check full zero offset: 2031760, n: 4
[00:00:00.286,000] <dbg> flash_stm32l5: write_nwords: 127 Write nwords after check erased offset: 2031760, n: 4
[00:00:00.296,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:00.305,000] <dbg> flash_stm32: flash_stm32_check_status: Status: 0x00000088
[00:00:00.313,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=-5
[00:00:00.324,000] <dbg> flash_stm32l5: write_nwords: 142 Write nwords after wait idle offset: 2031760, n: 4 RC=-5
[00:00:00.335,000] <dbg> flash_stm32l5: flash_stm32_write_range: 282 Write range after write_nwords offset: 2031760, len: 16 RC=-5
[00:00:00.347,000] <dbg> flash_stm32: flash_stm32_write: 237 Write after write range offset: 2031760, len: 16 RC=-5
[00:00:00.358,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:00.367,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=0
[00:00:00.377,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 96 Wait flash idle after check status RC=0
[00:00:00.387,000] <dbg> flash_stm32: flash_stm32_cr_lock: Enable write protection
[00:00:00.395,000] <dbg> flash_stm32: flash_stm32_write: 246 Write complete offset: 2031760, len: 16 RC=-5
[00:00:00.405,000] <dbg> fs_nvs: nvs_flash_al_wrt: 139 NVS data write: len=16 RC=-5
[00:00:00.413,000] <dbg> fs_nvs: nvs_flash_data_wrt: 217 NVS data write without CRC: len=12 RC=-5
[00:00:00.423,000] <dbg> fs_nvs: nvs_flash_data_wrt: 220 NVS data write complete: RC=-5
[00:00:00.431,000] <dbg> fs_nvs: nvs_flash_wrt_entry: 460 NVS data write complete: id=1, len=12 RC=-5
[00:00:00.441,000] <dbg> fs_nvs: nvs_write: 1188 NVS write after flash write: id=1, len=12 RC=-5
[00:00:00.450,000] <dbg> fs_nvs: nvs_write: 1212 NVS write end: id=1, len=12 RC=-5
No key found, adding it at id 2
[00:00:00.461,000] <dbg> fs_nvs: nvs_write: 1083 Writing NVS entry: id=2, len=8
[00:00:00.469,000] <dbg> fs_nvs: nvs_write: 1088 NVS write: id=2, len=8
[00:00:00.476,000] <dbg> fs_nvs: nvs_write: 1102 NVS write after len check: id=2, len=8
[00:00:00.484,000] <dbg> fs_nvs: nvs_write: 1114 NVS write before while: id=2, len=8
[00:00:00.493,000] <dbg> fs_nvs: nvs_write: 1130 NVS write after while: id=2, len=8
[00:00:00.501,000] <dbg> fs_nvs: nvs_write: 1166 NVS write before required space calc: id=2, len=8
[00:00:00.510,000] <dbg> fs_nvs: nvs_write: 1184 NVS write before space check: id=2, len=8
[00:00:00.519,000] <dbg> fs_nvs: nvs_flash_wrt_entry: 458 Writing NVS entry: id=2, offset=160, len=8
[00:00:00.529,000] <dbg> fs_nvs: nvs_flash_al_wrt: 115 NVS data write: len=8
[00:00:00.536,000] <dbg> flash_stm32: flash_stm32_write: 214 Write requested offset: 2031776, len: 16
[00:00:00.546,000] <dbg> flash_stm32: flash_stm32_write: 221 Write validated offset: 2031776, len: 16
[00:00:00.555,000] <dbg> flash_stm32: flash_stm32_write: Write offset: 2031776, len: 16
[00:00:00.564,000] <dbg> flash_stm32: flash_stm32_cr_lock: Disable write protection
[00:00:00.572,000] <dbg> flash_stm32: flash_stm32_write: 232 Write after unlock offset: 2031776, len: 16 RC=0
[00:00:00.582,000] <dbg> flash_stm32l5: flash_stm32_write_range: 270 Write range offset: 2031776, len: 16 cache_enabled=1
[00:00:00.594,000] <dbg> flash_stm32l5: flash_stm32_write_range: 273 Write range after icache disable offset: 2031776, len: 16
[00:00:00.605,000] <dbg> flash_stm32l5: write_nwords: 86 Write nwords offset: 2031776, n: 4
[00:00:00.614,000] <dbg> flash_stm32l5: write_nwords: 93 Write nwords after NSCR check offset: 2031776, n: 4
[00:00:00.624,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:00.633,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=0
[00:00:00.644,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 96 Wait flash idle after check status RC=0
[00:00:00.654,000] <dbg> flash_stm32l5: write_nwords: 97 Write nwords after wait idle offset: 2031776, n: 4 RC=0
[00:00:00.665,000] <dbg> flash_stm32l5: write_nwords: 102 Write nwords after wait idle offset: 2031776, n: 4
[00:00:00.675,000] <dbg> flash_stm32l5: write_nwords: 117 Write nwords after check full zero offset: 2031776, n: 4
[00:00:00.686,000] <dbg> flash_stm32l5: write_nwords: 127 Write nwords after check erased offset: 2031776, n: 4
[00:00:00.696,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:00.705,000] <dbg> flash_stm32: flash_stm32_check_status: Status: 0x00000088
[00:00:00.713,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=-5
[00:00:00.724,000] <dbg> flash_stm32l5: write_nwords: 142 Write nwords after wait idle offset: 2031776, n: 4 RC=-5
[00:00:00.734,000] <dbg> flash_stm32l5: flash_stm32_write_range: 282 Write range after write_nwords offset: 2031776, len: 16 RC=-5
[00:00:00.747,000] <dbg> flash_stm32: flash_stm32_write: 237 Write after write range offset: 2031776, len: 16 RC=-5
[00:00:00.757,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:00.766,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=0
[00:00:00.777,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 96 Wait flash idle after check status RC=0
[00:00:00.787,000] <dbg> flash_stm32: flash_stm32_cr_lock: Enable write protection
[00:00:00.795,000] <dbg> flash_stm32: flash_stm32_write: 246 Write complete offset: 2031776, len: 16 RC=-5
[00:00:00.805,000] <dbg> fs_nvs: nvs_flash_al_wrt: 139 NVS data write: len=16 RC=-5
[00:00:00.813,000] <dbg> fs_nvs: nvs_flash_data_wrt: 217 NVS data write without CRC: len=8 RC=-5
[00:00:00.822,000] <dbg> fs_nvs: nvs_flash_data_wrt: 220 NVS data write complete: RC=-5
[00:00:00.831,000] <dbg> fs_nvs: nvs_flash_wrt_entry: 460 NVS data write complete: id=2, len=8 RC=-5
[00:00:00.840,000] <dbg> fs_nvs: nvs_write: 1188 NVS write after flash write: id=2, len=8 RC=-5
[00:00:00.849,000] <dbg> fs_nvs: nvs_write: 1212 NVS write end: id=2, len=8 RC=-5
No Reboot counter found, adding it at id 3
[00:00:00.862,000] <dbg> fs_nvs: nvs_write: 1083 Writing NVS entry: id=3, len=4
[00:00:00.869,000] <dbg> fs_nvs: nvs_write: 1088 NVS write: id=3, len=4
[00:00:00.876,000] <dbg> fs_nvs: nvs_write: 1102 NVS write after len check: id=3, len=4
[00:00:00.885,000] <dbg> fs_nvs: nvs_write: 1114 NVS write before while: id=3, len=4
[00:00:00.894,000] <dbg> fs_nvs: nvs_write: 1130 NVS write after while: id=3, len=4
[00:00:00.901,000] <dbg> fs_nvs: nvs_write: 1166 NVS write before required space calc: id=3, len=4
[00:00:00.911,000] <dbg> fs_nvs: nvs_write: 1184 NVS write before space check: id=3, len=4
[00:00:00.919,000] <dbg> fs_nvs: nvs_flash_wrt_entry: 458 Writing NVS entry: id=3, offset=176, len=4
[00:00:00.929,000] <dbg> fs_nvs: nvs_flash_al_wrt: 115 NVS data write: len=4
[00:00:00.936,000] <dbg> flash_stm32: flash_stm32_write: 214 Write requested offset: 2031792, len: 16
[00:00:00.946,000] <dbg> flash_stm32: flash_stm32_write: 221 Write validated offset: 2031792, len: 16
[00:00:00.956,000] <dbg> flash_stm32: flash_stm32_write: Write offset: 2031792, len: 16
[00:00:00.964,000] <dbg> flash_stm32: flash_stm32_cr_lock: Disable write protection
[00:00:00.972,000] <dbg> flash_stm32: flash_stm32_write: 232 Write after unlock offset: 2031792, len: 16 RC=0
[00:00:00.983,000] <dbg> flash_stm32l5: flash_stm32_write_range: 270 Write range offset: 2031792, len: 16 cache_enabled=1
[00:00:00.994,000] <dbg> flash_stm32l5: flash_stm32_write_range: 273 Write range after icache disable offset: 2031792, len: 16
[00:00:01.006,000] <dbg> flash_stm32l5: write_nwords: 86 Write nwords offset: 2031792, n: 4
[00:00:01.015,000] <dbg> flash_stm32l5: write_nwords: 93 Write nwords after NSCR check offset: 2031792, n: 4
[00:00:01.025,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:01.034,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=0
[00:00:01.044,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 96 Wait flash idle after check status RC=0
[00:00:01.054,000] <dbg> flash_stm32l5: write_nwords: 97 Write nwords after wait idle offset: 2031792, n: 4 RC=0
[00:00:01.065,000] <dbg> flash_stm32l5: write_nwords: 102 Write nwords after wait idle offset: 2031792, n: 4
[00:00:01.075,000] <dbg> flash_stm32l5: write_nwords: 117 Write nwords after check full zero offset: 2031792, n: 4
[00:00:01.086,000] <dbg> flash_stm32l5: write_nwords: 127 Write nwords after check erased offset: 2031792, n: 4
[00:00:01.097,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:01.106,000] <dbg> flash_stm32: flash_stm32_check_status: Status: 0x00000088
[00:00:01.114,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=-5
[00:00:01.124,000] <dbg> flash_stm32l5: write_nwords: 142 Write nwords after wait idle offset: 2031792, n: 4 RC=-5
[00:00:01.135,000] <dbg> flash_stm32l5: flash_stm32_write_range: 282 Write range after write_nwords offset: 2031792, len: 16 RC=-5
[00:00:01.147,000] <dbg> flash_stm32: flash_stm32_write: 237 Write after write range offset: 2031792, len: 16 RC=-5
[00:00:01.158,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:01.167,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=0
[00:00:01.177,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 96 Wait flash idle after check status RC=0
[00:00:01.187,000] <dbg> flash_stm32: flash_stm32_cr_lock: Enable write protection
[00:00:01.195,000] <dbg> flash_stm32: flash_stm32_write: 246 Write complete offset: 2031792, len: 16 RC=-5
[00:00:01.206,000] <dbg> fs_nvs: nvs_flash_al_wrt: 139 NVS data write: len=16 RC=-5
[00:00:01.214,000] <dbg> fs_nvs: nvs_flash_data_wrt: 217 NVS data write without CRC: len=4 RC=-5
[00:00:01.223,000] <dbg> fs_nvs: nvs_flash_data_wrt: 220 NVS data write complete: RC=-5
[00:00:01.231,000] <dbg> fs_nvs: nvs_flash_wrt_entry: 460 NVS data write complete: id=3, len=4 RC=-5
[00:00:01.241,000] <dbg> fs_nvs: nvs_write: 1188 NVS write after flash write: id=3, len=4 RC=-5
[00:00:01.250,000] <dbg> fs_nvs: nvs_write: 1212 NVS write end: id=3, len=4 RC=-5
Id: 4 not found, adding it
[00:00:01.261,000] <dbg> fs_nvs: nvs_write: 1083 Writing NVS entry: id=4, len=5
[00:00:01.268,000] <dbg> fs_nvs: nvs_write: 1088 NVS write: id=4, len=5
[00:00:01.275,000] <dbg> fs_nvs: nvs_write: 1102 NVS write after len check: id=4, len=5
[00:00:01.284,000] <dbg> fs_nvs: nvs_write: 1114 NVS write before while: id=4, len=5
[00:00:01.293,000] <dbg> fs_nvs: nvs_write: 1130 NVS write after while: id=4, len=5
[00:00:01.300,000] <dbg> fs_nvs: nvs_write: 1166 NVS write before required space calc: id=4, len=5
[00:00:01.310,000] <dbg> fs_nvs: nvs_write: 1184 NVS write before space check: id=4, len=5
[00:00:01.319,000] <dbg> fs_nvs: nvs_flash_wrt_entry: 458 Writing NVS entry: id=4, offset=192, len=5
[00:00:01.328,000] <dbg> fs_nvs: nvs_flash_al_wrt: 115 NVS data write: len=5
[00:00:01.336,000] <dbg> flash_stm32: flash_stm32_write: 214 Write requested offset: 2031808, len: 16
[00:00:01.345,000] <dbg> flash_stm32: flash_stm32_write: 221 Write validated offset: 2031808, len: 16
[00:00:01.355,000] <dbg> flash_stm32: flash_stm32_write: Write offset: 2031808, len: 16
[00:00:01.363,000] <dbg> flash_stm32: flash_stm32_cr_lock: Disable write protection
[00:00:01.371,000] <dbg> flash_stm32: flash_stm32_write: 232 Write after unlock offset: 2031808, len: 16 RC=0
[00:00:01.382,000] <dbg> flash_stm32l5: flash_stm32_write_range: 270 Write range offset: 2031808, len: 16 cache_enabled=1
[00:00:01.393,000] <dbg> flash_stm32l5: flash_stm32_write_range: 273 Write range after icache disable offset: 2031808, len: 16
[00:00:01.405,000] <dbg> flash_stm32l5: write_nwords: 86 Write nwords offset: 2031808, n: 4
[00:00:01.414,000] <dbg> flash_stm32l5: write_nwords: 93 Write nwords after NSCR check offset: 2031808, n: 4
[00:00:01.424,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:01.433,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=0
[00:00:01.443,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 96 Wait flash idle after check status RC=0
[00:00:01.454,000] <dbg> flash_stm32l5: write_nwords: 97 Write nwords after wait idle offset: 2031808, n: 4 RC=0
[00:00:01.464,000] <dbg> flash_stm32l5: write_nwords: 102 Write nwords after wait idle offset: 2031808, n: 4
[00:00:01.474,000] <dbg> flash_stm32l5: write_nwords: 117 Write nwords after check full zero offset: 2031808, n: 4
[00:00:01.485,000] <dbg> flash_stm32l5: write_nwords: 127 Write nwords after check erased offset: 2031808, n: 4
[00:00:01.496,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:01.505,000] <dbg> flash_stm32: flash_stm32_check_status: Status: 0x00000088
[00:00:01.513,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=-5
[00:00:01.523,000] <dbg> flash_stm32l5: write_nwords: 142 Write nwords after wait idle offset: 2031808, n: 4 RC=-5
[00:00:01.534,000] <dbg> flash_stm32l5: flash_stm32_write_range: 282 Write range after write_nwords offset: 2031808, len: 16 RC=-5
[00:00:01.546,000] <dbg> flash_stm32: flash_stm32_write: 237 Write after write range offset: 2031808, len: 16 RC=-5
[00:00:01.557,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 88 Wait flash idle start 10
[00:00:01.566,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 91 Wait flash idle after check status RC=0
[00:00:01.576,000] <dbg> flash_stm32: flash_stm32_wait_flash_idle: 96 Wait flash idle after check status RC=0
[00:00:01.586,000] <dbg> flash_stm32: flash_stm32_cr_lock: Enable write protection
[00:00:01.594,000] <dbg> flash_stm32: flash_stm32_write: 246 Write complete offset: 2031808, len: 16 RC=-5
[00:00:01.605,000] <dbg> fs_nvs: nvs_flash_al_wrt: 139 NVS data write: len=16 RC=-5
[00:00:01.613,000] <dbg> fs_nvs: nvs_flash_data_wrt: 217 NVS data write without CRC: len=5 RC=-5
[00:00:01.622,000] <dbg> fs_nvs: nvs_flash_data_wrt: 220 NVS data write complete: RC=-5
[00:00:01.630,000] <dbg> fs_nvs: nvs_flash_wrt_entry: 460 NVS data write complete: id=4, len=5 RC=-5
[00:00:01.640,000] <dbg> fs_nvs: nvs_write: 1188 NVS write after flash write: id=4, len=5 RC=-5
[00:00:01.649,000] <dbg> fs_nvs: nvs_write: 1212 NVS write end: id=4, len=5 RC=-5
Longarray not found, adding it as id 5

[zafersn]

@Laczen , would you be able to have a look at this issue? Am I missing something here? Is it a limitation?

[Laczen]

@zafersn this is expected behavior. Doing an erase without noticing the user might result in valuable data being lost. IIRC there is some option to trigger an erase when data is detected at start of the sample.

[zafersn]

Thanks for your answer. Great to know that if it is expected, I'll get back to you after testing this.