Unable to get MySensors sensors to Work with MyController running on RPi
-
Hello,
I am running MyController on a Raspberry Pi and I have built a simple MySensors temperature and humidity sensor using the DHT-11 and an Arduino Pro Mini.
I have the serial gateway setup and I believe it is working, as it does "communicate" with my node but strange things are happening and the Sensor data is not updating.
For example, when I add the MySensors Temperature sensor node by pressing "Reset", it automatically registers 2 Nodes in MyController, one with ID 0 and another with ID 1.
The node with ID 0 "connects" itself to the gateway, as you can see here in the topology:
topologyThe node with ID 1, the TemperatureandHumidity node with the actual sensors registered to it, does not "connect" to the gateway.
Here is what the node page looks like: node page
And the sensor page: sensors
Here's my serial output from the sensor:
0 MCO:BGN:INIT NODE,CP=RNNNA--,VER=2.1.1 3 TSM:INIT 4 TSF:WUR:MS=0 11 TSM:INIT:TSP OK 13 TSF:SID:OK,ID=1 14 TSM:FPAR 51 TSF:MSG:SEND,1-1-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 940 TSF:MSG:READ,0-0-1,s=255,c=3,t=8,pt=1,l=1,sg=0:0 945 TSF:MSG:FPAR OK,ID=0,D=1 2058 TSM:FPAR:OK 2059 TSM:ID 2060 TSM:ID:OK 2062 TSM:UPL 2065 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=OK:1 2074 TSF:MSG:READ,0-0-1,s=255,c=3,t=25,pt=1,l=1,sg=0:1 2079 TSF:MSG:PONG RECV,HP=1 2081 TSM:UPL:OK 2083 TSM:READY:ID=1,PAR=0,DIS=1 2088 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0100 2095 TSF:MSG:READ,0-0-1,s=255,c=3,t=15,pt=6,l=2,sg=0:0100 2102 TSF:MSG:SEND,1-1-0-0,s=255,c=0,t=17,pt=0,l=5,sg=0,ft=0,st=OK:2.1.1 2110 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=6,pt=1,l=1,sg=0,ft=0,st=OK:0 2122 TSF:MSG:READ,0-0-1,s=255,c=3,t=6,pt=0,l=1,sg=0:M 2130 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=11,pt=0,l=22,sg=0,ft=0,st=OK:TemperatureAndHumidity 2141 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=12,pt=0,l=3,sg=0,ft=0,st=OK:1.1 2148 TSF:MSG:SEND,1-1-0-0,s=0,c=0,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 2156 TSF:MSG:SEND,1-1-0-0,s=1,c=0,t=6,pt=0,l=0,sg=0,ft=0,st=OK: 2161 MCO:REG:REQ 2165 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=26,pt=1,l=1,sg=0,ft=0,st=OK:2 2171 TSF:MSG:READ,0-0-1,s=255,c=3,t=27,pt=1,l=1,sg=0:1 2177 MCO:PIM:NODE REG=1 2179 MCO:BGN:STP 2182 MCO:SLP:MS=1000,SMS=0,I1=255,M1=255,I2=255,M2=255 2187 MCO:SLP:TPD 2188 MCO:SLP:WUP=-1 2190 MCO:BGN:INIT OK,TSP=1 2216 TSF:MSG:SEND,1-1-0-0,s=1,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=OK:21.0 T: 21.00 2225 TSF:MSG:SEND,1-1-0-0,s=0,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=OK:47.0 H: 47.00 2232 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2237 MCO:SLP:TPD 2239 MCO:SLP:WUP=-1 2263 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2268 MCO:SLP:TPD 2269 MCO:SLP:WUP=-1 2293 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2298 MCO:SLP:TPD 2299 MCO:SLP:WUP=-1 2323 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2328 MCO:SLP:TPD 2330 MCO:SLP:WUP=-1 2354 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2359 MCO:SLP:TPD 2361 MCO:SLP:WUP=-1 2384 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2390 MCO:SLP:TPD 2392 MCO:SLP:WUP=-1 2415 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2420 MCO:SLP:TPD 2422 MCO:SLP:WUP=-1 2446 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2451 MCO:SLP:TPD 2452 MCO:SLP:WUP=-1 2477 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2482 MCO:SLP:TPD 2483 MCO:SLP:WUP=-1 2506 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2511 MCO:SLP:TPD 2513 MCO:SLP:WUP=-1 2537 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2542 MCO:SLP:TPD 2544 MCO:SLP:WUP=-1 2570 TSF:MSG:SEND,1-1-0-0,s=1,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=OK:21.0 T: 21.00 2579 TSF:MSG:SEND,1-1-0-0,s=0,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=OK:47.0 H: 47.00 2585 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2590 MCO:SLP:TPD 2592 MCO:SLP:WUP=-1 2618 TSF:MSG:SEND,1-1-0-0,s=0,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=OK:48.0 H: 48.00 2625 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2630 MCO:SLP:TPD 2632 MCO:SLP:WUP=-1 2656 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2661 MCO:SLP:TPD 2662 MCO:SLP:WUP=-1 2685 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2691 MCO:SLP:TPD 2693 MCO:SLP:WUP=-1 2716 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2721 MCO:SLP:TPD 2723 MCO:SLP:WUP=-1 2749 TSF:MSG:SEND,1-1-0-0,s=0,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=OK:47.0 H: 47.00 2756 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2761 MCO:SLP:TPD 2763 MCO:SLP:WUP=-1 2787 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2792 MCO:SLP:TPD 2793 MCO:SLP:WUP=-1 2818 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2823 MCO:SLP:TPD 2824 MCO:SLP:WUP=-1 2847 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2852 MCO:SLP:TPD 2854 MCO:SLP:WUP=-1 2878 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2883 MCO:SLP:TPD 2885 MCO:SLP:WUP=-1 2909 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2914 MCO:SLP:TPD 2916 MCO:SLP:WUP=-1 2941 TSF:MSG:SEND,1-1-0-0,s=1,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=OK:21.0 T: 21.00 2951 TSF:MSG:SEND,1-1-0-0,s=0,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=OK:46.0 H: 46.00 2957 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2962 MCO:SLP:TPD 2964 MCO:SLP:WUP=-1 2988 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 2993 MCO:SLP:TPD 2995 MCO:SLP:WUP=-1 3018 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 3023 MCO:SLP:TPD 3024 MCO:SLP:WUP=-1 3049 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 3054 MCO:SLP:TPD 3055 MCO:SLP:WUP=-1 3080 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 3085 MCO:SLP:TPD 3086 MCO:SLP:WUP=-1 3109 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 3115 MCO:SLP:TPD 3117 MCO:SLP:WUP=-1 3140 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 3145 MCO:SLP:TPD 3147 MCO:SLP:WUP=-1 3171 MCO:SLP:MS=25000,SMS=0,I1=255,M1=255,I2=255,M2=255 3176 MCO:SLP:TPD
The resource logs in MyController are empty, but I may have the log level set incorrectly.
Any guidance or help anyone can provide me is greatly appreciated.
-
Here is the sole entry from my Resources log:
It appears the gateway is automatically assigning Node ID 0 to the sensor.
-
Bumping.
-
@jkandasa Sorry to bug you, but do you have any ideas?
-
@iamtheghost Do you see any issue on
logs/mycontroller.log
? Seems you are using 0.0.3-Alpha2 version. Can you try with SNAPSHOT version? -
@jkandasa Here is the full text from my log file after switching to the SNAPSHOT version you provided:
2017-08-14 19:02:47,471 INFO [main] [org.mycontroller.standalone.AppProperties:497] Created directory location: [/home/pi/mycontroller/bin/tmp] 2017-08-14 19:02:47,503 INFO [main] [org.mycontroller.standalone.AppProperties:497] Created directory location: [/home/pi/mycontroller/conf/resources/scripts] 2017-08-14 19:02:47,506 INFO [main] [org.mycontroller.standalone.AppProperties:497] Created directory location: [/home/pi/mycontroller/conf/resources/scripts/conditions] 2017-08-14 19:02:47,509 INFO [main] [org.mycontroller.standalone.AppProperties:497] Created directory location: [/home/pi/mycontroller/conf/resources/scripts/operations] 2017-08-14 19:02:47,517 INFO [main] [org.mycontroller.standalone.AppProperties:497] Created directory location: [/home/pi/mycontroller/www/_configurations] 2017-08-14 19:02:47,519 INFO [main] [org.mycontroller.standalone.AppProperties:497] Created directory location: [/home/pi/mycontroller/conf/persistent_stores] 2017-08-14 19:02:47,719 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:116] Checking migration... 2017-08-14 19:02:53,337 INFO [main] [org.mycontroller.standalone.db.migration.V1_01__Initial_Configuration:398] Migration completed successfully. 2017-08-14 19:02:53,751 INFO [main] [org.mycontroller.standalone.db.migration.V1_01_01__SNAPSHOT:63] Migration completed successfully. 2017-08-14 19:02:53,968 INFO [main] [org.mycontroller.standalone.db.migration.V1_01_02__SNAPSHOT:127] Migration completed successfully. 2017-08-14 19:02:54,148 INFO [main] [org.mycontroller.standalone.db.migration.V1_01_03__SNAPSHOT:48] Migration completed successfully. 2017-08-14 19:02:54,359 INFO [main] [org.mycontroller.standalone.db.migration.V1_01_04__SNAPSHOT:107] Migration completed successfully. 2017-08-14 19:02:54,498 INFO [main] [org.mycontroller.standalone.db.migration.V1_01_05__0_0_3_alpha1:49] Migration completed successfully. 2017-08-14 19:02:54,951 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_04__2016_Apr_25:58] Migration completed successfully. 2017-08-14 19:02:54,972 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_01__2016_Mar_24:340] Migration completed successfully. 2017-08-14 19:02:55,234 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_02__2016_Apr_11:66] Migration completed successfully. 2017-08-14 19:03:01,934 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_03__2016_Apr_14:85] Migration completed successfully. 2017-08-14 19:03:02,234 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_04__2016_Apr_25:58] Migration completed successfully. 2017-08-14 19:03:02,340 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_05__2016_Apr_27:68] Migration completed successfully. 2017-08-14 19:03:02,442 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_06__2016_May_10:49] Migration completed successfully. 2017-08-14 19:03:02,525 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_07__2016_May_19:57] Migration completed successfully. 2017-08-14 19:03:02,638 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_08__2016_Jul_01:108] Migration completed successfully. 2017-08-14 19:03:02,722 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_09__2016_Jul_16:93] Migration completed successfully. 2017-08-14 19:03:02,807 INFO [main] [org.mycontroller.standalone.db.migration.V1_02_10__2016_Jul_29:53] Migration completed successfully. 2017-08-14 19:03:02,894 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_01__2016_Aug_03:66] Migration completed successfully. 2017-08-14 19:03:02,986 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_02__2016_Aug_06:63] Migration completed successfully. 2017-08-14 19:03:03,065 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_03__2016_Sep_20:95] Migration completed successfully. 2017-08-14 19:03:03,138 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_04__2016_Oct_19:61] Migration completed successfully. 2017-08-14 19:03:03,228 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_05__2016_Nov_18:77] Migration completed successfully. 2017-08-14 19:03:03,468 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_06__2017_Mar_03:66] Migration completed successfully. 2017-08-14 19:03:04,211 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:161] Number of migrations done:22 2017-08-14 19:03:04,215 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:167] Application information: [Version:0.0.3.Final-SNAPSHOT, Database(type:H2 database embedded, version:1.4.194 (2017-03-10), schema version:1.03.06 - 2017 Mar 03), Built on:2017-08-03T01:01:02+0530, Git commit:204e8a96c57767fd7650ce041cfa2fef0dac2bf1:development] 2017-08-14 19:03:04,728 INFO [main] [org.mycontroller.standalone.AppProperties:497] Created directory location: [/home/pi/mycontroller/conf/persistent_stores/mc] 2017-08-14 19:03:05,842 INFO [Thread-8] [org.mycontroller.standalone.message.RawMessageQueue:54] Cleared offline messages[0] from the queue. 2017-08-14 19:03:07,457 INFO [main] [org.mycontroller.standalone.mqttbroker.MoquetteMqttBroker:52] MQTT Broker started successfully. MqttBrokerSettings(enabled=true, bindAddress=0.0.0.0, httpPort=1883, websocketPort=7080, allowAnonymous=false) 2017-08-14 19:03:11,999 INFO [main] [org.mycontroller.standalone.StartApp:214] TJWS server started successfully, HTTPS Enabled?:true, HTTP(S) Port: [8443] 2017-08-14 19:03:12,009 INFO [main] [org.mycontroller.standalone.StartApp:112] MyController.org server started in [24605] ms [Mon Aug 14 19:03:12 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:03:12 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:03:14 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:03:14 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:03:17 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:03:19 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:03:23 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:03:24 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:04:07 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:04:10 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:04:11 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:04:11 EST 2017] IO error: javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? in processing a request from /192.168.0.59:8443 / sun.security.ssl.SSLSocketImpl [Mon Aug 14 19:07:18 EST 2017] Accept: java.net.SocketException: Socket closed 2017-08-14 19:07:18,447 INFO [MyController.org Shutdown-Hook] [org.mycontroller.standalone.mqttbroker.MoquetteMqttBroker:70] MQTT Broker has been stopped successfully 2017-08-14 19:07:18,461 INFO [MyController.org Shutdown-Hook] [org.mycontroller.standalone.AppShutdownHook:36] Bye, Have a nice day! See you soon 2017-08-14 19:07:23,428 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:116] Checking migration... 2017-08-14 19:07:28,940 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:167] Application information: [Version:0.0.3.Final-SNAPSHOT, Database(type:H2 database embedded, version:1.4.194 (2017-03-10), schema version:1.03.06 - 2017 Mar 03), Built on:2017-08-03T01:01:02+0530, Git commit:204e8a96c57767fd7650ce041cfa2fef0dac2bf1:development] 2017-08-14 19:07:30,491 INFO [Thread-6] [org.mycontroller.standalone.message.RawMessageQueue:54] Cleared offline messages[0] from the queue. 2017-08-14 19:07:31,773 INFO [main] [org.mycontroller.standalone.mqttbroker.MoquetteMqttBroker:52] MQTT Broker started successfully. MqttBrokerSettings(enabled=true, bindAddress=0.0.0.0, httpPort=1883, websocketPort=7080, allowAnonymous=false) 2017-08-14 19:07:35,529 INFO [main] [org.mycontroller.standalone.StartApp:214] TJWS server started successfully, HTTPS Enabled?:false, HTTP(S) Port: [8443] 2017-08-14 19:07:35,531 INFO [main] [org.mycontroller.standalone.StartApp:112] MyController.org server started in [12367] ms 2017-08-14 19:09:14,691 INFO [Acme.Utils.ThreadPool(0)-PooledThread: Acme.Serve.Serve$ServeConnection@e8953c] [org.mycontroller.standalone.api.BackupApi:219] Restore triggered. 2017-08-14 19:09:17,694 INFO [Thread-10] [org.mycontroller.standalone.backup.Restore:69] About to restore a backup, BackupFile(name=on-demand_mc_backup-2017_08_14-18_52_05.zip, canonicalPath=/home/pi/mycontroller/backup/on-demand_mc_backup-2017_08_14-18_52_05.zip, timestamp=1502754725000, size=14004) [Mon Aug 14 19:09:17 EST 2017] Accept: java.net.SocketException: Socket closed 2017-08-14 19:09:17,968 INFO [Thread-10] [org.mycontroller.standalone.mqttbroker.MoquetteMqttBroker:70] MQTT Broker has been stopped successfully 2017-08-14 19:09:18,920 INFO [Thread-10] [org.mycontroller.standalone.db.DataBaseUtils:288] Database restore completed. Database url:jdbc:h2:file:../conf/mycontroller;MVCC=TRUE, Restored file name:/home/pi/mycontroller/bin/tmp/on-demand_mc_backup-2017_08_14-18_52_05/database_backup.zip 2017-08-14 19:09:18,947 INFO [Thread-10] [org.mycontroller.standalone.backup.Restore:153] Restore completed successfully. Start 'MyController.org' server manually 2017-08-14 19:09:18,961 WARN [MyController.org Shutdown-Hook] [org.knowm.sundial.SundialJobScheduler:140] Scheduler has not yet been created!!! Call "createScheduler" first. 2017-08-14 19:09:18,965 INFO [MyController.org Shutdown-Hook] [org.mycontroller.standalone.AppShutdownHook:36] Bye, Have a nice day! See you soon 2017-08-14 19:10:10,667 INFO [main] [org.mycontroller.standalone.AppProperties:497] Created directory location: [/home/pi/mycontroller/conf/resources/scripts] 2017-08-14 19:10:10,707 INFO [main] [org.mycontroller.standalone.AppProperties:497] Created directory location: [/home/pi/mycontroller/conf/resources/scripts/conditions] 2017-08-14 19:10:10,710 INFO [main] [org.mycontroller.standalone.AppProperties:497] Created directory location: [/home/pi/mycontroller/conf/resources/scripts/operations] 2017-08-14 19:10:10,925 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:116] Checking migration... 2017-08-14 19:10:17,195 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_01__2016_Aug_03:66] Migration completed successfully. 2017-08-14 19:10:17,455 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_02__2016_Aug_06:63] Migration completed successfully. 2017-08-14 19:10:18,637 INFO [main] [org.mycontroller.standalone.MapDbFactory:89] Cleared McPersistent location[../conf/persistent_stores/mc/] 2017-08-14 19:10:18,639 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_03__2016_Sep_20:95] Migration completed successfully. 2017-08-14 19:10:19,165 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_04__2016_Oct_19:61] Migration completed successfully. 2017-08-14 19:10:19,602 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_05__2016_Nov_18:77] Migration completed successfully. 2017-08-14 19:10:20,011 INFO [main] [org.mycontroller.standalone.db.migration.V1_03_06__2017_Mar_03:66] Migration completed successfully. 2017-08-14 19:10:20,992 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:161] Number of migrations done:6 2017-08-14 19:10:21,001 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:167] Application information: [Version:0.0.3.Final-SNAPSHOT, Database(type:H2 database embedded, version:1.4.194 (2017-03-10), schema version:1.03.06 - 2017 Mar 03), Built on:2017-08-03T01:01:02+0530, Git commit:204e8a96c57767fd7650ce041cfa2fef0dac2bf1:development] 2017-08-14 19:10:21,614 INFO [main] [org.mycontroller.standalone.AppProperties:497] Created directory location: [/home/pi/mycontroller/conf/persistent_stores/mc] 2017-08-14 19:10:22,728 INFO [Thread-11] [org.mycontroller.standalone.message.RawMessageQueue:54] Cleared offline messages[0] from the queue. 2017-08-14 19:10:24,143 INFO [main] [org.mycontroller.standalone.mqttbroker.MoquetteMqttBroker:52] MQTT Broker started successfully. MqttBrokerSettings(enabled=true, bindAddress=0.0.0.0, httpPort=1883, websocketPort=7080, allowAnonymous=false) 2017-08-14 19:10:28,637 INFO [main] [org.mycontroller.standalone.StartApp:214] TJWS server started successfully, HTTPS Enabled?:false, HTTP(S) Port: [8443] 2017-08-14 19:10:28,640 INFO [main] [org.mycontroller.standalone.StartApp:112] MyController.org server started in [18045] ms 2017-08-14 19:24:29,979 ERROR [Thread-11] [org.mycontroller.standalone.message.MessageMonitorThread:119] Throws exception while processing!, [RawMessage(gatewayId=2, data=0;0;3;1;28;0\n, subData=null, isTxMessage=true, networkType=MY_SENSORS, timestamp=1502756669895)] java.lang.NullPointerException: null at org.mycontroller.standalone.message.McMessageEngine.internalSubMessageTypeSelector(McMessageEngine.java:384) at org.mycontroller.standalone.message.McMessageEngine.execute(McMessageEngine.java:121) at org.mycontroller.standalone.message.McMessageEngine.run(McMessageEngine.java:1090) at org.mycontroller.standalone.message.McMessageUtils.sendToMcMessageEngine(McMessageUtils.java:662) at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:59) at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:571) at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:107) at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:182) at java.lang.Thread.run(Thread.java:745)
-
@jkandasa Here is the info from a simple temperature node (MySensors 2.0.0):
0 MCO:BGN:INIT NODE,CP=RNNNA--,VER=2.1.1 3 TSM:INIT 4 TSF:WUR:MS=0 11 TSM:INIT:TSP OK 13 TSM:INIT:STATID=1 14 TSF:SID:OK,ID=1 16 TSM:FPAR 53 TSF:MSG:SEND,1-1-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 355 TSF:MSG:READ,0-0-1,s=255,c=3,t=8,pt=1,l=1,sg=0:0 359 TSF:MSG:FPAR OK,ID=0,D=1 2060 TSM:FPAR:OK 2061 TSM:ID 2062 TSM:ID:OK 2064 TSM:UPL 2067 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=OK:1 2076 TSF:MSG:READ,0-0-1,s=255,c=3,t=25,pt=1,l=1,sg=0:1 2081 TSF:MSG:PONG RECV,HP=1 2083 TSM:UPL:OK 2085 TSM:READY:ID=1,PAR=0,DIS=1 2091 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0100 2097 TSF:MSG:READ,0-0-1,s=255,c=3,t=15,pt=6,l=2,sg=0:0100 2104 TSF:MSG:SEND,1-1-0-0,s=255,c=0,t=17,pt=0,l=5,sg=0,ft=0,st=OK:2.1.1 2112 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=6,pt=1,l=1,sg=0,ft=0,st=OK:0 2123 TSF:MSG:READ,0-0-1,s=255,c=3,t=6,pt=0,l=1,sg=0:M 2131 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=11,pt=0,l=22,sg=0,ft=0,st=OK:TemperatureAndHumidity 2141 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=12,pt=0,l=3,sg=0,ft=0,st=OK:1.1 2149 TSF:MSG:SEND,1-1-0-0,s=0,c=0,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 2157 TSF:MSG:SEND,1-1-0-0,s=1,c=0,t=6,pt=0,l=0,sg=0,ft=0,st=OK: 2162 MCO:REG:REQ 2166 TSF:MSG:SEND,1-1-0-0,s=255,c=3,t=26,pt=1,l=1,sg=0,ft=0,st=OK:2 2172 TSF:MSG:READ,0-0-1,s=255,c=3,t=27,pt=1,l=1,sg=0:1 2178 MCO:PIM:NODE REG=1 2180 MCO:BGN:STP 2182 MCO:SLP:MS=1000,SMS=0,I1=255,M1=255,I2=255,M2=255 2187 MCO:SLP:TPD 2189 MCO:SLP:WUP=-1 2191 MCO:BGN:INIT OK,TSP=1 2217 TSF:MSG:SEND,1-1-0-0,s=1,c=1,t=0,pt=7,l=5,sg=0,ft=0,st=OK:22.0 T: 22.00 2226 TSF:MSG:SEND,1-1-0-0,s=0,c=1,t=1,pt=7,l=5,sg=0,ft=0,st=OK:44.0 H: 44.00 2233 MCO:SLP:MS=10000,SMS=0,I1=255,M1=255,I2=255,M2=255 2238 MCO:SLP:TPD
-
Now the node/sensor is doing this:
187971 TSF:MSG:SEND,11-11-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 189978 !TSM:FPAR:NO REPLY 189980 TSM:FPAR 190017 TSF:MSG:SEND,11-11-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 192024 !TSM:FPAR:FAIL 192026 TSM:FAIL:CNT=7 192028 TSM:FAIL:PDT 252032 TSM:FAIL:RE-INIT 252034 TSM:INIT 252041 TSM:INIT:TSP OK 252043 TSM:INIT:STATID=11 252045 TSF:SID:OK,ID=11 252048 TSM:FPAR 252085 TSF:MSG:SEND,11-11-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 254092 !TSM:FPAR:NO REPLY 254094 TSM:FPAR 254131 TSF:MSG:SEND,11-11-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 256138 !TSM:FPAR:NO REPLY 256140 TSM:FPAR 256177 TSF:MSG:SEND,11-11-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 258184 !TSM:FPAR:NO REPLY 258186 TSM:FPAR 258223 TSF:MSG:SEND,11-11-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK: 260230 !TSM:FPAR:FAIL 260232 TSM:FAIL:CNT=7 260234 TSM:FAIL:PDT
I am able to get the node to show up under "Nodes", but it still adds a second node alongside my MySensors node. If I declare an ID using
#define MY_NODE_ID 11
, it will create that node but always with a second node with EUI 0. The EUI 0 Node says "Up" in the topology map and it is "connected" to the Gateway by a line. The MySensors node with EUI defined with#define MY_NODE_ID 11
shows up in the topology map, but it is not connected to the gateway by a line.#define NODE_ID AUTO
does nothing.Here is my sketch:
// Enable debug prints #define MY_DEBUG #define MY_NODE_ID 11 // Enable and select radio type attached #define MY_RADIO_NRF24 //#define MY_RADIO_RFM69 //#define MY_RS485 #include <MyConfig.h> #include <MySensors.h> #include <SPI.h> #include <DHT.h> // Set this to the pin you connected the DHT's data pin to #define DHT_DATA_PIN 3 // Set this offset if the sensor has a permanent small offset to the real temperatures #define SENSOR_TEMP_OFFSET 0 // Sleep time between sensor updates (in milliseconds) // Must be >1000ms for DHT22 and >2000ms for DHT11 static const uint64_t UPDATE_INTERVAL = 10000; // Force sending an update of the temperature after n sensor reads, so a controller showing the // timestamp of the last update doesn't show something like 3 hours in the unlikely case, that // the value didn't change since; // i.e. the sensor would force sending an update every UPDATE_INTERVAL*FORCE_UPDATE_N_READS [ms] static const uint8_t FORCE_UPDATE_N_READS = 10; #define CHILD_ID_HUM 0 #define CHILD_ID_TEMP 1 float lastTemp; float lastHum; uint8_t nNoUpdatesTemp; uint8_t nNoUpdatesHum; bool metric = true; MyMessage msgHum(CHILD_ID_HUM, V_HUM); MyMessage msgTemp(CHILD_ID_TEMP, V_TEMP); DHT dht; void presentation() { // Send the sketch version information to the gateway sendSketchInfo("TemperatureAndHumidity", "1.1"); // Register all sensors to gw (they will be created as child devices) present(CHILD_ID_HUM, S_HUM); present(CHILD_ID_TEMP, S_TEMP); metric = getControllerConfig().isMetric; } void setup() { dht.setup(DHT_DATA_PIN); // set data pin of DHT sensor if (UPDATE_INTERVAL <= dht.getMinimumSamplingPeriod()) { Serial.println("Warning: UPDATE_INTERVAL is smaller than supported by the sensor!"); } // Sleep for the time of the minimum sampling period to give the sensor time to power up // (otherwise, timeout errors might occure for the first reading) sleep(dht.getMinimumSamplingPeriod()); } void loop() { // Force reading sensor, so it works also after sleep() dht.readSensor(true); // Get temperature from DHT library float temperature = dht.getTemperature(); if (isnan(temperature)) { Serial.println("Failed reading temperature from DHT!"); } else if (temperature != lastTemp || nNoUpdatesTemp == FORCE_UPDATE_N_READS) { // Only send temperature if it changed since the last measurement or if we didn't send an update for n times lastTemp = temperature; if (!metric) { temperature = dht.toFahrenheit(temperature); } // Reset no updates counter nNoUpdatesTemp = 0; temperature += SENSOR_TEMP_OFFSET; send(msgTemp.set(temperature, 1)); #ifdef MY_DEBUG Serial.print("T: "); Serial.println(temperature); #endif } else { // Increase no update counter if the temperature stayed the same nNoUpdatesTemp++; } // Get humidity from DHT library float humidity = dht.getHumidity(); if (isnan(humidity)) { Serial.println("Failed reading humidity from DHT"); } else if (humidity != lastHum || nNoUpdatesHum == FORCE_UPDATE_N_READS) { // Only send humidity if it changed since the last measurement or if we didn't send an update for n times lastHum = humidity; // Reset no updates counter nNoUpdatesHum = 0; send(msgHum.set(humidity, 1)); #ifdef MY_DEBUG Serial.print("H: "); Serial.println(humidity); #endif } else { // Increase no update counter if the humidity stayed the same nNoUpdatesHum++; } // Sleep for a while to save energy sleep(UPDATE_INTERVAL); }
-
@iamtheghost When you define
#define NODE_ID AUTO
can you triggerDiscover
on MyController?Resources
>>Gateways
>>Select your gateway
>>Actions
>>Discover
.Regards the following issue,
2017-08-14 19:24:29,979 ERROR [Thread-11] [org.mycontroller.standalone.message.MessageMonitorThread:119] Throws exception while processing!, [RawMessage(gatewayId=2, data=0;0;3;1;28;0\n, subData=null, isTxMessage=true, networkType=MY_SENSORS, timestamp=1502756669895)] java.lang.NullPointerException: null
When we send internal message
0;0;3;1;28;0
, AFAIK, we should use SENSOR ID as255
. Here it is0
. -
Here is my latest log messages:
2017-08-17 18:55:06,186 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:167] Application information: [Version:0.0.3.Final-SNAPSHOT, Database(type:H2 database embedded, version:1.4.194 (2017-03-10), schema version:1.03.06 - 2017 Mar 03), Built on:2017-08-03T01:01:02+0530, Git commit:204e8a96c57767fd7650ce041cfa2fef0dac2bf1:development] 2017-08-17 18:55:07,873 INFO [Thread-6] [org.mycontroller.standalone.message.RawMessageQueue:54] Cleared offline messages[0] from the queue. 2017-08-17 18:55:09,404 INFO [main] [org.mycontroller.standalone.mqttbroker.MoquetteMqttBroker:52] MQTT Broker started successfully. MqttBrokerSettings(enabled=true, bindAddress=0.0.0.0, httpPort=1883, websocketPort=7080, allowAnonymous=false) 2017-08-17 18:55:14,362 INFO [main] [org.mycontroller.standalone.StartApp:214] TJWS server started successfully, HTTPS Enabled?:false, HTTP(S) Port: [8443] 2017-08-17 18:55:14,371 INFO [main] [org.mycontroller.standalone.StartApp:112] MyController.org server started in [14732] ms 2017-08-17 18:55:14,814 ERROR [Thread-6] [org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge:64] Unable to process this rawMessage:RawMessage(gatewayId=4, data=55,s=255,c=3,t=7,pt=0,l=0,sg=0:, subData=null, isTxMessage=false, networkType=MY_SENSORS, timestamp=1503014114706) org.mycontroller.standalone.message.RawMessageException: Unknown message format:[55,s=255,c=3,t=7,pt=0,l=0,sg=0:] at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:183) at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:68) at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:571) at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:107) at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:182) at java.lang.Thread.run(Thread.java:745) 2017-08-17 19:03:54,879 ERROR [Thread-6] [org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge:64] Unable to process this rawMessage:RawMessage(gatewayId=4, data=,s=0,c=1,t=1,pt=7,l=5,sg=0:45.0, subData=null, isTxMessage=false, networkType=MY_SENSORS, timestamp=1503014634864) org.mycontroller.standalone.message.RawMessageException: Unknown message format:[,s=0,c=1,t=1,pt=7,l=5,sg=0:45.0] at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:183) at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:68) at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:571) at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:107) at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:182) at java.lang.Thread.run(Thread.java:745)
Looks like something in there isn't working, I'm just not sure what!
-
Here is some more information:
It is still creating the node with EUI 0. I don't know why but it creates it every time I try to add the temperature and humidity node:
It seems like it wants to work, but I don't think I'm getting it right:
No matter what, a node with EUI 0 shows up. I manually set my temperature and humidity node to ID 11 and it still creates the "phantom" node 0:
Is there a way to make the nodes and the gateway refresh very fast to help with trouble-shooting?
-
@iamtheghost Thanks for the detailed information. Node
0
must be created on MySensors network, it is a gateway node. Without gateway, we cannot communicate with other nodes on the network.Is there a way to make the nodes and the gateway refresh very fast to help with trouble-shooting?
select your gateway and execute discover on the gateway page.
how frequent are you seeing this error? Which serial driver are you using on gateway. If not in auto, put it on auto.
2017-08-17 19:03:54,879 ERROR [Thread-6] [org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge:64] Unable to process this rawMessage:RawMessage(gatewayId=4, data=,s=0,c=1,t=1,pt=7,l=5,sg=0:45.0, subData=null, isTxMessage=false, networkType=MY_SENSORS, timestamp=1503014634864) org.mycontroller.standalone.message.RawMessageException: Unknown message format:[,s=0,c=1,t=1,pt=7,l=5,sg=0:45.0] at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.j
Can you post sensors page?
-
Thanks for your help. I am getting this error very frequently. The serial gateway is in auto mode for the driver.
2017-08-19 06:48:22,661 ERROR [Thread-6] [org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge:64] Unable to process this rawMessage:RawMessage(gatewayId=4, data=;0;9;MCO:BGN:STP, subData=null, isTxMessage=false, networkType=MY_SENSORS, timestamp=1503143302653) org.mycontroller.standalone.message.RawMessageException: Unknown message format:[;0;9;MCO:BGN:STP] at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:183) at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:68) at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:571) at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:107) at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:182) at java.lang.Thread.run(Thread.java:745)
That's as of 6:45A.M. EST time (U.S.). It is giving me this error in the log approximately every 120 seconds.
Here is my sensors page. When I used Discover on the Gateway, it found the Humidity Sensor and set it with a value. It did not find the Temperature portion of the sensor and I had to add it manually. The temperature portion of the sensor has not updated with a value (I'm using a DHT-11). It appears that the sensors values will initially get a value from the message sent, but future messages do not update the sensor value. I left it on overnight and the sensor values did not change. The "Last Seen" value changed when I used Discover mode on the Gateway. Hopefully this is helpful. I really want to get MyController and MySensors working together. Thank you so much for your help.
-
@iamtheghost Are you using inbuilt serial IO pins from raspberry PI? If yes, you may need to disable console login. Have you configured this serial port for any other service?
looks like you have some issues with your gateway serial port.