server@server-PowerEdge-T30:~$ docker logs openremote-manager-1 Picked up JAVA_TOOL_OPTIONS: Sep 12, 2023 11:01:29 AM org.openremote.container.util.LogUtil getConfigInputStream INFO: Using built in logging configuration from classpath: logging.properties 2023-09-12 11:01:30.067 INFO [main ] org.openremote.container.Container : Metrics enabled: true 2023-09-12 11:01:30.131 INFO [main ] org.openremote.container.Container : >>> Starting runtime container... 2023-09-12 11:01:30.132 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.syslog.SyslogService 2023-09-12 11:01:30.133 INFO [main ] .openremote.manager.syslog.SyslogService : Syslog service enabled 2023-09-12 11:01:30.136 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.persistence.ManagerPersistenceService 2023-09-12 11:01:30.136 INFO [main ] container.persistence.PersistenceService : Preparing persistence service for database: POSTGRES 2023-09-12 11:01:30.176 INFO [main ] container.persistence.PersistenceService : Setting storage directory to '/storage' 2023-09-12 11:01:30.177 INFO [main ] container.persistence.PersistenceService : Opening database connection: jdbc:postgresql://postgresql:5432/openremote?currentSchema=openremote 2023-09-12 11:01:30.540 INFO [main ] ydb.core.internal.license.VersionPrinter : Flyway Community Edition 9.15.1 by Redgate 2023-09-12 11:01:30.541 INFO [main ] ydb.core.internal.license.VersionPrinter : See release notes here: https://rd.gt/416ObMi 2023-09-12 11:01:30.541 INFO [main ] ydb.core.internal.license.VersionPrinter : 2023-09-12 11:01:30.597 INFO [main ] .internal.database.base.BaseDatabaseType : Database: jdbc:postgresql://postgresql:5432/openremote (PostgreSQL 14.6) 2023-09-12 11:01:30.621 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.623 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb_toolkit" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.707 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.708 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb_toolkit" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.760 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.761 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb_toolkit" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.815 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.818 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb_toolkit" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.853 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.855 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb_toolkit" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.900 INFO [main ] lywaydb.core.internal.command.DbValidate : Successfully validated 7 migrations (execution time 00:00.031s) 2023-09-12 11:01:30.926 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.927 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb_toolkit" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.932 INFO [main ] flywaydb.core.internal.command.DbMigrate : Current version of schema "openremote": 20230120.01 2023-09-12 11:01:30.933 INFO [main ] flywaydb.core.internal.command.DbMigrate : Schema "openremote" is up to date. No migration necessary. 2023-09-12 11:01:30.935 INFO [main ] container.persistence.PersistenceService : Applied database schema migrations: 0 2023-09-12 11:01:30.961 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.962 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb_toolkit" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.977 INFO [main ] lywaydb.core.internal.command.DbValidate : Successfully validated 7 migrations (execution time 00:00.009s) 2023-09-12 11:01:30.993 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:30.994 WARNING [main ] ernal.sqlscript.DefaultSqlScriptExecutor : DB: extension "timescaledb_toolkit" already exists, skipping (SQL State: 42710 - Error Code: 0) 2023-09-12 11:01:31.138 INFO [main ] te.model.util.ValueUtil.MODEL_AND_VALUES : Initialising asset model... 2023-09-12 11:01:32.070 INFO [main ] SQL dialect : HHH000400: Using dialect: org.hibernate.dialect.PostgreSQLDialect 2023-09-12 11:01:33.120 ERROR [main ] org.hibernate.AssertionFailure : HHH000099: an assertion failure occurred (this may indicate a bug in Hibernate, but is more likely due to unsafe use of the session): org.hibernate.AssertionFailure: value involves formulas 2023-09-12 11:01:34.675 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.security.ManagerIdentityService 2023-09-12 11:01:34.676 INFO [main ] .manager.security.ManagerIdentityService : Enabling Keycloak identity provider 2023-09-12 11:01:34.684 INFO [main ] curity.keycloak.KeycloakIdentityProvider : Keycloak service URL: http://keycloak:8080/auth 2023-09-12 11:01:34.929 INFO [main ] curity.keycloak.KeycloakIdentityProvider : Connecting to Keycloak server: http://keycloak:8080/auth 2023-09-12 11:01:35.191 INFO [main ] curity.keycloak.KeycloakIdentityProvider : Keycloak identity provider available: http://keycloak:8080/auth 2023-09-12 11:01:35.198 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.setup.SetupService 2023-09-12 11:01:35.198 INFO [main ] rg.openremote.manager.setup.SetupService : Setup service disabled, clean install = false 2023-09-12 11:01:35.199 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.gateway.GatewayService 2023-09-12 11:01:35.228 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.container.timer.TimerService 2023-09-12 11:01:35.228 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.asset.AssetStorageService 2023-09-12 11:01:35.234 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.mqtt.MQTTBrokerService 2023-09-12 11:01:35.244 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.provisioning.ProvisioningService 2023-09-12 11:01:35.245 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.datapoint.AssetDatapointService 2023-09-12 11:01:35.247 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.datapoint.AssetPredictedDatapointService 2023-09-12 11:01:35.248 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.rules.RulesetStorageService 2023-09-12 11:01:35.250 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.asset.AttributeLinkingService 2023-09-12 11:01:35.251 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.asset.AssetProcessingService 2023-09-12 11:01:35.253 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.system.HealthService 2023-09-12 11:01:35.255 INFO [main ] .openremote.manager.system.HealthService : Health Status Provider: class org.openremote.container.persistence.PersistenceHealthStatusProvider 2023-09-12 11:01:35.257 INFO [main ] .openremote.manager.system.HealthService : Health Status Provider: class org.openremote.manager.system.JvmHealthStatusProvider 2023-09-12 11:01:35.258 INFO [main ] .openremote.manager.system.HealthService : Health Status Provider: class org.openremote.manager.system.SslHealthStatusProvider 2023-09-12 11:01:35.259 INFO [main ] .openremote.manager.system.HealthService : Health Status Provider: class org.openremote.manager.system.SystemHealthStatusProvider 2023-09-12 11:01:35.259 INFO [main ] .openremote.manager.system.HealthService : Health Status Provider: class org.openremote.manager.rules.RulesHealthStatusProvider 2023-09-12 11:01:35.260 INFO [main ] .openremote.manager.system.HealthService : Health Status Provider: class org.openremote.manager.agent.AgentHealthStatusProvider 2023-09-12 11:01:35.260 INFO [main ] .openremote.manager.system.HealthService : Health Status Provider: class org.openremote.manager.datapoint.AssetDatapointHealthStatusProvider 2023-09-12 11:01:35.261 INFO [main ] .openremote.manager.system.HealthService : Health Status Provider: class org.openremote.manager.datapoint.AssetPredictedDatapointHealthStatusProvider 2023-09-12 11:01:35.261 INFO [main ] .openremote.manager.system.HealthService : Health Status Provider: class org.openremote.manager.system.CamelHealthStatusProvider 2023-09-12 11:01:35.263 INFO [main ] .openremote.manager.system.HealthService : Metrics collection enabled 2023-09-12 11:01:35.275 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.simulator.SimulatorService 2023-09-12 11:01:35.276 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.map.MapService 2023-09-12 11:01:35.276 WARNING [main ] org.openremote.manager.map.MapService : Map tiles data file not found '/deployment.local/mapdata/mapdata.mbtiles', falling back to built in map 2023-09-12 11:01:35.277 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.notification.NotificationService 2023-09-12 11:01:35.279 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.webhook.WebhookService 2023-09-12 11:01:35.280 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.apps.ConsoleAppService 2023-09-12 11:01:35.297 INFO [main ] org.openremote.container.web.WebService : Deploying insecure web context: / 2023-09-12 11:01:35.354 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.notification.PushNotificationHandler 2023-09-12 11:01:35.355 WARNING [main ] ger.notification.PushNotificationHandler : OR_FIREBASE_CONFIG_FILE invalid path or file not readable: /deployment/manager/fcm.json 2023-09-12 11:01:35.356 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.notification.EmailNotificationHandler 2023-09-12 11:01:35.356 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.gateway.GatewayClientService 2023-09-12 11:01:35.357 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.asset.AssetModelService 2023-09-12 11:01:35.358 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.energy.EnergyOptimisationService 2023-09-12 11:01:35.359 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.energy.ForecastWindService 2023-09-12 11:01:35.359 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.energy.ForecastSolarService 2023-09-12 11:01:35.359 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.dashboard.DashboardStorageService 2023-09-12 11:01:35.360 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.security.AuthorisationService 2023-09-12 11:01:35.360 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.app.ConfigurationService 2023-09-12 11:01:35.361 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.asset.ForecastService 2023-09-12 11:01:35.361 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.agent.AgentService 2023-09-12 11:01:35.362 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.event.ClientEventService 2023-09-12 11:01:35.383 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.rules.RulesService 2023-09-12 11:01:35.406 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.container.message.MessageBrokerService 2023-09-12 11:01:35.423 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.web.ManagerWebService 2023-09-12 11:01:35.434 INFO [main ] org.openremote.container.web.WebService : Building web routing with handler(s): Console app info files 2023-09-12 11:01:35.472 INFO [main ] emote.container.security.IdentityService : Securing web deployment: /api 2023-09-12 11:01:35.650 INFO [main ] org.openremote.container.web.WebService : Deploying insecure web context: / 2023-09-12 11:01:35.652 INFO [main ] org.openremote.container.web.WebService : Deploying insecure web context: / 2023-09-12 11:01:35.653 INFO [main ] org.openremote.container.Container : Initializing service: org.openremote.manager.rules.geofence.ORConsoleGeofenceAssetAdapter 2023-09-12 11:01:35.656 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.syslog.SyslogService 2023-09-12 11:01:35.663 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.persistence.ManagerPersistenceService 2023-09-12 11:01:35.663 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.security.ManagerIdentityService 2023-09-12 11:01:35.664 INFO [main ] security.ManagerKeycloakIdentityProvider : Loading OR_KEYCLOAK_GRANT_FILE: /deployment/manager/keycloak.json 2023-09-12 11:01:35.721 INFO [main ] curity.keycloak.KeycloakIdentityProvider : Found stored credentials so attempting to use them 2023-09-12 11:01:35.723 INFO [main ] curity.keycloak.KeycloakIdentityProvider : Keycloak proxy URI set to: http://keycloak:8080/auth 2023-09-12 11:01:35.723 INFO [main ] curity.keycloak.KeycloakIdentityProvider : Validating keycloak credentials 2023-09-12 11:01:36.746 INFO [main ] curity.keycloak.KeycloakIdentityProvider : Credentials are valid 2023-09-12 11:01:36.746 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.setup.SetupService 2023-09-12 11:01:36.746 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.gateway.GatewayService 2023-09-12 11:01:36.908 INFO [main ] e.manager.gateway.GatewayService.GATEWAY : Directly registered gateways found = 1 2023-09-12 11:01:36.930 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.container.timer.TimerService 2023-09-12 11:01:36.931 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.asset.AssetStorageService 2023-09-12 11:01:36.931 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.mqtt.MQTTBrokerService 2023-09-12 11:01:37.059 INFO [main ] org.apache.activemq.artemis.core.server : AMQ221000: live Message Broker is starting with configuration Broker Configuration (clustered=false,journalDirectory=data/journal,bindingsDirectory=data/bindings,largeMessagesDirectory=data/largemessages,pagingDirectory=data/paging) 2023-09-12 11:01:37.065 INFO [main ] org.apache.activemq.artemis.core.server : AMQ221045: libaio is not available, switching the configuration into NIO 2023-09-12 11:01:37.077 INFO [main ] org.apache.activemq.artemis.core.server : AMQ221057: Global Max Size is being adjusted to 1/2 of the JVM max size (-Xmx). being defined as 1073741824 2023-09-12 11:01:37.100 INFO [main ] org.apache.activemq.artemis.core.server : AMQ221043: Protocol module found: [artemis-server]. Adding protocol support for: CORE 2023-09-12 11:01:37.102 INFO [main ] org.apache.activemq.artemis.core.server : AMQ221043: Protocol module found: [artemis-mqtt-protocol]. Adding protocol support for: MQTT 2023-09-12 11:01:37.135 INFO [main ] org.apache.activemq.artemis.core.server : AMQ224092: Despite disabled persistence, page files will be persisted. 2023-09-12 11:01:37.288 INFO [main ] org.apache.activemq.artemis.core.server : AMQ221020: Started EPOLL Acceptor at 0.0.0.0:1883 for protocols [MQTT] 2023-09-12 11:01:37.288 INFO [main ] org.apache.activemq.artemis.core.server : AMQ221007: Server is now live 2023-09-12 11:01:37.289 INFO [main ] org.apache.activemq.artemis.core.server : AMQ221001: Apache ActiveMQ Artemis Message Broker version 2.29.0 [localhost, nodeID=fab32bef-514a-11ee-915a-0242ac140004] 2023-09-12 11:01:37.397 INFO [main ] mote.manager.mqtt.DefaultMQTTHandler.API : Adding publish consumer for topic '+/+/writeattributevalue/#': handler=DefaultMQTTHandler 2023-09-12 11:01:37.454 INFO [main ] qtt.UserAssetProvisioningMQTTHandler.API : Adding publish consumer for topic 'provisioning/+/request': handler=UserAssetProvisioningMQTTHandler 2023-09-12 11:01:37.461 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.provisioning.ProvisioningService 2023-09-12 11:01:37.464 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.datapoint.AssetDatapointService 2023-09-12 11:01:37.465 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.datapoint.AssetPredictedDatapointService 2023-09-12 11:01:37.465 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.rules.RulesetStorageService 2023-09-12 11:01:37.465 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.asset.AttributeLinkingService 2023-09-12 11:01:37.466 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.asset.AssetProcessingService 2023-09-12 11:01:37.466 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.system.HealthService 2023-09-12 11:01:37.466 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.simulator.SimulatorService 2023-09-12 11:01:37.466 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.map.MapService 2023-09-12 11:01:37.466 INFO [main ] org.openremote.manager.map.MapService : Starting map service with tile data: /deployment/map/mapdata.mbtiles 2023-09-12 11:01:37.564 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.notification.NotificationService 2023-09-12 11:01:37.564 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.webhook.WebhookService 2023-09-12 11:01:37.564 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.apps.ConsoleAppService 2023-09-12 11:01:37.564 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.notification.PushNotificationHandler 2023-09-12 11:01:37.565 WARNING [main ] ger.notification.PushNotificationHandler : FCM configuration invalid so cannot start 2023-09-12 11:01:37.565 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.notification.EmailNotificationHandler 2023-09-12 11:01:37.565 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.gateway.GatewayClientService 2023-09-12 11:01:37.745 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.asset.AssetModelService 2023-09-12 11:01:37.745 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.energy.EnergyOptimisationService 2023-09-12 11:01:37.782 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.energy.ForecastWindService 2023-09-12 11:01:37.783 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.energy.ForecastSolarService 2023-09-12 11:01:37.783 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.dashboard.DashboardStorageService 2023-09-12 11:01:37.783 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.security.AuthorisationService 2023-09-12 11:01:37.783 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.app.ConfigurationService 2023-09-12 11:01:37.783 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.asset.ForecastService 2023-09-12 11:01:37.786 WARNING [Scheduled task-4 ] manager.energy.EnergyOptimisationService : Optimisation '6lqrgbjxX272SmjvZIdJJa': Expected exactly one ElectricitySupplierAsset asset with a 'tariffImport' attribute but found: 0 2023-09-12 11:01:37.786 WARNING [Scheduled task-9 ] manager.energy.EnergyOptimisationService : Optimisation '5PFysG017Tu0cfiLn3294V': Expected exactly one ElectricitySupplierAsset asset with a 'tariffImport' attribute but found: 0 2023-09-12 11:01:37.790 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.agent.AgentService 2023-09-12 11:01:37.836 INFO [Scheduled task-8 ] gent.protocol.snmp.SNMPProtocol.PROTOCOL : No SNMP bind host provided for protocol: SNMP Client[snmp::162?protocol=udp&type=TRAP&snmpVersion=V2c] 2023-09-12 11:01:37.837 SEVERE [Scheduled task-4 ] rg.openremote.manager.agent.AgentService : Failed to start protocol instance for agent: ZWaveAgent{id='3XYdMERjY4uFhEmhSQSlgl', name='Z Wave agent', type ='ZWaveAgent', parentId='null', realm='master'} java.lang.RuntimeException: java.lang.IllegalStateException: Invalid serial port property at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:109) at org.openremote.manager.agent.AgentService.startAgent(AgentService.java:392) at org.openremote.manager.agent.AgentService.lambda$doAgentInit$15(AgentService.java:381) at io.micrometer.core.instrument.internal.TimedRunnable.run(TimedRunnable.java:49) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) Caused by: java.lang.IllegalStateException: Invalid serial port property at org.openremote.agent.protocol.zwave.ZWaveProtocol.lambda$doStart$0(ZWaveProtocol.java:82) at java.base/java.util.Optional.orElseThrow(Optional.java:403) at org.openremote.agent.protocol.zwave.ZWaveProtocol.doStart(ZWaveProtocol.java:82) at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:106) ... 9 more 2023-09-12 11:01:37.839 WARNING [Scheduled task-3 ] col.io.AbstractIOClientProtocol.PROTOCOL : Failed to create IO client for protocol: TCP Client[] java.lang.NullPointerException at java.base/java.util.Objects.requireNonNull(Objects.java:209) at org.openremote.model.util.TextUtil.requireNonNullAndNonEmpty(TextUtil.java:210) at org.openremote.model.util.TextUtil.requireNonNullAndNonEmpty(TextUtil.java:206) at org.openremote.agent.protocol.tcp.TCPIOClient.(TCPIOClient.java:46) at org.openremote.agent.protocol.tcp.AbstractTCPClientProtocol.doCreateIoClient(AbstractTCPClientProtocol.java:45) at org.openremote.agent.protocol.tcp.AbstractTCPClientProtocol.doCreateIoClient(AbstractTCPClientProtocol.java:32) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.createIoClient(AbstractIOClientProtocol.java:182) at org.openremote.agent.protocol.io.AbstractNettyIOClientProtocol.createIoClient(AbstractNettyIOClientProtocol.java:38) at org.openremote.agent.protocol.io.AbstractNettyIOClientProtocol.createIoClient(AbstractNettyIOClientProtocol.java:31) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.doStart(AbstractIOClientProtocol.java:154) at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:106) at org.openremote.manager.agent.AgentService.startAgent(AgentService.java:392) at org.openremote.manager.agent.AgentService.lambda$doAgentInit$15(AgentService.java:381) at io.micrometer.core.instrument.internal.TimedRunnable.run(TimedRunnable.java:49) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) 2023-09-12 11:01:37.844 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.event.ClientEventService 2023-09-12 11:01:37.844 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.rules.RulesService 2023-09-12 11:01:37.844 WARNING [Scheduled task-9 ] col.io.AbstractIOClientProtocol.PROTOCOL : Failed to create IO client for protocol: Websocket Client[] java.lang.IllegalArgumentException: Missing or invalid connectUri: WebsocketAgent{id='2enQk7asM8HxlwR0SJbYo3', name='New Asset', type ='WebsocketAgent', parentId='6FeSobaCnsyB8N26n88oOb', realm='master'} at org.openremote.agent.protocol.websocket.WebsocketAgentProtocol.lambda$doCreateIoClient$3(WebsocketAgentProtocol.java:139) at java.base/java.util.Optional.orElseThrow(Optional.java:403) at org.openremote.agent.protocol.websocket.WebsocketAgentProtocol.doCreateIoClient(WebsocketAgentProtocol.java:138) at org.openremote.agent.protocol.websocket.WebsocketAgentProtocol.doCreateIoClient(WebsocketAgentProtocol.java:72) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.createIoClient(AbstractIOClientProtocol.java:182) at org.openremote.agent.protocol.io.AbstractNettyIOClientProtocol.createIoClient(AbstractNettyIOClientProtocol.java:38) at org.openremote.agent.protocol.io.AbstractNettyIOClientProtocol.createIoClient(AbstractNettyIOClientProtocol.java:31) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.doStart(AbstractIOClientProtocol.java:154) at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:106) at org.openremote.manager.agent.AgentService.startAgent(AgentService.java:392) at org.openremote.manager.agent.AgentService.lambda$doAgentInit$15(AgentService.java:381) at io.micrometer.core.instrument.internal.TimedRunnable.run(TimedRunnable.java:49) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) 2023-09-12 11:01:37.870 SEVERE [Scheduled task-8 ] rg.openremote.manager.agent.AgentService : Failed to start protocol instance for agent: SNMPAgent{id='6FeSobaCnsyB8N26n88oOb', name='SNMP', type ='SNMPAgent', parentId='null', realm='master'} java.lang.RuntimeException: java.lang.IllegalArgumentException: No SNMP bind host provided for protocol: SNMP Client[snmp::162?protocol=udp&type=TRAP&snmpVersion=V2c] at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:109) at org.openremote.manager.agent.AgentService.startAgent(AgentService.java:392) at org.openremote.manager.agent.AgentService.lambda$doAgentInit$15(AgentService.java:381) at io.micrometer.core.instrument.internal.TimedRunnable.run(TimedRunnable.java:49) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) Caused by: java.lang.IllegalArgumentException: No SNMP bind host provided for protocol: SNMP Client[snmp::162?protocol=udp&type=TRAP&snmpVersion=V2c] at org.openremote.agent.protocol.snmp.SNMPProtocol.lambda$doStart$0(SNMPProtocol.java:57) at java.base/java.util.Optional.orElseThrow(Optional.java:403) at org.openremote.agent.protocol.snmp.SNMPProtocol.doStart(SNMPProtocol.java:54) at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:106) ... 9 more 2023-09-12 11:01:37.891 WARNING [Scheduled task-8 ] col.io.AbstractIOClientProtocol.PROTOCOL : Failed to create IO client for protocol: Serial[] java.lang.NullPointerException at java.base/java.util.Objects.requireNonNull(Objects.java:209) at org.openremote.model.util.TextUtil.requireNonNullAndNonEmpty(TextUtil.java:210) at org.openremote.model.util.TextUtil.requireNonNullAndNonEmpty(TextUtil.java:206) at org.openremote.agent.protocol.serial.SerialIOClient.(SerialIOClient.java:49) at org.openremote.agent.protocol.serial.AbstractSerialProtocol.doCreateIoClient(AbstractSerialProtocol.java:44) at org.openremote.agent.protocol.serial.AbstractSerialProtocol.doCreateIoClient(AbstractSerialProtocol.java:31) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.createIoClient(AbstractIOClientProtocol.java:182) at org.openremote.agent.protocol.io.AbstractNettyIOClientProtocol.createIoClient(AbstractNettyIOClientProtocol.java:38) at org.openremote.agent.protocol.io.AbstractNettyIOClientProtocol.createIoClient(AbstractNettyIOClientProtocol.java:31) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.doStart(AbstractIOClientProtocol.java:154) at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:106) at org.openremote.manager.agent.AgentService.startAgent(AgentService.java:392) at org.openremote.manager.agent.AgentService.lambda$doAgentInit$15(AgentService.java:381) at io.micrometer.core.instrument.internal.TimedRunnable.run(TimedRunnable.java:49) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) 2023-09-12 11:01:37.893 SEVERE [Scheduled task-9 ] rg.openremote.manager.agent.AgentService : Failed to start protocol instance for agent: MailAgent{id='30e43XK0HMzNC5b62zUBx4', name='Mail Agent', type ='MailAgent', parentId='null', realm='master'} java.lang.RuntimeException: java.util.NoSuchElementException: No value present at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:109) at org.openremote.manager.agent.AgentService.startAgent(AgentService.java:392) at org.openremote.manager.agent.AgentService.lambda$doAgentInit$15(AgentService.java:381) at io.micrometer.core.instrument.internal.TimedRunnable.run(TimedRunnable.java:49) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) Caused by: java.util.NoSuchElementException: No value present at java.base/java.util.Optional.orElseThrow(Optional.java:377) at org.openremote.agent.protocol.mail.AbstractMailProtocol.doStart(AbstractMailProtocol.java:64) at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:106) ... 9 more 2023-09-12 11:01:37.905 WARNING [Scheduled task-9 ] col.io.AbstractIOClientProtocol.PROTOCOL : Failed to create IO client for protocol: Websocket Client[] java.lang.IllegalArgumentException: Missing or invalid connectUri: WebsocketAgent{id='4QAT7ECac3EYgvVWWOcVEV', name='Websocket Agent', type ='WebsocketAgent', parentId='null', realm='master'} at org.openremote.agent.protocol.websocket.WebsocketAgentProtocol.lambda$doCreateIoClient$3(WebsocketAgentProtocol.java:139) at java.base/java.util.Optional.orElseThrow(Optional.java:403) at org.openremote.agent.protocol.websocket.WebsocketAgentProtocol.doCreateIoClient(WebsocketAgentProtocol.java:138) at org.openremote.agent.protocol.websocket.WebsocketAgentProtocol.doCreateIoClient(WebsocketAgentProtocol.java:72) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.createIoClient(AbstractIOClientProtocol.java:182) at org.openremote.agent.protocol.io.AbstractNettyIOClientProtocol.createIoClient(AbstractNettyIOClientProtocol.java:38) at org.openremote.agent.protocol.io.AbstractNettyIOClientProtocol.createIoClient(AbstractNettyIOClientProtocol.java:31) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.doStart(AbstractIOClientProtocol.java:154) at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:106) at org.openremote.manager.agent.AgentService.startAgent(AgentService.java:392) at org.openremote.manager.agent.AgentService.lambda$doAgentInit$15(AgentService.java:381) at io.micrometer.core.instrument.internal.TimedRunnable.run(TimedRunnable.java:49) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) 2023-09-12 11:01:37.953 WARNING [Scheduled task-3 ] col.io.AbstractIOClientProtocol.PROTOCOL : Failed to create IO client for protocol: Serial[] java.lang.NullPointerException at java.base/java.util.Objects.requireNonNull(Objects.java:209) at org.openremote.model.util.TextUtil.requireNonNullAndNonEmpty(TextUtil.java:210) at org.openremote.model.util.TextUtil.requireNonNullAndNonEmpty(TextUtil.java:206) at org.openremote.agent.protocol.serial.SerialIOClient.(SerialIOClient.java:49) at org.openremote.agent.protocol.serial.AbstractSerialProtocol.doCreateIoClient(AbstractSerialProtocol.java:44) at org.openremote.agent.protocol.serial.AbstractSerialProtocol.doCreateIoClient(AbstractSerialProtocol.java:31) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.createIoClient(AbstractIOClientProtocol.java:182) at org.openremote.agent.protocol.io.AbstractNettyIOClientProtocol.createIoClient(AbstractNettyIOClientProtocol.java:38) at org.openremote.agent.protocol.io.AbstractNettyIOClientProtocol.createIoClient(AbstractNettyIOClientProtocol.java:31) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.doStart(AbstractIOClientProtocol.java:154) at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:106) at org.openremote.manager.agent.AgentService.startAgent(AgentService.java:392) at org.openremote.manager.agent.AgentService.lambda$doAgentInit$15(AgentService.java:381) at io.micrometer.core.instrument.internal.TimedRunnable.run(TimedRunnable.java:49) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) 2023-09-12 11:01:37.975 WARNING [Scheduled task-4 ] col.io.AbstractIOClientProtocol.PROTOCOL : Failed to create IO client for protocol: MQTT Client[] java.lang.IllegalArgumentException: Server host must be at least one character long. at com.hivemq.client.internal.util.Checks.notEmpty(Checks.java:41) at com.hivemq.client.internal.mqtt.MqttClientTransportConfigImplBuilder.serverHost(MqttClientTransportConfigImplBuilder.java:86) at com.hivemq.client.internal.mqtt.MqttRxClientBuilderBase.serverHost(MqttRxClientBuilderBase.java:85) at com.hivemq.client.internal.mqtt.mqtt3.Mqtt3RxClientViewBuilder.serverHost(Mqtt3RxClientViewBuilder.java:38) at org.openremote.agent.protocol.mqtt.AbstractMQTT_IOClient.(AbstractMQTT_IOClient.java:139) at org.openremote.agent.protocol.mqtt.MQTT_IOClient.(MQTT_IOClient.java:34) at org.openremote.agent.protocol.mqtt.MQTTProtocol.doCreateIoClient(MQTTProtocol.java:112) at org.openremote.agent.protocol.mqtt.MQTTProtocol.doCreateIoClient(MQTTProtocol.java:41) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.createIoClient(AbstractIOClientProtocol.java:182) at org.openremote.agent.protocol.mqtt.MQTTProtocol.createIoClient(MQTTProtocol.java:75) at org.openremote.agent.protocol.mqtt.MQTTProtocol.createIoClient(MQTTProtocol.java:41) at org.openremote.agent.protocol.io.AbstractIOClientProtocol.doStart(AbstractIOClientProtocol.java:154) at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:106) at org.openremote.manager.agent.AgentService.startAgent(AgentService.java:392) at org.openremote.manager.agent.AgentService.lambda$doAgentInit$15(AgentService.java:381) at io.micrometer.core.instrument.internal.TimedRunnable.run(TimedRunnable.java:49) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) 2023-09-12 11:01:37.981 SEVERE [Scheduled task-4 ] rg.openremote.manager.agent.AgentService : Failed to link attribute 'AttributeRef{id='6lqrgbjxX272SmjvZIdJJa', name='energyMeter'}' to protocol: MQTT Client[] java.lang.RuntimeException: java.lang.NullPointerException: Cannot invoke "org.openremote.agent.protocol.mqtt.MQTT_IOClient.addMessageConsumer(String, java.util.function.Consumer)" because "this.client" is null at org.openremote.agent.protocol.AbstractProtocol.linkAttribute(AbstractProtocol.java:150) at org.openremote.manager.agent.AgentService.lambda$linkAttributes$22(AgentService.java:472) at java.base/java.util.ArrayList.forEach(ArrayList.java:1511) at org.openremote.manager.agent.AgentService.linkAttributes(AgentService.java:467) at org.openremote.manager.agent.AgentService.lambda$startAgent$18(AgentService.java:417) at java.base/java.util.HashMap.forEach(HashMap.java:1421) at org.openremote.manager.agent.AgentService.lambda$startAgent$19(AgentService.java:417) at java.base/java.util.ArrayList.forEach(ArrayList.java:1511) at org.openremote.manager.agent.AgentService.startAgent(AgentService.java:410) at org.openremote.manager.agent.AgentService.lambda$doAgentInit$15(AgentService.java:381) at io.micrometer.core.instrument.internal.TimedRunnable.run(TimedRunnable.java:49) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) Caused by: java.lang.NullPointerException: Cannot invoke "org.openremote.agent.protocol.mqtt.MQTT_IOClient.addMessageConsumer(String, java.util.function.Consumer)" because "this.client" is null at org.openremote.agent.protocol.mqtt.MQTTProtocol.lambda$doLinkAttribute$1(MQTTProtocol.java:57) at java.base/java.util.Optional.ifPresent(Optional.java:178) at org.openremote.agent.protocol.mqtt.MQTTProtocol.doLinkAttribute(MQTTProtocol.java:53) at org.openremote.agent.protocol.mqtt.MQTTProtocol.doLinkAttribute(MQTTProtocol.java:41) at org.openremote.agent.protocol.AbstractProtocol.linkAttribute(AbstractProtocol.java:147) ... 16 more 2023-09-12 11:01:37.996 INFO [Scheduled task-8 ] .agent.protocol.knx.KNXProtocol.PROTOCOL : No KNX gateway IP address provided for protocol: KNX[knx://null] 2023-09-12 11:01:37.999 SEVERE [Scheduled task-8 ] rg.openremote.manager.agent.AgentService : Failed to start protocol instance for agent: KNXAgent{id='2tGtnqJmjRy3CMjJb4yrvh', name='KNX', type ='KNXAgent', parentId='null', realm='master'} java.lang.RuntimeException: java.lang.IllegalArgumentException: No KNX gateway IP address provided for protocol: KNX[knx://null] at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:109) at org.openremote.manager.agent.AgentService.startAgent(AgentService.java:392) at org.openremote.manager.agent.AgentService.lambda$doAgentInit$15(AgentService.java:381) at io.micrometer.core.instrument.internal.TimedRunnable.run(TimedRunnable.java:49) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) Caused by: java.lang.IllegalArgumentException: No KNX gateway IP address provided for protocol: KNX[knx://null] at org.openremote.agent.protocol.knx.KNXProtocol.lambda$doStart$0(KNXProtocol.java:73) at java.base/java.util.Optional.orElseThrow(Optional.java:403) at org.openremote.agent.protocol.knx.KNXProtocol.doStart(KNXProtocol.java:70) at org.openremote.agent.protocol.AbstractProtocol.start(AbstractProtocol.java:106) ... 9 more 2023-09-12 11:01:38.002 INFO [Scheduled task-8 ] otocol.io.AbstractNettyIOClient.PROTOCOL : Establishing connection: udp://0.0.0.0:0 (bindPort: 0) 2023-09-12 11:01:38.024 INFO [nioEventLoopGroup-2-1 ] otocol.io.AbstractNettyIOClient.PROTOCOL : Connected: udp://0.0.0.0:0 (bindPort: 0) 2023-09-12 11:01:38.375 INFO [main ] org.openremote.manager.rules.RulesEngine : Compiling ruleset deployment: RealmRuleset{id='2884', version='6', name='WebHook Rule', lang='JSON', createdOn='2023-09-01 07:04:51.891', lastModified='2023-09-01 07:04:51.883', enabled='true', meta='{}', realm='master', accessPublicRead='false'} 2023-09-12 11:01:38.420 INFO [main ] org.openremote.manager.rules.RulesEngine : Compiling ruleset deployment: RealmRuleset{id='5352', version='6', name='Temperature', lang='FLOW', createdOn='2023-09-02 11:44:19.223', lastModified='2023-09-02 11:44:19.207', enabled='true', meta='{}', realm='master', accessPublicRead='false'} 2023-09-12 11:01:38.429 INFO [main ] org.openremote.manager.rules.RulesEngine : Compiling flow rule: Temperature - 0 2023-09-12 11:01:38.480 WARNING [Scheduled task-3 ] gent.protocol.http.HTTPProtocol.PROTOCOL : HTTP Client [https://api.openweathermap.org/data/2.5]: Exception thrown whilst processing polling response [CamelContext is stopped]: https://api.openweathermap.org/data/2.5/weather 2023-09-12 11:01:38.481 WARNING [Scheduled task-4 ] gent.protocol.http.HTTPProtocol.PROTOCOL : HTTP Client [https://api.openweathermap.org/data/2.5]: Exception thrown whilst processing polling response [CamelContext is stopped]: https://api.openweathermap.org/data/2.5/weather 2023-09-12 11:01:38.503 INFO [main ] org.openremote.manager.rules.RulesEngine : Starting: RulesEngine{id='RulesEngineId{scope=RealmRuleset, realm='master', assetId='null'}', running='false', deployments='[RulesetDeployment{id=2884, name='WebHook Rule', version=6, status=READY}, RulesetDeployment{id=5352, name='Temperature', version=6, status=READY}]'} 2023-09-12 11:01:38.511 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.container.message.MessageBrokerService 2023-09-12 11:01:38.512 INFO [main ] e.container.message.MessageBrokerService : Starting Camel message broker 2023-09-12 11:01:38.545 WARNING [Scheduled task-9 ] gent.protocol.http.HTTPProtocol.PROTOCOL : HTTP Client [https://api.openweathermap.org/data/2.5]: Exception thrown whilst processing polling response [CamelContext is stopped]: https://api.openweathermap.org/data/2.5/weather 2023-09-12 11:01:38.698 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Apache Camel 3.20.6 (camel-1) is starting 2023-09-12 11:01:38.784 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Using ThreadPoolFactory: org.openremote.container.message.MessageBrokerService$1@7192075e 2023-09-12 11:01:39.011 INFO [main ] emote.container.security.IdentityService : Securing web deployment: /websocket/events 2023-09-12 11:01:39.067 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Routes startup (started:37) 2023-09-12 11:01:39.067 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-GatewayAsset (seda://PersistenceTopic) 2023-09-12 11:01:39.068 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-Asset (seda://PersistenceTopic) 2023-09-12 11:01:39.068 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ClientInbound-Query (seda://ClientInboundQueue) 2023-09-12 11:01:39.068 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-UserAndAssetLink (seda://PersistenceTopic) 2023-09-12 11:01:39.069 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ClientOutbound-DefaultMQTTHandler (seda://ClientOutboundQueue) 2023-09-12 11:01:39.069 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-MQTTConnectedAttributes (seda://PersistenceTopic) 2023-09-12 11:01:39.070 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started AttributeEventProcessor (seda://AttributeEventQueue) 2023-09-12 11:01:39.071 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ClientInbound-ReadSimulatorState (seda://ClientInboundQueue) 2023-09-12 11:01:39.071 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started NotificationQueue (seda://NotificationQueue) 2023-09-12 11:01:39.071 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-PushNotificationConsoleAsset (seda://PersistenceTopic) 2023-09-12 11:01:39.071 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-GatewayConnection (seda://PersistenceTopic) 2023-09-12 11:01:39.071 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ClientInbound-Websocket (ws://0.0.0.0/websocket/events) 2023-09-12 11:01:39.072 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ClientInbound-EventProcessor (seda://ClientInboundQueue) 2023-09-12 11:01:39.072 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ClientOutbound-Splitter (seda://ClientPublishQueue) 2023-09-12 11:01:39.072 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ClientOutbound-Websocket (seda://ClientOutboundQueue) 2023-09-12 11:01:39.072 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-Ruleset (seda://PersistenceTopic) 2023-09-12 11:01:39.072 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-RulesRealm (seda://PersistenceTopic) 2023-09-12 11:01:39.073 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-RulesAsset (seda://PersistenceTopic) 2023-09-12 11:01:39.073 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-GeofenceAdapterConsoleAsset (seda://PersistenceTopic) 2023-09-12 11:01:39.073 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-ProvisioningConfig (seda://PersistenceTopic) 2023-09-12 11:01:39.073 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-EnergyOptimisation (seda://PersistenceTopic) 2023-09-12 11:01:39.073 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-ForecastConfiguration (seda://PersistenceTopic) 2023-09-12 11:01:39.073 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started Persistence-Agent (seda://PersistenceTopic) 2023-09-12 11:01:39.074 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolOutbound (seda://SensorQueue) 2023-09-12 11:01:39.074 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-SNMP Client6FeSobaCnsyB8N26n88oOb (seda://ActuatorTopic) 2023-09-12 11:01:39.074 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-Z-Wave3XYdMERjY4uFhEmhSQSlgl (seda://ActuatorTopic) 2023-09-12 11:01:39.074 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-TCP Client3kBJwmJ6HQCxNmbZUOHhKN (seda://ActuatorTopic) 2023-09-12 11:01:39.075 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-Websocket Client2enQk7asM8HxlwR0SJbYo3 (seda://ActuatorTopic) 2023-09-12 11:01:39.075 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-MQTT Client70xTmP3WzsKkk95m0J7K3R (seda://ActuatorTopic) 2023-09-12 11:01:39.075 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-Serial7iNmnPt4zCJt64QMFFG4YJ (seda://ActuatorTopic) 2023-09-12 11:01:39.075 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-Mail Client30e43XK0HMzNC5b62zUBx4 (seda://ActuatorTopic) 2023-09-12 11:01:39.077 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-HTTP Client5wBWcTvAo9lHGqRx0xs3eW (seda://ActuatorTopic) 2023-09-12 11:01:39.077 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-Websocket Client4QAT7ECac3EYgvVWWOcVEV (seda://ActuatorTopic) 2023-09-12 11:01:39.078 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-HTTP Client5BZ9bHmr7YXjnWH9JUlPGf (seda://ActuatorTopic) 2023-09-12 11:01:39.079 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-UDP Client7Gh73o28Fu9kNYd8y498TF (seda://ActuatorTopic) 2023-09-12 11:01:39.079 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-Serial3Qq5ZQoISL8KHv6KbfDsgo (seda://ActuatorTopic) 2023-09-12 11:01:39.079 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Started ProtocolInbound-KNX2tGtnqJmjRy3CMjJb4yrvh (seda://ActuatorTopic) 2023-09-12 11:01:39.079 INFO [main ] e.camel.impl.engine.AbstractCamelContext : Apache Camel 3.20.6 (camel-1) started in 620ms (build:54ms init:185ms start:381ms) 2023-09-12 11:01:39.081 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.web.ManagerWebService 2023-09-12 11:01:39.130 INFO [main ] org.jboss.threads : JBoss Threads version 3.5.0.Final 2023-09-12 11:01:39.177 INFO [main ] org.openremote.container.web.WebService : Webserver ready on http://0.0.0.0:8080 2023-09-12 11:01:39.177 INFO [main ] org.openremote.container.Container : Starting service: org.openremote.manager.rules.geofence.ORConsoleGeofenceAssetAdapter 2023-09-12 11:01:39.182 INFO [main ] org.openremote.container.Container : >>> Runtime container startup complete 2023-09-12 11:01:45.027 INFO [Thread-0 (ActiveMQ-serve..0fd)] nager.mqtt.ActiveMQORSecurityManager.API : Un-supported request sub: topic=$SYS/#, connection=/172.20.0.5:51868, clientID=mqtt-explorer-f9684031, subject=service-account-mqttuser 2023-09-12 11:02:21.244 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 75ms: attribute=Asset ID=4wAbWtWaLV8T2cNOga16cF, Asset name=Weather 2, Attribute{name='temperature', value='305.2', timestamp='1694509341164'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=74ms AttributeLinkingService{}=0ms 2023-09-12 11:03:40.623 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 60ms: attribute=Asset ID=4wAbWtWaLV8T2cNOga16cF, Asset name=Weather 2, Attribute{name='temperature', value='305.2', timestamp='1694509420560'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=59ms AttributeLinkingService{}=0ms 2023-09-12 11:04:17.300 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 64ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='32.05', timestamp='1694509457233'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=63ms AttributeLinkingService{}=0ms 2023-09-12 11:04:29.343 INFO [Scheduled task-9 ] org.apache.http.impl.execchain.RetryExec : I/O exception (org.apache.http.NoHttpResponseException) caught when processing request to {s}->https://api.openweathermap.org:443: The target server failed to respond 2023-09-12 11:04:29.344 INFO [Scheduled task-9 ] org.apache.http.impl.execchain.RetryExec : Retrying request to {s}->https://api.openweathermap.org:443 2023-09-12 11:04:29.369 INFO [Scheduled task-9 ] org.apache.http.impl.execchain.RetryExec : I/O exception (org.apache.http.NoHttpResponseException) caught when processing request to {s}->https://api.openweathermap.org:443: The target server failed to respond 2023-09-12 11:04:29.369 INFO [Scheduled task-9 ] org.apache.http.impl.execchain.RetryExec : Retrying request to {s}->https://api.openweathermap.org:443 2023-09-12 11:04:30.621 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 92ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='32.05', timestamp='1694509470526'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=91ms AttributeLinkingService{}=0ms 2023-09-12 11:04:59.862 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 78ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694509499781'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=77ms AttributeLinkingService{}=0ms 2023-09-12 11:05:24.726 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 184ms: attribute=Asset ID=4wAbWtWaLV8T2cNOga16cF, Asset name=Weather 2, Attribute{name='temperature', value='305.2', timestamp='1694509524540'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=183ms AttributeLinkingService{}=0ms 2023-09-12 11:05:25.278 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 58ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='32.05', timestamp='1694509525217'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=57ms AttributeLinkingService{}=0ms 2023-09-12 11:06:00.743 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 67ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694509560674'} , consumer=null, timings=GatewayService{active=true}=1ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=66ms AttributeLinkingService{}=0ms 2023-09-12 11:06:19.610 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 97ms: attribute=Asset ID=4wAbWtWaLV8T2cNOga16cF, Asset name=Weather 2, Attribute{name='temperature', value='305.2', timestamp='1694509579511'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=1ms AssetDatapointService{}=95ms AttributeLinkingService{}=0ms 2023-09-12 11:06:32.410 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 56ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='32.05', timestamp='1694509592351'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=56ms AttributeLinkingService{}=0ms 2023-09-12 11:06:56.768 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 88ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='32.05', timestamp='1694509616677'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=87ms AttributeLinkingService{}=0ms 2023-09-12 11:07:20.276 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 73ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694509640200'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=72ms AttributeLinkingService{}=0ms 2023-09-12 11:07:58.020 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 65ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='32.05', timestamp='1694509677952'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=1ms AssetDatapointService{}=64ms AttributeLinkingService{}=0ms 2023-09-12 11:08:59.146 INFO [Scheduled task-8 ] org.apache.http.impl.execchain.RetryExec : I/O exception (org.apache.http.NoHttpResponseException) caught when processing request to {s}->https://api.openweathermap.org:443: The target server failed to respond 2023-09-12 11:08:59.146 INFO [Scheduled task-8 ] org.apache.http.impl.execchain.RetryExec : Retrying request to {s}->https://api.openweathermap.org:443 2023-09-12 11:09:22.473 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 52ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694509762419'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=1ms AssetDatapointService{}=50ms AttributeLinkingService{}=0ms 2023-09-12 11:11:06.130 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 56ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694509866071'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=55ms AttributeLinkingService{}=0ms 2023-09-12 11:11:21.695 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 64ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='33.05', timestamp='1694509881628'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=63ms AttributeLinkingService{}=0ms 2023-09-12 11:11:30.528 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 62ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694509890464'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=61ms AttributeLinkingService{}=0ms 2023-09-12 11:11:46.152 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 94ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='33.05', timestamp='1694509906056'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=93ms AttributeLinkingService{}=0ms 2023-09-12 11:12:26.659 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 183ms: attribute=Asset ID=4wAbWtWaLV8T2cNOga16cF, Asset name=Weather 2, Attribute{name='temperature', value='306.2', timestamp='1694509946474'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=182ms AttributeLinkingService{}=0ms 2023-09-12 11:13:02.763 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 130ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694509982630'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=129ms AttributeLinkingService{}=0ms 2023-09-12 11:13:09.142 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 68ms: attribute=Asset ID=4wAbWtWaLV8T2cNOga16cF, Asset name=Weather 2, Attribute{name='temperature', value='306.2', timestamp='1694509989072'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=68ms AttributeLinkingService{}=0ms 2023-09-12 11:13:14.853 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 58ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694509994793'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=57ms AttributeLinkingService{}=0ms 2023-09-12 11:13:33.160 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 73ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694510013085'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=72ms AttributeLinkingService{}=0ms 2023-09-12 11:14:10.149 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 74ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694510050073'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=1ms AssetDatapointService{}=72ms AttributeLinkingService{}=0ms 2023-09-12 11:14:30.605 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 91ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='33.05', timestamp='1694510070512'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=91ms AttributeLinkingService{}=0ms 2023-09-12 11:14:48.971 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 73ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='33.05', timestamp='1694510088896'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=72ms AttributeLinkingService{}=0ms 2023-09-12 11:15:01.782 WARNING [Scheduled task-8 ] manager.energy.EnergyOptimisationService : Optimisation '5PFysG017Tu0cfiLn3294V': Expected exactly one ElectricitySupplierAsset asset with a 'tariffImport' attribute but found: 0 2023-09-12 11:15:03.779 WARNING [Scheduled task-4 ] manager.energy.EnergyOptimisationService : Optimisation '6lqrgbjxX272SmjvZIdJJa': Expected exactly one ElectricitySupplierAsset asset with a 'tariffImport' attribute but found: 0 2023-09-12 11:15:11.512 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 103ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694510111407'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=1ms AssetDatapointService{}=102ms AttributeLinkingService{}=0ms 2023-09-12 11:16:06.265 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 71ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694510166192'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=71ms AttributeLinkingService{}=0ms 2023-09-12 11:17:15.603 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 86ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='33.05', timestamp='1694510235515'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=85ms AttributeLinkingService{}=0ms 2023-09-12 11:18:08.572 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 140ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='humidity', value='62', timestamp='1694510288430'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=139ms AttributeLinkingService{}=0ms 2023-09-12 11:18:10.645 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 58ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='33.05', timestamp='1694510290585'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=58ms AttributeLinkingService{}=0ms 2023-09-12 11:19:00.347 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 91ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='33.05', timestamp='1694510340253'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=90ms AttributeLinkingService{}=0ms 2023-09-12 11:19:12.812 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 399ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='33.05', timestamp='1694510352411'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=398ms AttributeLinkingService{}=0ms 2023-09-12 11:19:16.920 INFO [Scheduled task-8 ] org.apache.http.impl.execchain.RetryExec : I/O exception (java.net.SocketException) caught when processing request to {s}->https://api.openweathermap.org:443: Connection reset 2023-09-12 11:19:16.921 INFO [Scheduled task-8 ] org.apache.http.impl.execchain.RetryExec : Retrying request to {s}->https://api.openweathermap.org:443 2023-09-12 11:19:18.571 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 88ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='33.05', timestamp='1694510358481'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=88ms AttributeLinkingService{}=0ms 2023-09-12 11:19:41.661 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 62ms: attribute=Asset ID=4wAbWtWaLV8T2cNOga16cF, Asset name=Weather 2, Attribute{name='temperature', value='306.2', timestamp='1694510381597'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=62ms AttributeLinkingService{}=0ms 2023-09-12 11:19:48.943 INFO [Pool-AttributeEventQueue-25 ] ote.manager.asset.AssetProcessingService : <<< Attribute event processing took a long time 56ms: attribute=Asset ID=3CkPy3tzsPj8BYFGEjVQAT, Asset name=Weather Asset, Attribute{name='temperature', value='33.05', timestamp='1694510388885'} , consumer=null, timings=GatewayService{active=true}=0ms AgentService{}=0ms RulesService{}=0ms AssetDatapointService{}=56ms AttributeLinkingService{}=0ms