OpenHAB 3 - MQTT Items not persisted via JDBC/MariaDB

After migration from OH2 (used several years) to OH3 there is no more working Items persistence for Items received from MQTT broker.
JDBC persistence works, as SystemInfo binding is successfully persisting Items defined in jdbc.persist to MariaDB.

Seems

  • some additional configuration needed (did not find anything related in OpenHAB web, also did not find similar issues in Community/web) or
  • a bug in the Item / persistence area.

jdbc.cfg

############################ JDBC Persistence Service ##################################
# I N S T A L L   J D B C   P E R S I S T E N C E   S E R V I C E
#
# https://github.com/openhab/openhab/wiki/JDBC-Persistence
#
# Tested databases/url-prefix: jdbc:derby, jdbc:h2, jdbc:hsqldb, jdbc:mariadb, jdbc:mysql, jdbc:postgresql, jdbc:sqlite
#
# derby, h2, hsqldb, sqlite can be embedded,
# If no database is available it will be created, for example the url 'jdbc:h2:./testH2' creates a new DB in OpenHab Folder.
#
# Create new database, for example on a MySQL-Server use:
# CREATE DATABASE 'yourDB' CHARACTER SET utf8 COLLATE utf8_general_ci;

# D A T A B A S E  C O N F I G
# Some URL-Examples, 'service' identifies and activates internally the correct jdbc driver.
# required database url like 'jdbc:<service>:<host>[:<port>;<attributes>]'
# url=jdbc:derby:./testDerby;create=true
# url=jdbc:h2:./testH2
# url=jdbc:hsqldb:./testHsqlDb
# url=jdbc:mariadb://192.168.0.1:3306/testMariadb
# url=jdbc:mysql://192.168.0.1:3306/testMysql
# url=jdbc:postgresql://192.168.0.1:5432/testPostgresql
# url=jdbc:sqlite:./testSqlite.db
url=jdbc:mariadb://127.0.0.1:3306/openhab2_persistence

# required database user
user=**********

# required database password
password=****************

# E R R O R   H A N D L I N G
# optional when Service is deactivated (optional, default: 0 -> ignore)
#errReconnectThreshold=

# I T E M   O P E R A T I O N S
# optional tweaking SQL datatypes
# see: https://mybatis.github.io/mybatis-3/apidocs/reference/org/apache/ibatis/type/JdbcType.html
# see: http://www.h2database.com/html/datatypes.html
# see: http://www.postgresql.org/docs/9.3/static/datatype.html
# defaults:
#sqltype.CALL          =   VARCHAR(200)
#sqltype.COLOR         =   VARCHAR(70)
#sqltype.CONTACT       =   VARCHAR(6)
#sqltype.DATETIME      =   DATETIME
#sqltype.DIMMER        =   TINYINT
#sqltype.LOCATION      =   VARCHAR(30)
#sqltype.NUMBER        =   DOUBLE
#sqltype.ROLLERSHUTTER =   TINYINT
#sqltype.STRING        =   VARCHAR(65500)
#sqltype.SWITCH        =   VARCHAR(6)

# For Itemtype "Number" default decimal digit count (optional, default: 3)
#numberDecimalcount=

# T A B L E   O P E R A T I O N S
# Tablename Prefix String (optional, default: "item")
# for Migration from MYSQL-Bundle set to 'Item'.
#tableNamePrefix=Item

# Tablename Prefix generation, using Item real names or "item" (optional, default: false -> "item")
# If true, 'tableNamePrefix' is ignored.
tableUseRealItemNames=true

# Tablename Suffix length (optional, default: 4 -> 0001-9999)
# for Migration from MYSQL-Bundle set to 0.
#tableIdDigitCount=

# Rename existing Tables using tableUseRealItemNames and tableIdDigitCount (optional, default: false)
# USE WITH CARE! Deactivate after Renaming is done!
#rebuildTableNames=true

# D A T A B A S E  C O N N E C T I O N S
# Some embeded Databases can handle only one Connection (optional, default: configured per database in packet org.openhab.persistence.jdbc.db.* )
# see: https://github.com/brettwooldridge/HikariCP/issues/256
# jdbc.maximumPoolSize = 1
# jdbc.minimumIdle = 1

# T I M E K E E P I N G
# (optional, default: false)

jdbc.persist

// Persistence strategies have a name and a definition and are referred to in the "Items" section
Strategies {
  //everyMinute :   "0 * * * * ?"
  //everyHour :     "0 0 * * * ?"
  //everyDay  :     "0 0 0 * * ?"

  // If no strategy is specified for an item entry below, the default list will be used.
  default = everyChange
}

/*
 * Each line in this section defines for which item(s) which strategy(ies) should be applied.
 * You can list single items, use "*" for all items or "groupitem*" for all members of a group
 * item (excl. the group item itself).
 */
Items {
  //* : strategy = everyChange

  //*****************
  //RPi4 SystemInfo
  //*****************
  RPi4_Cpu_LoadAverage1       : strategy = everyChange
  RPi4_Cpu_NumberOfThreads    : strategy = everyChange
  RPi4_Sensors_CPUTemperature : strategy = everyChange
  RPi4_Memory_Used_percent    : strategy = everyChange

  //*****************
  //PIC18F27J53 items (received from MQTT broker)
  //*****************

  i02_State                        : strategy = everyChange
  LAN_PIC18F27j53_ENC28j60_Online  : strategy = everyChange
  LAN_PIC18F27j53_ENC28j60_Latency : strategy = everyChange

  i02_MQTT_CONNECT_count : strategy = everyChange
  i02_MQTT_dbg_u16 : strategy = everyChange
  i02_MQTT_dbg_u32 : strategy = everyChange
  i02_MQTT_dbg_f   : strategy = everyChange

  i02_ETH_PktsRxErr : strategy = everyChange
  i02_ETH_PktsTxErr : strategy = everyChange
//  i02_ETH_PktsRxOK  : strategy = everyChange
//  i02_ETH_PktsTxOK  : strategy = everyChange

  i02_DHT11_T     : strategy = everyChange
  i02_DHT11_RH    : strategy = everyChange
  i02_DHT11_alive : strategy = everyChange

  i02_DS18x20_109EC27A01080096_T : strategy = everyChange
  i02_DS18x20_28781E7C05000076_T : strategy = everyChange
  i02_DS18x20_2834791105000033_T : strategy = everyChange
  i02_DS18x20_288EDB7B050000C0_T : strategy = everyChange
  i02_DS18x20_28FECD10050000BC_T : strategy = everyChange
  i02_DS18x20_280D821005000011_T : strategy = everyChange
  i02_DS18x20_2887EE1005000004_T : strategy = everyChange
  i02_DS18x20_28570111050000FA_T : strategy = everyChange
  i02_DS18x20_28F08A1105000098_T : strategy = everyChange
  i02_DS18x20_283CAB10050000C0_T : strategy = everyChange

  i02_VEML6075_UVA_wm2 : strategy = everyChange
  i02_VEML6075_UVB_wm2 : strategy = everyChange
  i02_VEML6075_UVA_idx : strategy = everyChange
  i02_VEML6075_UVB_idx : strategy = everyChange
  i02_VEML6075_UV_idx  : strategy = everyChange
  i02_VEML6075_alive   : strategy = everyChange

  i02_CCS811_CO2_ppm    : strategy = everyChange
  i02_CCS811_TVOC_ppb   : strategy = everyChange
  i02_CCS811_alive      : strategy = everyChange

  i02_SGP30_CO2_ppm     : strategy = everyChange
  i02_SGP30_TVOC_ppb    : strategy = everyChange
  i02_SGP30_Ethanol_raw : strategy = everyChange
  i02_SGP30_H2_raw      : strategy = everyChange
  i02_SGP30_alive       : strategy = everyChange

  i02_SCD4X_CO2_ppm     : strategy = everyChange
  i02_SCD4X_T           : strategy = everyChange
  i02_SCD4X_RH          : strategy = everyChange
  i02_SCD4X_alive       : strategy = everyChange

  i02_SI7021_RH     : strategy = everyChange
  i02_SI7021_T      : strategy = everyChange
  i02_SI7021_alive  : strategy = everyChange

  i02_BMP180_T     : strategy = everyChange
  i02_BMP180_P_pa  : strategy = everyChange
  i02_BMP180_alive : strategy = everyChange

  i02_BMx280_T     : strategy = everyChange
  i02_BMx280_P_pa  : strategy = everyChange
  i02_BME280_RH    : strategy = everyChange
  i02_BME280_alive : strategy = everyChange

  i02_BME680_T     : strategy = everyChange
  i02_BME680_P_pa  : strategy = everyChange
  i02_BME680_RH    : strategy = everyChange
  i02_BME680_Q     : strategy = everyChange
  i02_BME680_alive : strategy = everyChange

  i02_MLX90615_Ta    : strategy = everyChange
  i02_MLX90615_To    : strategy = everyChange
  i02_MLX90615_alive : strategy = everyChange
}

log:set DEBUG org.openhab
log:tail

> 20:11:58.834 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_DS18x20:I02_DS18x20_2887EE1005000004_T triggered 28.937504
> 20:11:58.837 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:11:58.837 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '8' of rule 'mqtt-4' is triggered.
> 20:11:58.841 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:11:58.845 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_DS18x20_2887EE1005000004_T/state.
> 20:11:58.845 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:11:58.850 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_DS18x20_2887EE1005000004_T' changed from 28.875008 to 28.937504
> 20:11:59.038 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Used/state.
> 20:11:59.042 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Available/state.
> 20:11:59.046 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Available/state.
> 20:11:59.050 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Available_percent/state.
> 20:11:59.068 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Available/state.
> 20:11:59.097 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Used_percent/state.
> 20:11:59.101 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Available_percent/state.
> 20:11:59.130 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Used/state.
> 20:11:59.134 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Used_percent/state.
> 20:11:59.155 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Available_percent/state.
> 20:11:59.158 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Used_percent/state.
> 20:11:59.161 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Sensors_CPUTemperature/state.
> 20:11:59.164 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Used/state.
> 20:11:59.175 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_SI7021:I02_SI7021_RH triggered 52.40
> 20:11:59.177 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '29' of rule 'mqtt-4' is triggered.
> 20:11:59.178 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:11:59.180 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:11:59.184 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:11:59.184 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_SI7021_RH/state.
> 20:11:59.189 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_SI7021_RH' changed from 52.50 to 52.40
> 20:12:03.123 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_DS18x20:I02_DS18x20_2887EE1005000004_T triggered 28.875008
> 20:12:03.127 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '8' of rule 'mqtt-4' is triggered.
> 20:12:03.127 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:03.131 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:03.137 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:03.137 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_DS18x20_2887EE1005000004_T/state.
> 20:12:03.145 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_DS18x20_2887EE1005000004_T' changed from 28.937504 to 28.875008
> 20:12:03.727 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Used/state.
> 20:12:03.732 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Available/state.
> 20:12:03.736 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Available/state.
> 20:12:03.741 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Available_percent/state.
> 20:12:03.771 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Available/state.
> 20:12:03.803 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Used_percent/state.
> 20:12:03.806 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Available_percent/state.
> 20:12:03.826 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Used/state.
> 20:12:03.828 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Used_percent/state.
> 20:12:03.847 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Available_percent/state.
> 20:12:03.850 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Used_percent/state.
> 20:12:03.853 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Sensors_CPUTemperature/state.
> 20:12:03.856 [DEBUG] [.persistence.jdbc.internal.JdbcMapper] - JDBC::storeItemValue: item=RPi4_Sensors_CPUTemperature (Type=NumberItem, State=52.1, Label=RPi4 CPU Temperature, Category=null)
> 20:12:03.857 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Used/state.
> 20:12:03.859 [DEBUG] [g.openhab.persistence.jdbc.dto.ItemVO] - JDBC:ItemVO tableName=rpi4_sensors_cputemperature_0115; newTableName=null;
> 20:12:03.861 [DEBUG] [enhab.persistence.jdbc.db.JdbcBaseDAO] - JDBC::getItemType: Try to use ItemType NUMBERITEM for Item RPi4_Sensors_CPUTemperature
> 20:12:03.862 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'RPi4_Sensors_CPUTemperature' changed from 53.1 to 52.1
> 20:12:03.863 [DEBUG] [enhab.persistence.jdbc.db.JdbcBaseDAO] - JDBC::storeItemValueProvider: item 'RPi4_Sensors_CPUTemperature' as Type 'NUMBERITEM' in 'rpi4_sensors_cputemperature_0115' with state '52.1'
> 20:12:03.866 [DEBUG] [enhab.persistence.jdbc.db.JdbcBaseDAO] - JDBC::storeItemValueProvider: getState: '52.1'
> 20:12:03.868 [DEBUG] [g.openhab.persistence.jdbc.dto.ItemVO] - JDBC:ItemVO setValueTypes dbType=DOUBLE; javaType=class java.lang.Double;
> 20:12:03.870 [DEBUG] [enhab.persistence.jdbc.db.JdbcBaseDAO] - JDBC::storeItemValueProvider: newVal.doubleValue: '52.1'
> 20:12:03.872 [DEBUG] [enhab.persistence.jdbc.db.JdbcBaseDAO] - JDBC::doStoreItemValue sql=INSERT INTO rpi4_sensors_cputemperature_0115 (TIME, VALUE) VALUES( NOW(3), ? ) ON DUPLICATE KEY UPDATE VALUE= ? value='52.1'
> 20:12:03.886 [DEBUG] [.jdbc.internal.JdbcPersistenceService] - JDBC: Stored item 'RPi4_Sensors_CPUTemperature' as '52.1' in SQL database at Tue Jun 28 20:12:03 CEST 2022 in 30 ms.
> 20:12:05.079 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_BMx280:I02_BMx280_P_pa triggered 99988.00
> 20:12:05.082 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '34' of rule 'mqtt-4' is triggered.
> 20:12:05.082 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:05.085 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:05.090 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:05.090 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_BMx280_P_pa/state.
> 20:12:05.096 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_BMx280_P_pa' changed from 99986.00 to 99988.00
> 20:12:06.708 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_CCS811:I02_CCS811_CO2_ppm triggered 2066
> 20:12:06.710 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '17' of rule 'mqtt-4' is triggered.
> 20:12:06.710 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:06.712 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:06.716 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:06.716 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_CCS811_CO2_ppm/state.
> 20:12:06.720 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_CCS811_CO2_ppm' changed from 1804 to 2066
> 20:12:07.046 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Used/state.
> 20:12:07.049 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Available/state.
> 20:12:07.052 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Available/state.
> 20:12:07.056 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Available_percent/state.
> 20:12:07.066 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_CCS811:I02_CCS811_TVOC_ppb triggered 854
> 20:12:07.070 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '18' of rule 'mqtt-4' is triggered.
> 20:12:07.070 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:07.073 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:07.078 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_CCS811_TVOC_ppb/state.
> 20:12:07.078 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:07.083 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Available/state.
> 20:12:07.088 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_CCS811_TVOC_ppb' changed from 454 to 854
> 20:12:07.113 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Used_percent/state.
> 20:12:07.116 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Available_percent/state.
> 20:12:07.139 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Used/state.
> 20:12:07.142 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Used_percent/state.
> 20:12:07.145 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'RPi4_Storage_Used' changed from 17641 to 17642
> 20:12:07.161 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Available_percent/state.
> 20:12:07.165 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Used_percent/state.
> 20:12:07.169 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Sensors_CPUTemperature/state.
> 20:12:07.172 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Used/state.
> 20:12:07.176 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'RPi4_Memory_Used' changed from 1434 to 1435
> 20:12:08.738 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_DS18x20:I02_DS18x20_2887EE1005000004_T triggered 28.937504
> 20:12:08.740 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '8' of rule 'mqtt-4' is triggered.
> 20:12:08.740 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:08.742 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:08.746 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:08.746 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_DS18x20_2887EE1005000004_T/state.
> 20:12:08.752 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_DS18x20_2887EE1005000004_T' changed from 28.875008 to 28.937504
> 20:12:09.081 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_SGP30:I02_SGP30_CO2_ppm triggered 1445
> 20:12:09.085 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '20' of rule 'mqtt-4' is triggered.
> 20:12:09.085 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:09.088 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:09.096 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_SGP30_CO2_ppm/state.
> 20:12:09.097 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:09.102 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_SGP30_CO2_ppm' changed from 1437 to 1445
> 20:12:09.178 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Used/state.
> 20:12:09.183 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Available/state.
> 20:12:09.187 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Available/state.
> 20:12:09.191 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Available_percent/state.
> 20:12:09.219 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Available/state.
> 20:12:09.256 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Used_percent/state.
> 20:12:09.259 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Available_percent/state.
> 20:12:09.277 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Used/state.
> 20:12:09.280 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Used_percent/state.
> 20:12:09.299 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Storage_Available_percent/state.
> 20:12:09.302 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Swap_Used_percent/state.
> 20:12:09.306 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Sensors_CPUTemperature/state.
> 20:12:09.309 [DEBUG] [.persistence.jdbc.internal.JdbcMapper] - JDBC::storeItemValue: item=RPi4_Sensors_CPUTemperature (Type=NumberItem, State=53.1, Label=RPi4 CPU Temperature, Category=null)
> 20:12:09.309 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/RPi4_Memory_Used/state.
> 20:12:09.311 [DEBUG] [g.openhab.persistence.jdbc.dto.ItemVO] - JDBC:ItemVO tableName=rpi4_sensors_cputemperature_0115; newTableName=null;
> 20:12:09.313 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'RPi4_Sensors_CPUTemperature' changed from 52.1 to 53.1
> 20:12:09.314 [DEBUG] [enhab.persistence.jdbc.db.JdbcBaseDAO] - JDBC::getItemType: Try to use ItemType NUMBERITEM for Item RPi4_Sensors_CPUTemperature
> 20:12:09.317 [DEBUG] [enhab.persistence.jdbc.db.JdbcBaseDAO] - JDBC::storeItemValueProvider: item 'RPi4_Sensors_CPUTemperature' as Type 'NUMBERITEM' in 'rpi4_sensors_cputemperature_0115' with state '53.1'
> 20:12:09.319 [DEBUG] [enhab.persistence.jdbc.db.JdbcBaseDAO] - JDBC::storeItemValueProvider: getState: '53.1'
> 20:12:09.321 [DEBUG] [g.openhab.persistence.jdbc.dto.ItemVO] - JDBC:ItemVO setValueTypes dbType=DOUBLE; javaType=class java.lang.Double;
> 20:12:09.323 [DEBUG] [enhab.persistence.jdbc.db.JdbcBaseDAO] - JDBC::storeItemValueProvider: newVal.doubleValue: '53.1'
> 20:12:09.325 [DEBUG] [enhab.persistence.jdbc.db.JdbcBaseDAO] - JDBC::doStoreItemValue sql=INSERT INTO rpi4_sensors_cputemperature_0115 (TIME, VALUE) VALUES( NOW(3), ? ) ON DUPLICATE KEY UPDATE VALUE= ? value='53.1'
> 20:12:09.334 [DEBUG] [.jdbc.internal.JdbcPersistenceService] - JDBC: Stored item 'RPi4_Sensors_CPUTemperature' as '53.1' in SQL database at Tue Jun 28 20:12:09 CEST 2022 in 25 ms.
> 20:12:09.434 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_SGP30:I02_SGP30_TVOC_ppb triggered 168
> 20:12:09.436 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '21' of rule 'mqtt-4' is triggered.
> 20:12:09.436 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:09.438 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:09.442 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:09.442 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_SGP30_TVOC_ppb/state.
> 20:12:09.446 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_SGP30_TVOC_ppb' changed from 167 to 168
> 20:12:09.794 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_SGP30:I02_SGP30_Ethanol_raw triggered 36.546880
> 20:12:09.796 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '24' of rule 'mqtt-4' is triggered.
> 20:12:09.796 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:09.798 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:09.802 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_SGP30_Ethanol_raw/state.
> 20:12:09.802 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:09.806 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_SGP30_Ethanol_raw' changed from 36.548832 to 36.546880
> 20:12:10.136 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_SGP30:I02_SGP30_H2_raw triggered 24.970704
> 20:12:10.139 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '25' of rule 'mqtt-4' is triggered.
> 20:12:10.139 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:10.142 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:10.148 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:10.148 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_SGP30_H2_raw/state.
> 20:12:10.155 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_SGP30_H2_raw' changed from 24.964848 to 24.970704
> 20:12:11.793 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_SCD4X:I02_SCD4X_CO2_ppm triggered 1629
> 20:12:11.797 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '26' of rule 'mqtt-4' is triggered.
> 20:12:11.798 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:11.802 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:11.809 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_SCD4X_CO2_ppm/state.
> 20:12:11.810 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:11.816 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_SCD4X_CO2_ppm' changed from 1628 to 1629
> 20:12:12.132 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_SCD4X:I02_SCD4X_T triggered 31.89
> 20:12:12.136 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '27' of rule 'mqtt-4' is triggered.
> 20:12:12.136 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:12.139 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:12.147 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:12.147 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_SCD4X_T/state.
> 20:12:12.154 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_SCD4X_T' changed from 31.88 to 31.89
> 20:12:12.485 [INFO ] [openhab.event.ChannelTriggeredEvent  ] - mqtt:topic:mosquitto:T02_SCD4X:I02_SCD4X_RH triggered 44.25
> 20:12:12.489 [DEBUG] [re.automation.internal.RuleEngineImpl] - The trigger '28' of rule 'mqtt-4' is triggered.
> 20:12:12.489 [DEBUG] [ntime.internal.engine.DSLScriptEngine] - Script uses context 'mqtt-4'.
> 20:12:12.493 [DEBUG] [o.monitor.internal.metrics.RuleMetric] - Rule mqtt-4 RUNNING - updating metric.
> 20:12:12.502 [DEBUG] [tor.internal.metrics.EventCountMetric] - Received event on topic openhab/items/i02_SCD4X_RH/state.
> 20:12:12.503 [DEBUG] [re.automation.internal.RuleEngineImpl] - The rule 'mqtt-4' is executed.
> 20:12:12.516 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_SCD4X_RH' changed from 44.19 to 44.25

Anybody having similar experience?
Any advices are welcome.

Persistence works with Items. It doesn’t know an MQTT Item from a Zwave Item from an Item not linked to anything at all. So what ever problem you are seeing has nothing to do with MQTT itself. There is something different about those Items from the SystemInfo Items. That’s where you need to focus.

Things that could cause problems:

  1. The Item names do not match exactly in the .persist file (case matters)
  2. The Items are not changing, the strategy only saves the Items on change
  3. Not all changes to .persist files are picked up immediately after a save, have you restarted OH after saving the .persist file?

I also do not think there is direct Item dependency on a what way it is populated (via MQTT).
However only those MQTT Items are not persisted :frowning:

  1. not a case - OH2 worked OH3 not
    jdbc.persist
    i02_DS18x20_2887EE1005000004_T : strategy = everyChange
    openhab log
    > 20:11:58.850 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'i02_DS18x20_2887EE1005000004_T' changed from 28.875008 to 28.937504
  2. not a case:
> 20:11:58.850 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_DS18x20_2887EE1005000004_T' changed from 28.875008 to 28.937504
> 20:11:59.189 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_SI7021_RH' changed from 52.50 to 52.40
> 20:12:05.096 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'i02_BMx280_P_pa' changed from 99986.00 to 99988.00
  1. did not change anything in OH2 configuration, was transferred to OH3 automatically during OH3 installation.
    3.1 However did a restart from shell now and it started to work.
    3.2 Did also RPI4 reboot and retained OK :slight_smile:
    Yesterday I also did some configuration changes in OH3 GUI, might be it relates.

This topic was automatically closed 41 days after the last reply. New replies are no longer allowed.