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:
    topology

    The node with ID 1, the TemperatureandHumidity node with the actual sensors registered to it, does not "connect" to the gateway.

    Here is my 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:

    0_1502545720650_image.png

    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?


  • ADMIN

    @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
    


  • @jkandasa

    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); 
    }
    

  • ADMIN

    @iamtheghost When you define #define NODE_ID AUTO can you trigger Discover 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 as 255. Here it is 0.



  • @jkandasa

    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?


  • ADMIN

    @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?



  • @jkandasa

    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.
    0_1503143735552_image.png


  • ADMIN

    @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.


 

Suggested Topics

5
Online

333
Users

317
Topics

1.9k
Posts