1.3.0 snapshot losing sensor names....
-
@skywatch Looks like the problem on your environment only. Can you post environment details like gateway type, MySensors version, MyController debug log etc?
Are you facing this is the issue only for a particular sensor or for all the sensors? -
Typical of my luck!
MYS version = 2.2.0
Gateway = Pro mini, serial connection to pi.So far it has happened on 2 nodes. Three sensors have done this.
See lod below.....
2018-05-09 11:09:28,844 INFO [main] [org.mycontroller.standalone.AppProperties:513] Created directory location: [/home/mycontroller/bin/tmp] 2018-05-09 11:09:28,864 INFO [main] [org.mycontroller.standalone.AppProperties:513] Created directory location: [/home/mycontroller/conf/resources/scripts] 2018-05-09 11:09:28,866 INFO [main] [org.mycontroller.standalone.AppProperties:513] Created directory location: [/home/mycontroller/conf/resources/scripts/conditions] 2018-05-09 11:09:28,867 INFO [main] [org.mycontroller.standalone.AppProperties:513] Created directory location: [/home/mycontroller/conf/resources/scripts/operations] 2018-05-09 11:09:28,871 INFO [main] [org.mycontroller.standalone.AppProperties:513] Created directory location: [/home/mycontroller/www/_configurations] 2018-05-09 11:09:28,873 INFO [main] [org.mycontroller.standalone.AppProperties:513] Created directory location: [/home/mycontroller/conf/persistent_stores] 2018-05-09 11:09:29,011 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:117] Checking migration... 2018-05-09 11:09:32,881 INFO [main] [org.mycontroller.standalone.db.migration.V1_01__Initial_Configuration:398] Migration completed successfully. 2018-05-09 11:09:33,162 INFO [main] [org.mycontroller.standalone.db.migration.V1_01_01__SNAPSHOT:63] Migration completed successfully. 2018-05-09 11:09:33,312 INFO [main] [org.mycontroller.standalone.db.migration.V1_01_02__SNAPSHOT:127] Migration completed successfully. 2018-05-09 11:09:33,445 INFO [main] [org.mycontroller.standalone.db.migration.V1_01_03__SNAPSHOT:48] Migration completed successfully. 2018-05-09 11:09:33,570 INFO [main] [org.mycontroller.standalone.db.migration.V1_01_04__SNAPSHOT:107] Migration completed successfully. 2018-05-09 11:09:33,669 INFO [main] [org.mycontroller.standalone.db.migration.V1_01_05__0_0_3_alpha1:49] Migration completed successfully. 2018-05-09 11:09:33,960 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_04__2016_Apr_25:58] Migration completed successfully. 2018-05-09 11:09:33,975 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_01__2016_Mar_24:340] Migration completed successfully. 2018-05-09 11:09:34,192 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_02__2016_Apr_11:66] Migration completed successfully. 2018-05-09 11:09:39,130 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_03__2016_Apr_14:85] Migration completed successfully. 2018-05-09 11:09:39,315 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_04__2016_Apr_25:58] Migration completed successfully. 2018-05-09 11:09:39,372 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_05__2016_Apr_27:68] Migration completed successfully. 2018-05-09 11:09:39,419 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_06__2016_May_10:49] Migration completed successfully. 2018-05-09 11:09:39,476 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_07__2016_May_19:59] Migration completed successfully. 2018-05-09 11:09:39,555 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_08__2016_Jul_01:108] Migration completed successfully. 2018-05-09 11:09:39,602 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_09__2016_Jul_16:93] Migration completed successfully. 2018-05-09 11:09:39,650 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_10__2016_Jul_29:53] Migration completed successfully. 2018-05-09 11:09:39,703 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_01__2016_Aug_03:66] Migration completed successfully. 2018-05-09 11:09:39,755 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_02__2016_Aug_06:63] Migration completed successfully. 2018-05-09 11:09:39,813 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_03__2016_Sep_20:95] Migration completed successfully. 2018-05-09 11:09:39,857 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_04__2016_Oct_19:61] Migration completed successfully. 2018-05-09 11:09:39,911 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_05__2016_Nov_18:77] Migration completed successfully. 2018-05-09 11:09:40,058 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_06__2017_Mar_03:66] Migration completed successfully. 2018-05-09 11:09:40,183 INFO [main] [org.mycontroller.standalone.db.migration.V1_04_01__2017_Oct_25:56] Migration completed successfully. 2018-05-09 11:09:40,678 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:162] Number of migrations done:23 2018-05-09 11:09:40,681 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:168] Application information: [Version:1.3.0-SNAPSHOT, Database(type:H2 database embedded, version:1.4.194 (2017-03-10), schema version:1.04.01 - 2017 Oct 25), Built on:2018-05-04T09:09:24+0530, Git commit:d111efac6d9ee68aaf862ff8de5c4b677534e16f:development] 2018-05-09 11:09:41,003 INFO [main] [org.mycontroller.standalone.AppProperties:513] Created directory location: [/home/mycontroller/conf/persistent_stores/mc] 2018-05-09 11:09:41,769 WARN [main] [io.moquette.persistence.mapdb.MapDBPersistentStore:78] Using fresh MapDB store file. Path=../conf/persistent_stores/moquette/moquette_store.mapdb 2018-05-09 11:09:42,089 WARN [main] [io.moquette.server.Server:186] Using default SSL context creator 2018-05-09 11:09:42,647 WARN [main] [io.netty.bootstrap.ServerBootstrap:146] Unknown channel option 'TCP_NODELAY' for channel '[id: 0x415760f6]' 2018-05-09 11:09:42,739 WARN [main] [io.netty.bootstrap.ServerBootstrap:146] Unknown channel option 'TCP_NODELAY' for channel '[id: 0x1c073915]' 2018-05-09 11:09:42,749 INFO [main] [org.mycontroller.standalone.mqttbroker.MoquetteMqttBroker:54] MQTT Broker started successfully. MqttBrokerSettings(enabled=true, sslEnabled=false, bindAddress=0.0.0.0, mqttPort=1883, mqttsPort=8883, websocketPort=7080, allowAnonymous=false, enabledOnBackend=true, sslKeystoreFile=null) 2018-05-09 11:09:45,246 INFO [main] [org.mycontroller.standalone.StartApp:215] TJWS server started successfully, HTTPS Enabled?:true, HTTP(S) Port: [8443] 2018-05-09 11:09:45,247 INFO [main] [org.mycontroller.standalone.StartApp:113] MyController.org server started in [16446] ms 2018-05-09 11:09:55,702 ERROR [nioEventLoopGroup-3-1] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_30C6u 2018-05-09 11:09:55,748 ERROR [nioEventLoopGroup-3-2] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_30C6u 2018-05-09 11:10:10,823 ERROR [nioEventLoopGroup-3-3] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_NGXH4 2018-05-09 11:10:10,867 ERROR [nioEventLoopGroup-3-4] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_NGXH4 2018-05-09 11:10:25,921 ERROR [nioEventLoopGroup-3-5] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_HQtBX 2018-05-09 11:10:25,946 ERROR [nioEventLoopGroup-3-6] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_HQtBX 2018-05-09 11:10:40,993 ERROR [nioEventLoopGroup-3-7] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_mkY4O 2018-05-09 11:10:41,015 ERROR [nioEventLoopGroup-3-8] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_mkY4O 2018-05-09 11:10:56,064 ERROR [nioEventLoopGroup-3-1] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_cWabF 2018-05-09 11:10:56,094 ERROR [nioEventLoopGroup-3-2] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_cWabF 2018-05-09 11:11:11,149 ERROR [nioEventLoopGroup-3-3] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_X18It 2018-05-09 11:11:11,179 ERROR [nioEventLoopGroup-3-4] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_X18It 2018-05-09 11:11:26,257 ERROR [nioEventLoopGroup-3-5] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_Ohgyp 2018-05-09 11:11:26,277 ERROR [nioEventLoopGroup-3-6] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_Ohgyp 2018-05-09 11:11:41,320 ERROR [nioEventLoopGroup-3-7] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_W2KIV 2018-05-09 11:11:41,340 ERROR [nioEventLoopGroup-3-8] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_W2KIV 2018-05-09 11:11:56,386 ERROR [nioEventLoopGroup-3-1] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_VbSA4 2018-05-09 11:11:56,404 ERROR [nioEventLoopGroup-3-2] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_VbSA4 2018-05-09 11:12:11,459 ERROR [nioEventLoopGroup-3-3] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_vkHme 2018-05-09 11:12:11,480 ERROR [nioEventLoopGroup-3-4] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_vkHme 2018-05-09 11:12:26,526 ERROR [nioEventLoopGroup-3-5] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_z9FjA 2018-05-09 11:12:26,548 ERROR [nioEventLoopGroup-3-6] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_z9FjA 2018-05-09 11:12:41,590 ERROR [nioEventLoopGroup-3-7] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_w4FkZ 2018-05-09 11:12:41,610 ERROR [nioEventLoopGroup-3-8] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_w4FkZ 2018-05-09 11:12:56,657 ERROR [nioEventLoopGroup-3-1] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_hxU0a 2018-05-09 11:12:56,688 ERROR [nioEventLoopGroup-3-2] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_hxU0a 2018-05-09 11:13:11,739 ERROR [nioEventLoopGroup-3-3] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_T0HyA 2018-05-09 11:13:11,757 ERROR [nioEventLoopGroup-3-4] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_T0HyA 2018-05-09 11:13:26,805 ERROR [nioEventLoopGroup-3-5] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_Z1AWX 2018-05-09 11:13:26,834 ERROR [nioEventLoopGroup-3-6] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_Z1AWX 2018-05-09 11:13:41,874 ERROR [nioEventLoopGroup-3-7] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_kGVxD 2018-05-09 11:13:41,892 ERROR [nioEventLoopGroup-3-8] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_kGVxD 2018-05-09 11:13:56,945 ERROR [nioEventLoopGroup-3-1] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_cBn0h 2018-05-09 11:13:56,969 ERROR [nioEventLoopGroup-3-2] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_cBn0h 2018-05-09 11:14:12,010 ERROR [nioEventLoopGroup-3-3] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_dxD7w 2018-05-09 11:14:12,029 ERROR [nioEventLoopGroup-3-4] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_dxD7w 2018-05-09 11:14:24,512 INFO [Acme.Utils.ThreadPool(2)-PooledThread: Acme.Serve.Serve$ServeConnection@dabe16] [org.mycontroller.standalone.api.BackupApi:219] Restore triggered. 2018-05-09 11:14:27,074 ERROR [nioEventLoopGroup-3-5] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_POKzx 2018-05-09 11:14:27,092 ERROR [nioEventLoopGroup-3-6] [io.moquette.spi.impl.ProtocolProcessor:337] Client didn't supply any credentials and MQTT anonymous mode is disabled. CId=mc-rpi-client_POKzx 2018-05-09 11:14:27,514 INFO [Thread-16] [org.mycontroller.standalone.backup.Restore:69] About to restore a backup, BackupFile(name=MYCback_mc_backup_h2db_embedded-2018_04_29-23_51_48.zip, canonicalPath=/home/mycbackup/MYCback_mc_backup_h2db_embedded-2018_04_29-23_51_48.zip, timestamp=1525042313000, size=1064681) [Wed May 09 11:14:27 BST 2018] Accept: java.net.SocketException: Socket closed 2018-05-09 11:14:29,849 INFO [Thread-16] [org.mycontroller.standalone.mqttbroker.MoquetteMqttBroker:72] MQTT Broker has been stopped successfully 2018-05-09 11:15:05,116 INFO [Thread-16] [org.mycontroller.standalone.db.DataBaseUtils:289] Database restore completed. Database url:jdbc:h2:file:../conf/mycontroller;MVCC=TRUE, Restored file name:/home/mycontroller/bin/tmp/MYCback_mc_backup_h2db_embedded-2018_04_29-23_51_48/database_backup.zip 2018-05-09 11:15:05,133 INFO [Thread-16] [org.mycontroller.standalone.backup.Restore:153] Restore completed successfully. Start 'MyController.org' server manually 2018-05-09 11:15:05,140 WARN [MyController.org Shutdown-Hook] [org.knowm.sundial.SundialJobScheduler:159] Scheduler has not yet been created!!! Call "createScheduler" first. 2018-05-09 11:15:05,141 INFO [MyController.org Shutdown-Hook] [org.mycontroller.standalone.AppShutdownHook:36] Bye, Have a nice day! See you soon 2018-05-09 11:59:02,674 INFO [main] [org.mycontroller.standalone.AppProperties:513] Created directory location: [/home/mycontroller/conf/resources/scripts] 2018-05-09 11:59:02,692 INFO [main] [org.mycontroller.standalone.AppProperties:513] Created directory location: [/home/mycontroller/conf/resources/scripts/conditions] 2018-05-09 11:59:02,693 INFO [main] [org.mycontroller.standalone.AppProperties:513] Created directory location: [/home/mycontroller/conf/resources/scripts/operations] 2018-05-09 11:59:02,820 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:117] Checking migration... 2018-05-09 11:59:11,273 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:168] Application information: [Version:1.3.0-SNAPSHOT, Database(type:H2 database embedded, version:1.4.194 (2017-03-10), schema version:1.04.01 - 2017 Oct 25), Built on:2018-05-04T09:09:24+0530, Git commit:d111efac6d9ee68aaf862ff8de5c4b677534e16f:development] 2018-05-09 11:59:15,192 INFO [main] [org.mycontroller.standalone.StartApp:215] TJWS server started successfully, HTTPS Enabled?:true, HTTP(S) Port: [8443] 2018-05-09 11:59:15,193 INFO [main] [org.mycontroller.standalone.StartApp:113] MyController.org server started in [12557] ms [Wed May 09 15:47:51 BST 2018] IO error: java.net.SocketException: Connection reset in processing a request from /192.168.1.6:8443 / sun.security.ssl.SSLSocketImpl [Wed May 09 15:47:52 BST 2018] IO error: java.net.SocketException: Connection reset in processing a request from /192.168.1.6:8443 / sun.security.ssl.SSLSocketImpl [Wed May 09 15:47:54 BST 2018] IO error: java.net.SocketException: Connection reset in processing a request from /192.168.1.6:8443 / sun.security.ssl.SSLSocketImpl [Wed May 09 15:48:06 BST 2018] IO error: java.net.SocketException: Connection reset in processing a request from /192.168.1.6:8443 / sun.security.ssl.SSLSocketImpl 2018-05-09 21:10:01,612 ERROR [mc-th-pool-0] [org.mycontroller.standalone.db.dao.NodeDaoImpl:66] unable to get Node org.h2.jdbc.JdbcSQLException: General error: "java.lang.NullPointerException" [50000-194] at org.h2.message.DbException.getJdbcSQLException(DbException.java:345) at org.h2.message.DbException.get(DbException.java:168) at org.h2.message.DbException.convert(DbException.java:295) at org.h2.message.DbException.toSQLException(DbException.java:268) at org.h2.message.TraceObject.logAndConvert(TraceObject.java:352) at org.h2.jdbc.JdbcResultSet.findColumn(JdbcResultSet.java:188) at com.j256.ormlite.jdbc.JdbcDatabaseResults.findColumn(JdbcDatabaseResults.java:103) at com.j256.ormlite.field.FieldType.resultToJava(FieldType.java:826) at com.j256.ormlite.stmt.mapped.BaseMappedQuery.mapRow(BaseMappedQuery.java:61) at com.j256.ormlite.stmt.StatementExecutor.queryForFirst(StatementExecutor.java:109) at com.j256.ormlite.dao.BaseDaoImpl.queryForFirst(BaseDaoImpl.java:239) at com.j256.ormlite.stmt.QueryBuilder.queryForFirst(QueryBuilder.java:380) at org.mycontroller.standalone.db.dao.NodeDaoImpl.get(NodeDaoImpl.java:64) at org.mycontroller.standalone.provider.ExecuterAbstract.getNode(ExecuterAbstract.java:468) at org.mycontroller.standalone.provider.ExecuterAbstract.isNodeRegistered(ExecuterAbstract.java:605) at org.mycontroller.standalone.provider.ExecuterAbstract.execute(ExecuterAbstract.java:98) at org.mycontroller.standalone.provider.EngineAbstract.auditQueue(EngineAbstract.java:256) at org.mycontroller.standalone.provider.EngineAbstract.run(EngineAbstract.java:131) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Caused by: java.lang.NullPointerException: null at org.h2.util.StringUtils.toUpperEnglish(StringUtils.java:106) at org.h2.jdbc.JdbcResultSet.getColumnIndex(JdbcResultSet.java:3129) at org.h2.jdbc.JdbcResultSet.findColumn(JdbcResultSet.java:186) ... 15 common frames omitted 2018-05-09 21:10:01,645 ERROR [mc-th-pool-0] [org.mycontroller.standalone.db.dao.BaseAbstractDaoImpl:247] unable to add new item:[Node(id=null, eui=6, gatewayTable=GatewayTable(id=1, enabled=null, name=null, networkType=null, timestamp=null, state=UNAVAILABLE, statusMessage=null, statusSince=null, type=null, properties=null), name=null, version=null, type=null, libVersion=null, batteryLevel=null, eraseConfig=null, firmware=null, state=UP, lastSeen=1525896601618, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED, smartSleepEnabled=false)] java.sql.SQLException: Unable to run insert stmt on object Node(id=null, eui=6, gatewayTable=GatewayTable(id=1, enabled=null, name=null, networkType=null, timestamp=null, state=UNAVAILABLE, statusMessage=null, statusSince=null, type=null, properties=null), name=null, version=null, type=null, libVersion=null, batteryLevel=null, eraseConfig=null, firmware=null, state=UP, lastSeen=1525896601618, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED, smartSleepEnabled=false): INSERT INTO `node` (`id` ,`eui` ,`gatewayId` ,`name` ,`version` ,`type` ,`libVersion` ,`batteryLevel` ,`eraseConfig` ,`firmwareId` ,`state` ,`lastSeen` ,`rssi` ,`properties` ,`parentNodeEui` ,`registrationState` ,`smartSleepEnabled` ) VALUES (?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) at com.j256.ormlite.misc.SqlExceptionUtil.create(SqlExceptionUtil.java:25) at com.j256.ormlite.stmt.mapped.MappedCreate.insert(MappedCreate.java:137) at com.j256.ormlite.stmt.StatementExecutor.create(StatementExecutor.java:458) at com.j256.ormlite.dao.BaseDaoImpl.create(BaseDaoImpl.java:328) at org.mycontroller.standalone.db.dao.BaseAbstractDaoImpl.create(BaseAbstractDaoImpl.java:244) at org.mycontroller.standalone.provider.ExecuterAbstract.getNode(ExecuterAbstract.java:481) at org.mycontroller.standalone.provider.ExecuterAbstract.isNodeRegistered(ExecuterAbstract.java:605) at org.mycontroller.standalone.provider.ExecuterAbstract.execute(ExecuterAbstract.java:98) at org.mycontroller.standalone.provider.EngineAbstract.auditQueue(EngineAbstract.java:256) at org.mycontroller.standalone.provider.EngineAbstract.run(EngineAbstract.java:131) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Caused by: org.h2.jdbc.JdbcSQLException: Unique index or primary key violation: "CONSTRAINT_24_INDEX_2 ON PUBLIC.NODE(EUI, GATEWAYID) VALUES ('6', 1, 2)"; SQL statement: INSERT INTO `node` (`id` ,`eui` ,`gatewayId` ,`name` ,`version` ,`type` ,`libVersion` ,`batteryLevel` ,`eraseConfig` ,`firmwareId` ,`state` ,`lastSeen` ,`rssi` ,`properties` ,`parentNodeEui` ,`registrationState` ,`smartSleepEnabled` ) VALUES (?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) [23505-194] at org.h2.message.DbException.getJdbcSQLException(DbException.java:345) at org.h2.message.DbException.get(DbException.java:179) at org.h2.message.DbException.get(DbException.java:155) at org.h2.index.BaseIndex.getDuplicateKeyException(BaseIndex.java:103) at org.h2.mvstore.db.MVSecondaryIndex.checkUnique(MVSecondaryIndex.java:231) at org.h2.mvstore.db.MVSecondaryIndex.add(MVSecondaryIndex.java:190) at org.h2.mvstore.db.MVTable.addRow(MVTable.java:707) at org.h2.command.dml.Insert.insertRows(Insert.java:156) at org.h2.command.dml.Insert.update(Insert.java:114) at org.h2.command.CommandContainer.update(CommandContainer.java:101) at org.h2.command.Command.executeUpdate(Command.java:258) at org.h2.jdbc.JdbcPreparedStatement.executeUpdateInternal(JdbcPreparedStatement.java:160) at org.h2.jdbc.JdbcPreparedStatement.executeUpdate(JdbcPreparedStatement.java:146) at com.j256.ormlite.jdbc.JdbcDatabaseConnection.insert(JdbcDatabaseConnection.java:183) at com.j256.ormlite.stmt.mapped.MappedCreate.insert(MappedCreate.java:91) ... 11 common frames omitted 2018-05-09 22:51:48,166 ERROR [mc-th-pool-0] [org.mycontroller.standalone.db.dao.NodeDaoImpl:66] unable to get Node org.h2.jdbc.JdbcSQLException: General error: "java.lang.NullPointerException" [50000-194] at org.h2.message.DbException.getJdbcSQLException(DbException.java:345) at org.h2.message.DbException.get(DbException.java:168) at org.h2.message.DbException.convert(DbException.java:295) at org.h2.message.DbException.toSQLException(DbException.java:268) at org.h2.message.TraceObject.logAndConvert(TraceObject.java:352) at org.h2.jdbc.JdbcResultSet.findColumn(JdbcResultSet.java:188) at com.j256.ormlite.jdbc.JdbcDatabaseResults.findColumn(JdbcDatabaseResults.java:103) at com.j256.ormlite.field.FieldType.resultToJava(FieldType.java:826) at com.j256.ormlite.stmt.mapped.BaseMappedQuery.mapRow(BaseMappedQuery.java:61) at com.j256.ormlite.stmt.StatementExecutor.queryForFirst(StatementExecutor.java:109) at com.j256.ormlite.dao.BaseDaoImpl.queryForFirst(BaseDaoImpl.java:239) at com.j256.ormlite.stmt.QueryBuilder.queryForFirst(QueryBuilder.java:380) at org.mycontroller.standalone.db.dao.NodeDaoImpl.get(NodeDaoImpl.java:64) at org.mycontroller.standalone.provider.ExecuterAbstract.getNode(ExecuterAbstract.java:468) at org.mycontroller.standalone.provider.ExecuterAbstract.isNodeRegistered(ExecuterAbstract.java:605) at org.mycontroller.standalone.provider.ExecuterAbstract.execute(ExecuterAbstract.java:98) at org.mycontroller.standalone.provider.EngineAbstract.auditQueue(EngineAbstract.java:256) at org.mycontroller.standalone.provider.EngineAbstract.run(EngineAbstract.java:131) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Caused by: java.lang.NullPointerException: null at org.h2.util.StringUtils.toUpperEnglish(StringUtils.java:106) at org.h2.jdbc.JdbcResultSet.getColumnIndex(JdbcResultSet.java:3129) at org.h2.jdbc.JdbcResultSet.findColumn(JdbcResultSet.java:186) ... 15 common frames omitted 2018-05-09 22:51:48,174 ERROR [mc-th-pool-0] [org.mycontroller.standalone.db.dao.BaseAbstractDaoImpl:247] unable to add new item:[Node(id=null, eui=6, gatewayTable=GatewayTable(id=1, enabled=null, name=null, networkType=null, timestamp=null, state=UNAVAILABLE, statusMessage=null, statusSince=null, type=null, properties=null), name=null, version=null, type=null, libVersion=null, batteryLevel=null, eraseConfig=null, firmware=null, state=UP, lastSeen=1525902708169, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED, smartSleepEnabled=false)] java.sql.SQLException: Unable to run insert stmt on object Node(id=null, eui=6, gatewayTable=GatewayTable(id=1, enabled=null, name=null, networkType=null, timestamp=null, state=UNAVAILABLE, statusMessage=null, statusSince=null, type=null, properties=null), name=null, version=null, type=null, libVersion=null, batteryLevel=null, eraseConfig=null, firmware=null, state=UP, lastSeen=1525902708169, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED, smartSleepEnabled=false): INSERT INTO `node` (`id` ,`eui` ,`gatewayId` ,`name` ,`version` ,`type` ,`libVersion` ,`batteryLevel` ,`eraseConfig` ,`firmwareId` ,`state` ,`lastSeen` ,`rssi` ,`properties` ,`parentNodeEui` ,`registrationState` ,`smartSleepEnabled` ) VALUES (?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) at com.j256.ormlite.misc.SqlExceptionUtil.create(SqlExceptionUtil.java:25) at com.j256.ormlite.stmt.mapped.MappedCreate.insert(MappedCreate.java:137) at com.j256.ormlite.stmt.StatementExecutor.create(StatementExecutor.java:458) at com.j256.ormlite.dao.BaseDaoImpl.create(BaseDaoImpl.java:328) at org.mycontroller.standalone.db.dao.BaseAbstractDaoImpl.create(BaseAbstractDaoImpl.java:244) at org.mycontroller.standalone.provider.ExecuterAbstract.getNode(ExecuterAbstract.java:481) at org.mycontroller.standalone.provider.ExecuterAbstract.isNodeRegistered(ExecuterAbstract.java:605) at org.mycontroller.standalone.provider.ExecuterAbstract.execute(ExecuterAbstract.java:98) at org.mycontroller.standalone.provider.EngineAbstract.auditQueue(EngineAbstract.java:256) at org.mycontroller.standalone.provider.EngineAbstract.run(EngineAbstract.java:131) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Caused by: org.h2.jdbc.JdbcSQLException: Unique index or primary key violation: "CONSTRAINT_24_INDEX_2 ON PUBLIC.NODE(EUI, GATEWAYID) VALUES ('6', 1, 2)"; SQL statement: INSERT INTO `node` (`id` ,`eui` ,`gatewayId` ,`name` ,`version` ,`type` ,`libVersion` ,`batteryLevel` ,`eraseConfig` ,`firmwareId` ,`state` ,`lastSeen` ,`rssi` ,`properties` ,`parentNodeEui` ,`registrationState` ,`smartSleepEnabled` ) VALUES (?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) [23505-194] at org.h2.message.DbException.getJdbcSQLException(DbException.java:345) at org.h2.message.DbException.get(DbException.java:179) at org.h2.message.DbException.get(DbException.java:155) at org.h2.index.BaseIndex.getDuplicateKeyException(BaseIndex.java:103) at org.h2.mvstore.db.MVSecondaryIndex.checkUnique(MVSecondaryIndex.java:231) at org.h2.mvstore.db.MVSecondaryIndex.add(MVSecondaryIndex.java:190) at org.h2.mvstore.db.MVTable.addRow(MVTable.java:707) at org.h2.command.dml.Insert.insertRows(Insert.java:156) at org.h2.command.dml.Insert.update(Insert.java:114) at org.h2.command.CommandContainer.update(CommandContainer.java:101) at org.h2.command.Command.executeUpdate(Command.java:258) at org.h2.jdbc.JdbcPreparedStatement.executeUpdateInternal(JdbcPreparedStatement.java:160) at org.h2.jdbc.JdbcPreparedStatement.executeUpdate(JdbcPreparedStatement.java:146) at com.j256.ormlite.jdbc.JdbcDatabaseConnection.insert(JdbcDatabaseConnection.java:183) at com.j256.ormlite.stmt.mapped.MappedCreate.insert(MappedCreate.java:91) ... 11 common frames omitted [Fri May 11 08:38:53 BST 2018] IO error: java.net.SocketException: Connection reset in processing a request from /192.168.1.6:8443 / sun.security.ssl.SSLSocketImpl [Fri May 11 08:38:55 BST 2018] IO error: java.net.SocketException: Connection reset in processing a request from /192.168.1.6:8443 / sun.security.ssl.SSLSocketImpl [Fri May 11 08:38:56 BST 2018] IO error: java.net.SocketException: Connection reset in processing a request from /192.168.1.6:8443 / sun.security.ssl.SSLSocketImpl [Fri May 11 08:40:54 BST 2018] IO error: java.net.SocketException: Connection reset in processing a request from /192.168.1.6:8443 / sun.security.ssl.SSLSocketImpl
Thanks!
-
-
@jkandasa
Been very busy recently but a major power outage stopped all nodes data after power restored, so I must look into that and see if it's all trash now or if I can start again. I think at lease one node will need a new bootloader flash, as for the others, who knows?
Never a dull moment around here! - I will of course let you know when I can update on the situation. Internal sensors on the pi are still running fine. -
@skywatch ok, Thanks for the update!
-
OK I am setting up again with new RF channel and mysensors 2.3.0 version.
So far gw is working a node registers but nothing works after that. I'll keep trying!
-
Here is what I get - I think that this shows that the node finds the parent, but then it doesn't respond to any commands from the myc pi. In myc it shows node as down, yet it is 'talking' to the gw so maybe the problem is between gw and controller? But how to test this?
__ __ ____ | \/ |_ _/ ___| ___ _ __ ___ ___ _ __ ___ | |\/| | | | \___ \ / _ \ `_ \/ __|/ _ \| `__/ __| | | | | |_| |___| | __/ | | \__ \ _ | | \__ \ |_| |_|\__, |____/ \___|_| |_|___/\___/|_| |___/ |___/ 2.3.0 16 MCO:BGN:INIT NODE,CP=RNNNA---,VER=2.3.0 25 TSM:INIT 26 TSF:WUR:MS=0 33 TSM:INIT:TSP OK 35 TSM:INIT:STATID=140 37 TSF:SID:OK,ID=140 39 TSM:FPAR 1158 TSF:MSG:SEND,140-140-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 3166 !TSM:FPAR:NO REPLY 3168 TSM:FPAR 3208 TSF:MSG:SEND,140-140-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 3837 TSF:MSG:READ,0-0-140,s=255,c=3,t=8,pt=1,l=1,sg=0:0 3843 TSF:MSG:FPAR OK,ID=0,D=1 5215 TSM:FPAR:OK 5216 TSM:ID 5217 TSM:ID:OK 5219 TSM:UPL 5225 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=OK:1 5258 TSF:MSG:READ,0-0-140,s=255,c=3,t=25,pt=1,l=1,sg=0:1 5263 TSF:MSG:PONG RECV,HP=1 5266 TSM:UPL:OK 5268 TSM:READY:ID=140,PAR=0,DIS=1 5284 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0100 5310 TSF:MSG:READ,0-0-140,s=255,c=3,t=15,pt=6,l=2,sg=0:0100 5324 TSF:MSG:SEND,140-140-0-0,s=255,c=0,t=17,pt=0,l=5,sg=0,ft=0,st=OK:2.3.0 5344 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=6,pt=1,l=1,sg=0,ft=0,st=OK:0 5390 TSF:MSG:READ,0-0-140,s=255,c=3,t=6,pt=0,l=1,sg=0:0 5404 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=11,pt=0,l=11,sg=0,ft=0,st=OK:MYS-4-Relay 5424 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=12,pt=0,l=3,sg=0,ft=0,st=OK:0.2 5473 TSF:MSG:READ,0-0-140,s=255,c=3,t=12,pt=0,l=3,sg=0:0.2 5683 TSF:MSG:SEND,140-140-0-0,s=0,c=0,t=3,pt=0,l=0,sg=0,ft=0,st=OK: 5702 TSF:MSG:SEND,140-140-0-0,s=1,c=0,t=3,pt=0,l=0,sg=0,ft=0,st=OK: 5723 TSF:MSG:SEND,140-140-0-0,s=2,c=0,t=3,pt=0,l=0,sg=0,ft=0,st=OK: 5742 TSF:MSG:SEND,140-140-0-0,s=3,c=0,t=3,pt=0,l=0,sg=0,ft=0,st=OK: 5748 MCO:REG:REQ 5763 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=26,pt=1,l=1,sg=0,ft=0,st=OK:2 5791 TSF:MSG:READ,0-0-140,s=255,c=3,t=27,pt=1,l=1,sg=0:1 5796 MCO:PIM:NODE REG=1 5798 MCO:BGN:STP 5803 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=22,pt=5,l=4,sg=0,ft=0,st=OK:2 5837 TSF:MSG:READ,0-0-140,s=0,c=0,t=3,pt=0,l=0,sg=0: 5867 TSF:MSG:READ,0-0-140,s=255,c=3,t=2,pt=0,l=0,sg=0: 6320 TSF:MSG:SEND,140-140-0-0,s=0,c=1,t=2,pt=1,l=1,sg=0,ft=0,st=OK:1 6346 TSF:MSG:READ,0-0-140,s=0,c=1,t=2,pt=0,l=1,sg=0:1 6837 TSF:MSG:SEND,140-140-0-0,s=1,c=1,t=2,pt=1,l=1,sg=0,ft=0,st=OK:1 6864 TSF:MSG:READ,0-0-140,s=1,c=1,t=2,pt=0,l=1,sg=0:1 7355 TSF:MSG:SEND,140-140-0-0,s=2,c=1,t=2,pt=1,l=1,sg=0,ft=0,st=OK:1 7382 TSF:MSG:READ,0-0-140,s=2,c=1,t=2,pt=0,l=1,sg=0:1 7872 TSF:MSG:SEND,140-140-0-0,s=3,c=1,t=2,pt=1,l=1,sg=0,ft=0,st=OK:1 7900 TSF:MSG:READ,0-0-140,s=3,c=1,t=2,pt=0,l=1,sg=0:1 8129 MCO:BGN:INIT OK,TSP=1 526097 TSF:MSG:READ,0-0-255,s=255,c=3,t=20,pt=0,l=0,sg=0: 526102 TSF:MSG:BC 526909 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=21,pt=1,l=1,sg=0,ft=0,st=OK:0 526940 TSF:MSG:READ,0-0-140,s=255,c=3,t=21,pt=0,l=1,sg=0:0
-
I tried adding ack and that seems to work (see below) yet still the nide does not show up in <resources><nodes> - What am I doing wrong, it all used to work ,,,Argh!
16 MCO:BGN:INIT NODE,CP=RNNNA---,VER=2.3.0 25 TSM:INIT 26 TSF:WUR:MS=0 33 TSM:INIT:TSP OK 35 TSM:INIT:STATID=140 37 TSF:SID:OK,ID=140 39 TSM:FPAR 537 TSF:MSG:SEND,140-140-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 1216 TSF:MSG:READ,0-0-140,s=255,c=3,t=8,pt=1,l=1,sg=0:0 1221 TSF:MSG:FPAR OK,ID=0,D=1 2544 TSM:FPAR:OK 2545 TSM:ID 2546 TSM:ID:OK 2548 TSM:UPL 2552 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=OK:1 2586 TSF:MSG:READ,0-0-140,s=255,c=3,t=25,pt=1,l=1,sg=0:1 2591 TSF:MSG:PONG RECV,HP=1 2594 TSM:UPL:OK 2595 TSM:READY:ID=140,PAR=0,DIS=1 2612 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0100 2636 TSF:MSG:READ,0-0-140,s=255,c=3,t=15,pt=6,l=2,sg=0:0100 2652 TSF:MSG:SEND,140-140-0-0,s=255,c=0,t=17,pt=0,l=5,sg=0,ft=0,st=OK:2.3.0 2672 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=6,pt=1,l=1,sg=0,ft=0,st=OK:0 4681 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=11,pt=0,l=11,sg=0,ft=0,st=OK:MYS-4-Relay 4701 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=12,pt=0,l=3,sg=0,ft=0,st=OK:0.2 4960 TSF:MSG:SEND,140-140-0-0,s=0,c=0,t=3,pt=0,l=11,sg=0,ft=0,st=OK:LoungePower 4980 TSF:MSG:SEND,140-140-0-0,s=1,c=0,t=3,pt=0,l=11,sg=0,ft=0,st=OK:LoungePower 5035 TSF:MSG:SEND,140-140-0-0,s=2,c=0,t=3,pt=0,l=11,sg=0,ft=0,st=OK:LoungePower 5044 TSF:MSG:SEND,140-140-0-0,s=3,c=0,t=3,pt=0,l=11,sg=0,ft=0,st=OK:LoungePower 5051 MCO:REG:REQ 5060 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=26,pt=1,l=1,sg=0,ft=0,st=OK:2 5098 TSF:MSG:READ,0-0-140,s=1,c=0,t=3,pt=0,l=11,sg=0:LoungePower 5104 TSF:MSG:ACK 5164 TSF:MSG:READ,0-0-140,s=2,c=0,t=3,pt=0,l=11,sg=0:LoungePower 5169 TSF:MSG:ACK 5228 TSF:MSG:READ,0-0-140,s=3,c=0,t=3,pt=0,l=11,sg=0:LoungePower 5234 TSF:MSG:ACK 5281 TSF:MSG:READ,0-0-140,s=255,c=3,t=27,pt=1,l=1,sg=0:1 5286 MCO:PIM:NODE REG=1 5288 MCO:BGN:STP 5318 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=22,pt=5,l=4,sg=0,ft=0,st=OK:3 5835 TSF:MSG:SEND,140-140-0-0,s=0,c=1,t=2,pt=1,l=1,sg=0,ft=0,st=OK:1 6352 TSF:MSG:SEND,140-140-0-0,s=1,c=1,t=2,pt=1,l=1,sg=0,ft=0,st=OK:1 6379 TSF:MSG:READ,0-0-140,s=1,c=1,t=2,pt=0,l=1,sg=0:1 6870 TSF:MSG:SEND,140-140-0-0,s=2,c=1,t=2,pt=1,l=1,sg=0,ft=0,st=OK:1 6896 TSF:MSG:READ,0-0-140,s=2,c=1,t=2,pt=0,l=1,sg=0:1 7387 TSF:MSG:SEND,140-140-0-0,s=3,c=1,t=2,pt=1,l=1,sg=0,ft=0,st=OK:1 7414 TSF:MSG:READ,0-0-140,s=3,c=1,t=2,pt=0,l=1,sg=0:1 7643 MCO:BGN:INIT OK,TSP=1 470725 TSF:MSG:READ,0-0-255,s=255,c=3,t=20,pt=0,l=0,sg=0: 470730 TSF:MSG:BC 471488 TSF:MSG:SEND,140-140-0-0,s=255,c=3,t=21,pt=1,l=1,sg=0,ft=0,st=OK:0 471517 TSF:MSG:READ,0-0-140,s=255,c=3,t=21,pt=0,l=1,sg=0:0```
-
@skywatch Do you see any error on the mycontroller log?
-
Can you post the top of your sketch (the mysensors stuff)...
I had the same issue some time ago where the sensor names that I had set in mycontroller got overwritten, most probably when I upgraded to a new release..I fixed this by hard coding the sensor description names into the sensors presentation loop:
See: https://www.mysensors.org/download/sensor_api_20#presentation
(copy of the most important part below)Presentation
The sensors should present themselves before they start reporting sensor data to the controller. Do these calls in your presentation()-functionvoid present(uint8_t childSensorId, uint8_t sensorType, const char *description, bool ack);
childSensorId - The unique child id you want to choose for the sensor connected to this Arduino. Range 0-254.
sensorType - The sensor type you want to create.
description An optional textual description of the attached sensor.
ack - Set this to true if you want destination node to send ack back to this node. Default is not to request any ack.Description is the one for investigation..