Modul-Entwicklung: Somfy RTS

[Garbsen]

@Blackstone:
Ich meine mich dunkel erinnern zu können, dass ich das Problem beim Einrichten auch einmal hatte. Die Lösung ergab sich m.E. nachdem ich die Zeit für to 100 und to close um 1 sek unterschiedlich gesetzt hatte.
Vielleicht kannst du das ja mal ausprobieren.

[BlackStone]

Sodale einmal die logauszüge..

erst runtergefahren und dann mit verbose 5

hoch gefahren, dann pos 50 und nach stillstand nochmals pos 50

Code Auswählen Erweitern

2017.02.07 17:46:57 4: SOMFY_set: rollBuero Inverse before cmd:off: arg1:: pos:0:
2017.02.07 17:46:57 4: SOMFY_set: rollBuero Inverse after  cmd:off: arg1:: pos:200:
2017.02.07 17:46:57 4: SOMFY_set: rollBuero -> entering with mode :send: cmd :off:  arg1 ::  pos :200:
2017.02.07 17:46:57 4: SOMFY_set: handled command off --> move :off:  newState :200:
2017.02.07 17:46:57 5: SOMFY_set: handled for drive/udpate:  updateState :0:  drivet :0: updatet :17:
2017.02.07 17:46:57 4: SOMFY_UpdateState: rollBuero enter with  newState:200:   updatestate:0:   move:off:
2017.02.07 17:46:57 4: SOMFY_UpdateState: rollBuero after conversions  newState:0:  rounded:0:  stateTrans:closed:
2017.02.07 17:46:57 4: SOMFY_sendCommand: rollBuero -> cmd :off:
2017.02.07 17:46:57 5: SOMFY_sendCommand: rollBuero -> message :sAA2000FA000059:
2017.02.07 17:46:57 4: SOMFY_set: rollBuero -> update state in 17 sec
2017.02.07 17:46:57 4: SOMFY_set: rollBuero Inverse before cmd:off: arg1:: pos:0:
2017.02.07 17:46:57 4: SOMFY_set: rollBuero Inverse after  cmd:off: arg1:: pos:200:
2017.02.07 17:46:57 4: SOMFY_set: rollBuero -> entering with mode :virtual: cmd :off:  arg1 ::  pos :200:
2017.02.07 17:46:57 4: SOMFY_set: handled command off --> move :off:  newState :194:
2017.02.07 17:46:57 5: SOMFY_set: handled for drive/udpate:  updateState :0:  drivet :0: updatet :16.79:
2017.02.07 17:46:57 4: SOMFY_UpdateState: rollBuero enter with  newState:194:   updatestate:0:   move:off:
2017.02.07 17:46:57 4: SOMFY_UpdateState: rollBuero after conversions  newState:0.6:  rounded:0:  stateTrans:closed:
2017.02.07 17:46:57 4: SOMFY_set: rollBuero -> update state in 16.79 sec
2017.02.07 17:46:58 1: usb create starting
2017.02.07 17:46:58 3: Probing CUL device /dev/ttyAMA0
2017.02.07 17:46:59 3: Probing TCM_ESP3 device /dev/ttyAMA0
2017.02.07 17:46:59 3: Probing FRM device /dev/ttyAMA0
2017.02.07 17:47:04 1: usb create end
2017.02.07 17:47:04 3: CUL_0: Unknown code YsAA2B00FA590000, help me!
2017.02.07 17:47:04 4: SOMFY_TimedUpdate
2017.02.07 17:47:04 5: SOMFY_TimedUpdate : pos before convert so far : 0.6
2017.02.07 17:47:04 5: SOMFY_TimedUpdate : pos so far : 194
2017.02.07 17:47:04 5: SOMFY_TimedUpdate : delta time : 6.96   new rounde pos (rounded): 70.4285714285714
2017.02.07 17:47:04 4: SOMFY_UpdateState: rollBuero enter with  newState:70.4285714285714:   updatestate:0:   move:off:
2017.02.07 17:47:04 4: SOMFY_UpdateState: rollBuero after conversions  newState:36.6142857142857:  rounded:40:  stateTrans:40:
2017.02.07 17:47:04 4: SOMFY_TimedUpdate: rollBuero -> update state in 9.83 sec
2017.02.07 17:47:04 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486027.53336
2017.02.07 17:47:04 5: SOMFY_TimedUpdate DONE
2017.02.07 17:47:07 4: SOMFY_TimedUpdate
2017.02.07 17:47:07 5: SOMFY_TimedUpdate : pos before convert so far : 36.6142857142857
2017.02.07 17:47:07 5: SOMFY_TimedUpdate : pos so far : 70.4285714285714
2017.02.07 17:47:07 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 49.0714285714286
2017.02.07 17:47:07 4: SOMFY_UpdateState: rollBuero enter with  newState:49.0714285714286:   updatestate:0:   move:off:
2017.02.07 17:47:07 4: SOMFY_UpdateState: rollBuero after conversions  newState:55.8357142857143:  rounded:60:  stateTrans:60:
2017.02.07 17:47:07 4: SOMFY_TimedUpdate: rollBuero -> update state in 6.84 sec
2017.02.07 17:47:07 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486030.5274
2017.02.07 17:47:07 5: SOMFY_TimedUpdate DONE
2017.02.07 17:47:10 4: SOMFY_TimedUpdate
2017.02.07 17:47:10 5: SOMFY_TimedUpdate : pos before convert so far : 55.8357142857143
2017.02.07 17:47:10 5: SOMFY_TimedUpdate : pos so far : 49.0714285714286
2017.02.07 17:47:10 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 27.7142857142857
2017.02.07 17:47:10 4: SOMFY_UpdateState: rollBuero enter with  newState:27.7142857142857:   updatestate:0:   move:off:
2017.02.07 17:47:10 4: SOMFY_UpdateState: rollBuero after conversions  newState:75.0571428571429:  rounded:80:  stateTrans:80:
2017.02.07 17:47:10 4: SOMFY_TimedUpdate: rollBuero -> update state in 3.85 sec
2017.02.07 17:47:10 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486033.52077
2017.02.07 17:47:10 5: SOMFY_TimedUpdate DONE
2017.02.07 17:47:13 4: SOMFY_TimedUpdate
2017.02.07 17:47:13 5: SOMFY_TimedUpdate : pos before convert so far : 75.0571428571429
2017.02.07 17:47:13 5: SOMFY_TimedUpdate : pos so far : 27.7142857142857
2017.02.07 17:47:13 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 6.35714285714283
2017.02.07 17:47:13 4: SOMFY_UpdateState: rollBuero enter with  newState:6.35714285714283:   updatestate:0:   move:off:
2017.02.07 17:47:13 4: SOMFY_UpdateState: rollBuero after conversions  newState:94.2785714285715:  rounded:90:  stateTrans:90:
2017.02.07 17:47:13 4: SOMFY_TimedUpdate: rollBuero -> update state in 0.859999999999998 sec
2017.02.07 17:47:13 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486034.3744
2017.02.07 17:47:13 5: SOMFY_TimedUpdate DONE
2017.02.07 17:47:14 4: SOMFY_TimedUpdate
2017.02.07 17:47:14 5: SOMFY_TimedUpdate : pos before convert so far : 94.2785714285715
2017.02.07 17:47:14 5: SOMFY_TimedUpdate : pos so far : 6.35714285714283
2017.02.07 17:47:14 5: SOMFY_TimedUpdate : delta time : 0.85   new rounde pos (rounded): 0.285714285714258
2017.02.07 17:47:14 4: SOMFY_UpdateState: rollBuero enter with  newState:0:   updatestate:<undef>:   move:stop:
2017.02.07 17:47:14 4: SOMFY_UpdateState: rollBuero after conversions  newState:100:  rounded:100:  stateTrans:open:
2017.02.07 17:47:14 5: SOMFY_TimedUpdate DONE
2017.02.07 17:51:05 4: SOMFY_set: rollBuero Inverse before cmd:pos: arg1:50: pos:100:
2017.02.07 17:51:05 4: SOMFY_set: rollBuero Inverse after  cmd:pos: arg1:55.5555555555556: pos:0:
2017.02.07 17:51:05 4: SOMFY_set: rollBuero -> entering with mode :send: cmd :pos:  arg1 :55.5555555555556:  pos :0:
2017.02.07 17:51:05 4: SOMFY_set: handled command pos --> move :on:  newState :0:
2017.02.07 17:51:05 5: SOMFY_set: handled for drive/udpate:  updateState :55.5555555555556:  drivet :7.22222222222222: updatet :0:
2017.02.07 17:51:05 4: SOMFY_UpdateState: rollBuero enter with  newState:0:   updatestate:55.5555555555556:   move:on:
2017.02.07 17:51:05 4: SOMFY_UpdateState: rollBuero after conversions  newState:100:  rounded:100:  stateTrans:open:
2017.02.07 17:51:05 4: SOMFY_sendCommand: rollBuero -> cmd :on:
2017.02.07 17:51:05 5: SOMFY_sendCommand: rollBuero -> message :sAB4000FB000059:
2017.02.07 17:51:05 4: SOMFY_set: rollBuero -> stopping in 7.22222222222222 sec
2017.02.07 17:51:06 4: SOMFY_set: rollBuero Inverse before cmd:on: arg1:: pos:100:
2017.02.07 17:51:06 4: SOMFY_set: rollBuero Inverse after  cmd:on: arg1:: pos:0:
2017.02.07 17:51:06 4: SOMFY_set: rollBuero -> entering with mode :virtual: cmd :on:  arg1 ::  pos :0:
2017.02.07 17:51:06 4: SOMFY_set: handled command on --> move :on:  newState :2:
2017.02.07 17:51:06 5: SOMFY_set: handled for drive/udpate:  updateState :200:  drivet :0: updatet :14.79:
2017.02.07 17:51:06 4: SOMFY_UpdateState: rollBuero enter with  newState:2:   updatestate:200:   move:on:
2017.02.07 17:51:06 4: SOMFY_UpdateState: rollBuero after conversions  newState:98.2:  rounded:100:  stateTrans:open:
2017.02.07 17:51:06 4: SOMFY_set: rollBuero -> update state in 14.79 sec
2017.02.07 17:51:06 1: usb create starting
2017.02.07 17:51:07 3: Probing CUL device /dev/ttyAMA0
2017.02.07 17:51:07 3: Probing TCM_ESP3 device /dev/ttyAMA0
2017.02.07 17:51:07 3: Probing FRM device /dev/ttyAMA0
2017.02.07 17:51:13 1: usb create end
2017.02.07 17:51:13 3: CUL_0: Unknown code YsAB4D00FB590000, help me!
2017.02.07 17:51:13 4: SOMFY_TimedUpdate
2017.02.07 17:51:13 5: SOMFY_TimedUpdate : pos before convert so far : 98.2
2017.02.07 17:51:13 5: SOMFY_TimedUpdate : pos so far : 2
2017.02.07 17:51:13 5: SOMFY_TimedUpdate : delta time : 6.97   new rounde pos (rounded): 55.6153846153846
2017.02.07 17:51:13 4: SOMFY_UpdateState: rollBuero enter with  newState:55.6153846153846:   updatestate:200:   move:on:
2017.02.07 17:51:13 4: SOMFY_UpdateState: rollBuero after conversions  newState:49.9461538461538:  rounded:50:  stateTrans:50:
2017.02.07 17:51:13 4: SOMFY_TimedUpdate: rollBuero -> update state in 7.82 sec
2017.02.07 17:51:13 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486276.04429
2017.02.07 17:51:13 5: SOMFY_TimedUpdate DONE
2017.02.07 17:51:16 4: SOMFY_TimedUpdate
2017.02.07 17:51:16 5: SOMFY_TimedUpdate : pos before convert so far : 49.9461538461538
2017.02.07 17:51:16 5: SOMFY_TimedUpdate : pos so far : 55.6153846153846
2017.02.07 17:51:16 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 78.6153846153846
2017.02.07 17:51:16 4: SOMFY_UpdateState: rollBuero enter with  newState:78.6153846153846:   updatestate:200:   move:on:
2017.02.07 17:51:16 4: SOMFY_UpdateState: rollBuero after conversions  newState:29.2461538461538:  rounded:30:  stateTrans:30:
2017.02.07 17:51:16 4: SOMFY_TimedUpdate: rollBuero -> update state in 4.83 sec
2017.02.07 17:51:16 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486279.03811
2017.02.07 17:51:16 5: SOMFY_TimedUpdate DONE
2017.02.07 17:51:19 4: SOMFY_TimedUpdate
2017.02.07 17:51:19 5: SOMFY_TimedUpdate : pos before convert so far : 29.2461538461538
2017.02.07 17:51:19 5: SOMFY_TimedUpdate : pos so far : 78.6153846153846
2017.02.07 17:51:19 5: SOMFY_TimedUpdate : delta time : 3.01   new rounde pos (rounded): 111.5
2017.02.07 17:51:19 4: SOMFY_UpdateState: rollBuero enter with  newState:111.5:   updatestate:200:   move:on:
2017.02.07 17:51:19 4: SOMFY_UpdateState: rollBuero after conversions  newState:8.85:  rounded:10:  stateTrans:down:
2017.02.07 17:51:19 4: SOMFY_TimedUpdate: rollBuero -> update state in 1.82 sec
2017.02.07 17:51:19 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486280.86856
2017.02.07 17:51:19 5: SOMFY_TimedUpdate DONE
2017.02.07 17:51:20 4: SOMFY_TimedUpdate
2017.02.07 17:51:20 5: SOMFY_TimedUpdate : pos before convert so far : 8.85
2017.02.07 17:51:20 5: SOMFY_TimedUpdate : pos so far : 111.5
2017.02.07 17:51:20 5: SOMFY_TimedUpdate : delta time : 1.81   new rounde pos (rounded): 200
2017.02.07 17:51:20 4: SOMFY_UpdateState: rollBuero enter with  newState:200:   updatestate:<undef>:   move:stop:
2017.02.07 17:51:20 4: SOMFY_UpdateState: rollBuero after conversions  newState:0:  rounded:0:  stateTrans:closed:
2017.02.07 17:51:20 5: SOMFY_TimedUpdate DONE
2017.02.07 17:51:51 4: SOMFY_set: rollBuero Inverse before cmd:pos: arg1:50: pos:0:
2017.02.07 17:51:51 4: SOMFY_set: rollBuero Inverse after  cmd:pos: arg1:55.5555555555556: pos:200:
2017.02.07 17:51:51 4: SOMFY_set: rollBuero -> entering with mode :send: cmd :pos:  arg1 :55.5555555555556:  pos :200:
2017.02.07 17:51:51 4: SOMFY_set: handled command pos --> move :off:  newState :200:
2017.02.07 17:51:51 5: SOMFY_set: handled for drive/udpate:  updateState :55.5555555555556:  drivet :9.22222222222222: updatet :0:
2017.02.07 17:51:51 4: SOMFY_UpdateState: rollBuero enter with  newState:200:   updatestate:55.5555555555556:   move:off:
2017.02.07 17:51:51 4: SOMFY_UpdateState: rollBuero after conversions  newState:0:  rounded:0:  stateTrans:closed:
2017.02.07 17:51:51 4: SOMFY_sendCommand: rollBuero -> cmd :off:
2017.02.07 17:51:51 5: SOMFY_sendCommand: rollBuero -> message :sAC2000FC000059:
2017.02.07 17:51:51 4: SOMFY_set: rollBuero -> stopping in 9.22222222222222 sec
2017.02.07 17:51:51 4: SOMFY_set: rollBuero Inverse before cmd:off: arg1:: pos:0:
2017.02.07 17:51:51 4: SOMFY_set: rollBuero Inverse after  cmd:off: arg1:: pos:200:
2017.02.07 17:51:51 4: SOMFY_set: rollBuero -> entering with mode :virtual: cmd :off:  arg1 ::  pos :200:
2017.02.07 17:51:51 4: SOMFY_set: handled command off --> move :off:  newState :194:
2017.02.07 17:51:51 5: SOMFY_set: handled for drive/udpate:  updateState :0:  drivet :0: updatet :16.79:
2017.02.07 17:51:51 4: SOMFY_UpdateState: rollBuero enter with  newState:194:   updatestate:0:   move:off:
2017.02.07 17:51:51 4: SOMFY_UpdateState: rollBuero after conversions  newState:0.6:  rounded:0:  stateTrans:closed:
2017.02.07 17:51:51 4: SOMFY_set: rollBuero -> update state in 16.79 sec
2017.02.07 17:51:52 1: usb create starting
2017.02.07 17:51:52 3: Probing CUL device /dev/ttyAMA0
2017.02.07 17:51:53 3: Probing TCM_ESP3 device /dev/ttyAMA0
2017.02.07 17:51:53 3: Probing FRM device /dev/ttyAMA0
2017.02.07 17:51:58 1: usb create end
2017.02.07 17:51:58 3: CUL_0: Unknown code YsAC2B00FC590000, help me!
2017.02.07 17:51:58 4: SOMFY_TimedUpdate
2017.02.07 17:51:58 5: SOMFY_TimedUpdate : pos before convert so far : 0.6
2017.02.07 17:51:58 5: SOMFY_TimedUpdate : pos so far : 194
2017.02.07 17:51:58 5: SOMFY_TimedUpdate : delta time : 6.96   new rounde pos (rounded): 70.4285714285714
2017.02.07 17:51:58 4: SOMFY_UpdateState: rollBuero enter with  newState:70.4285714285714:   updatestate:0:   move:off:
2017.02.07 17:51:58 4: SOMFY_UpdateState: rollBuero after conversions  newState:36.6142857142857:  rounded:40:  stateTrans:40:
2017.02.07 17:51:58 4: SOMFY_TimedUpdate: rollBuero -> update state in 9.83 sec
2017.02.07 17:51:58 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486321.5745
2017.02.07 17:51:58 5: SOMFY_TimedUpdate DONE
2017.02.07 17:52:01 4: SOMFY_TimedUpdate
2017.02.07 17:52:01 5: SOMFY_TimedUpdate : pos before convert so far : 36.6142857142857
2017.02.07 17:52:01 5: SOMFY_TimedUpdate : pos so far : 70.4285714285714
2017.02.07 17:52:01 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 49.0714285714286
2017.02.07 17:52:01 4: SOMFY_UpdateState: rollBuero enter with  newState:49.0714285714286:   updatestate:0:   move:off:
2017.02.07 17:52:01 4: SOMFY_UpdateState: rollBuero after conversions  newState:55.8357142857143:  rounded:60:  stateTrans:60:
2017.02.07 17:52:01 4: SOMFY_TimedUpdate: rollBuero -> update state in 6.84 sec
2017.02.07 17:52:01 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486324.56827
2017.02.07 17:52:01 5: SOMFY_TimedUpdate DONE
2017.02.07 17:52:04 4: SOMFY_TimedUpdate
2017.02.07 17:52:04 5: SOMFY_TimedUpdate : pos before convert so far : 55.8357142857143
2017.02.07 17:52:04 5: SOMFY_TimedUpdate : pos so far : 49.0714285714286
2017.02.07 17:52:04 5: SOMFY_TimedUpdate : delta time : 3.00   new rounde pos (rounded): 27.6428571428571
2017.02.07 17:52:04 4: SOMFY_UpdateState: rollBuero enter with  newState:27.6428571428571:   updatestate:0:   move:off:
2017.02.07 17:52:04 4: SOMFY_UpdateState: rollBuero after conversions  newState:75.1214285714286:  rounded:80:  stateTrans:80:
2017.02.07 17:52:04 4: SOMFY_TimedUpdate: rollBuero -> update state in 3.84 sec
2017.02.07 17:52:04 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486327.56718
2017.02.07 17:52:04 5: SOMFY_TimedUpdate DONE
2017.02.07 17:52:07 4: SOMFY_TimedUpdate
2017.02.07 17:52:07 5: SOMFY_TimedUpdate : pos before convert so far : 75.1214285714286
2017.02.07 17:52:07 5: SOMFY_TimedUpdate : pos so far : 27.6428571428571
2017.02.07 17:52:07 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 6.28571428571427
2017.02.07 17:52:07 4: SOMFY_UpdateState: rollBuero enter with  newState:6.28571428571427:   updatestate:0:   move:off:
2017.02.07 17:52:07 4: SOMFY_UpdateState: rollBuero after conversions  newState:94.3428571428572:  rounded:90:  stateTrans:90:
2017.02.07 17:52:07 4: SOMFY_TimedUpdate: rollBuero -> update state in 0.849999999999998 sec
2017.02.07 17:52:07 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486328.41001
2017.02.07 17:52:07 5: SOMFY_TimedUpdate DONE
2017.02.07 17:52:08 4: SOMFY_TimedUpdate
2017.02.07 17:52:08 5: SOMFY_TimedUpdate : pos before convert so far : 94.3428571428572
2017.02.07 17:52:08 5: SOMFY_TimedUpdate : pos so far : 6.28571428571427
2017.02.07 17:52:08 5: SOMFY_TimedUpdate : delta time : 0.84   new rounde pos (rounded): 0.285714285714271
2017.02.07 17:52:08 4: SOMFY_UpdateState: rollBuero enter with  newState:0:   updatestate:<undef>:   move:stop:
2017.02.07 17:52:08 4: SOMFY_UpdateState: rollBuero after conversions  newState:100:  rounded:100:  stateTrans:open:
2017.02.07 17:52:08 5: SOMFY_TimedUpdate DONE


und das dev log

Code Auswählen Erweitern

2017-02-07_17:47:04 rollBuero closed
2017-02-07_17:47:04 rollBuero position: 0
2017-02-07_17:47:04 rollBuero exact: 0.6
2017-02-07_17:47:04 rollBuero 40
2017-02-07_17:47:04 rollBuero position: 40
2017-02-07_17:47:04 rollBuero exact: 36.6142857142857
2017-02-07_17:47:07 rollBuero 60
2017-02-07_17:47:07 rollBuero position: 60
2017-02-07_17:47:07 rollBuero exact: 55.8357142857143
2017-02-07_17:47:10 rollBuero 80
2017-02-07_17:47:10 rollBuero position: 80
2017-02-07_17:47:10 rollBuero exact: 75.0571428571429
2017-02-07_17:47:13 rollBuero 90
2017-02-07_17:47:13 rollBuero position: 90
2017-02-07_17:47:13 rollBuero exact: 94.2785714285715
2017-02-07_17:47:14 rollBuero open
2017-02-07_17:47:14 rollBuero position: 100
2017-02-07_17:47:14 rollBuero exact: 100
2017-02-07_17:51:13 rollBuero open
2017-02-07_17:51:13 rollBuero position: 100
2017-02-07_17:51:13 rollBuero exact: 98.2
2017-02-07_17:51:13 rollBuero 50
2017-02-07_17:51:13 rollBuero position: 50
2017-02-07_17:51:13 rollBuero exact: 49.9461538461538
2017-02-07_17:51:16 rollBuero 30
2017-02-07_17:51:16 rollBuero position: 30
2017-02-07_17:51:16 rollBuero exact: 29.2461538461538
2017-02-07_17:51:19 rollBuero down
2017-02-07_17:51:19 rollBuero position: 10
2017-02-07_17:51:19 rollBuero exact: 8.85
2017-02-07_17:51:20 rollBuero closed
2017-02-07_17:51:20 rollBuero position: 0
2017-02-07_17:51:20 rollBuero exact: 0
2017-02-07_17:51:58 rollBuero closed
2017-02-07_17:51:58 rollBuero position: 0
2017-02-07_17:51:58 rollBuero exact: 0.6
2017-02-07_17:51:58 rollBuero 40
2017-02-07_17:51:58 rollBuero position: 40
2017-02-07_17:51:58 rollBuero exact: 36.6142857142857
2017-02-07_17:52:01 rollBuero 60
2017-02-07_17:52:01 rollBuero position: 60
2017-02-07_17:52:01 rollBuero exact: 55.8357142857143
2017-02-07_17:52:04 rollBuero 80
2017-02-07_17:52:04 rollBuero position: 80
2017-02-07_17:52:04 rollBuero exact: 75.1214285714286
2017-02-07_17:52:07 rollBuero 90
2017-02-07_17:52:07 rollBuero position: 90
2017-02-07_17:52:07 rollBuero exact: 94.3428571428572
2017-02-07_17:52:08 rollBuero open
2017-02-07_17:52:08 rollBuero position: 100
2017-02-07_17:52:08 rollBuero exact: 100

mal so geraten

Code Auswählen Erweitern

2017.02.07 17:47:13 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486486034.3744
soll das die zeit sein wann gestoptwerden soll in sekunden ?
das wären dann ca 17,5 tausend tage. Kalender stapel.

[viegener]

Dein Cul hat ein Problem:

Code Auswählen Erweitern


2017.02.07 17:51:52 1: usb create starting
2017.02.07 17:51:52 3: Probing CUL device /dev/ttyAMA0
2017.02.07 17:51:53 3: Probing TCM_ESP3 device /dev/ttyAMA0
2017.02.07 17:51:53 3: Probing FRM device /dev/ttyAMA0
2017.02.07 17:51:58 1: usb create end
2017.02.07 17:51:58 3: CUL_0: Unknown code YsAC2B00FC590000, help me!


Es sieht so aus, als ob der sich ständig ab und anmeldet.

[BlackStone]

Das scheint der nur zu machen wenn ich an die shutter ein Signal sende.
Der aktuell letzte Eintrag im log ist von gestern 19:51 .

Hm evtl ist das Netzteil ausgelaugt ,  mal schauen.

Tante edith: warum empfängt der cul sich selbst.  000059 hab ich im Modul für die def angelegt.  Das ist nen cul v3 mit a-cfw. Der sollte doch nur senden.
Und der fhemduino empfangen.
Gesendet von iPad mit Tapatalk

[BlackStone]

also ich habe die anderen usb anschlüsse freigemacht, war sowieso geplant.

und der cul meldet sich nicht mehr an und ab, also zuviel am saugen.

dennoch macht der das nicht, die zeit für den stop berechnet der scheinbar richtig, dennoch klappt das nicht, denn die zeit ist der logic nach zu lange in der laufzeit. denn die startzeit wird falsch gepunktet. ^^

habe mal nen screen drum gefaltet.  der erste ist nur eine normale fahrt, der zweitr mit 7.22222 sec ist auf pos 50.

[viegener]

also ich habe die anderen usb anschlüsse freigemacht, war sowieso geplant.

und der cul meldet sich nicht mehr an und ab, also zuviel am saugen.

dennoch macht der das nicht, die zeit für den stop berechnet der scheinbar richtig, dennoch klappt das nicht, denn die zeit ist der logic nach zu lange in der laufzeit. denn die startzeit wird falsch gepunktet. ^^

habe mal nen screen drum gefaltet.  der erste ist nur eine normale fahrt, der zweitr mit 7.22222 sec ist auf pos 50.

Die Startzeit ist völlig richtig und nicht "falsch gepunktet" die startzeit ist eine Uhrzeit/Timestamp (als Angabe in Sekunden). Das sind aber eigentlich beides komplett interne Werte für die Fahrzeit und nichts woraus man etwas ableiten könnte.

Das bringt so nichts, wenn der CUL jetzt tut, dann mach doch nochmal denselben Log wie oben schonmal angefordert.

Ich habe mir den Log von oben nochmals angeschaut und nach dem "pos 50" wird direkt ein kommando "off" verarbeitet? Gibt es da noch etwas was reinpfuscht? Hast DU nur den CUL oder auch andere Empfänger?

[viegener]

Die Startzeit ist völlig richtig und nicht "falsch gepunktet" die startzeit ist eine Uhrzeit/Timestamp (als Angabe in Sekunden). Das sind aber eigentlich beides komplett interne Werte für die Fahrzeit und nichts woraus man etwas ableiten könnte.

Das bringt so nichts, wenn der CUL jetzt tut, dann mach doch nochmal denselben Log wie oben schonmal angefordert.

Ich habe mir den Log von oben nochmals angeschaut und nach dem "pos 50" wird direkt ein kommando "off" verarbeitet? Gibt es da noch etwas was reinpfuscht? Hast DU nur den CUL oder auch andere Empfänger?

Noch konkreter: Kann es sein, dass DU einen fhemduino hast und die Adresse Deines Somfy-Devices in FHEM auch per FHEMDuino an den Somfy-Device gebunden hast? Oder setzt Du eine modifizierte SOMFY.PM ein ?

[BlackStone]

ich nutze einen fhemduino und einen cul v3, und die module sind alle aus dem svn also wird automatisch mit upgedatet und nix verändert an den dateien.
fhemduino:

Code Auswählen Erweitern

Internals:
   CMDS       VifdhtRq
   Clients    :IT:CUL_TX:OREGON:FHEMduino_Env:FHEMduino_EZ6:FHEMduino_Oregon:FHEMduino_PT2262:FHEMduino_FA20RF:FHEMduino_TCM:FHEMduino_HX:FHEMduino_DCF77:FHEMduino_Gas:FHEMduino_SomfyR:
   DEF        /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_AI02MI1J-if00-port0@9600
   DeviceName /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_AI02MI1J-if00-port0@9600
   FD         11
   FHEMduino_MSGCNT 93
   FHEMduino_TIME 2017-02-09 20:21:46
   NAME       FHEMduino
   NR         23
   PARTIAL
   RAWMSG     Ys af 40 011f 590000
   STATE      Initialized
   TYPE       FHEMduino
   VERSION    V 2.3v FHEMduino - compiled at Jun 28 215 2:31:02
   .clientArray:
     FHEMduino_PT2262
     FHEMduino_SomfyR
   Matchlist:
     10:FHEMduino_DCF77 D...............$
     11:OREGON  ^(3[8-9A-F]|[4-6][0-9A-F]|7[0-8]).*
     12:FHEMduino_Gas G...........$
     13:FHEMduino_SomfyR Ys .. .. .... ......$
     1:IT       ^i......$
     2:CUL_TX   ^TX..........
     3:FHEMduino_Env W.*$
     4:FHEMduino_EZ6 E...........$
     5:FHEMduino_Oregon OSV2:.*$
     6:FHEMduino_PT2262 IR.*$
     7:FHEMduino_FA20RF F............$
     8:FHEMduino_TCM M.....$
     9:FHEMduino_HX H...$
   Readings:
     2016-11-27 16:48:02   FAParms         No answer
     2017-02-09 18:17:41   cmds             V i f d h t R q
     2017-01-03 17:27:32   raw             isF000FFFFF0317
     2017-02-09 18:17:38   state           opened
     2016-11-27 17:12:00   uptime          0 00:00:24
Attributes:
   flashCommand avrdude -c arduino -b 57600 -P [PORT] -p atmega328p -vv -U flash:w:[HEXFILE] 2>[LOGFILE]
   verbose    1
CUL:

Code Auswählen Erweitern

nternals:
   CMDS       ABbCeFGhiKkLlMmNRTtUuVWXxYZ
   CUL_0_MSGCNT 1101
   CUL_0_TIME 2017-02-09 20:30:00
   Clients    :CUL_HM:HMS:CUL_IR:STACKABLE_CC:TSSTACKED:
   DEF        /dev/ttyACM0@9600 1034
   DeviceName /dev/ttyACM0@9600
   FD         10
   FHTID      1034
   NAME       CUL_0
   NR         21
   NR_CMD_LAST_H 3
   PARTIAL
   RAWMSG     A14D5845E2EAAC200000080C202000000000008D80237
   RSSI       -46.5
   STATE      Initialized
   TYPE       CUL
   VERSION    V 1.67 CUL868
   initString X21
Ar
   .clientArray:
     CUL_HM
   Matchlist:
     1:CUL_HM   ^A....................
     8:HMS      ^810e04....(1|5|9).a001
     D:CUL_IR   ^I............
     H:STACKABLE_CC ^\*
     M:TSSTACKED ^\*
   Readings:
     2017-02-09 18:17:38   cmds             A B b C e F G h i K k L l M m N R T t U u V W X x Y Z
     2017-02-09 20:30:00   state           Initialized
   XMIT_TIME:
     1486664632.48939
     1486664884.56104
     1486668214.43095
   Helper:
     30cbb9:
       QUEUE:
     363bb4:
       QUEUE:
Attributes:
   addvaltrigger 1
   hmId       2BC9D1
   rfmode     HomeMatic
   room       CUL_HM

Code Auswählen Erweitern

Internals:
   ADDRESS    63C3AE
   DEF        63C3AE
   FHEMduino_MSGCNT 4
   FHEMduino_RAWMSG Ys a9 20 0299 aec363
   FHEMduino_TIME 2017-02-09 20:14:56
   IODev      FHEMduino
   LASTInputDev FHEMduino
   MSGCNT     4
   NAME       fb_Roll_Buero
   NR         24
   STATE      20
   TYPE       FHEMduino_SomfyR
   lastMsg    Ys a9 20 0299 aec363
   Readings:
     2017-02-09 20:14:55   command         20
     2017-02-09 20:14:55   state           20
Attributes:
   IODev      FHEMduino
   rawDevice  000059
   room       FHEMduino_SomfyR

Code Auswählen Erweitern

Internals:
   .eventMapCmd Auf:noArg Zu:noArg Stop:noArg Sonne:noArg
   ADDRESS    000059
   DEF        000059
   IODev      CUL_0
   NAME       rollBuero
   NR         50
   STATE      closed
   TYPE       SOMFY
   move       stop
   Code:
     1          000059
   Readings:
     2017-02-09 20:21:45   enc_key         A0
     2017-02-09 20:22:00   exact           200
     2017-01-03 17:13:51   parsestate      on
     2017-02-09 20:22:00   position        200
     2017-02-09 20:21:45   rolling_code    0120
     2017-02-09 20:22:00   state           closed
Attributes:
   IODev      CUL_0
   devStateIcon open:fts_shutter_10 10:fts_shutter_10 20:fts_shutter_20 30:fts_shutter_30 40:fts_shutter_40 50:fts_shutter_50 60:fts_shutter_60 70:fts_shutter_70 80:fts_shutter_80 90:fts_shutter_90 down:fts_shutter_100 closed:fts_shutter_100
   drive-down-time-to-100 13
   drive-down-time-to-close 15
   drive-up-time-to-100 3
   drive-up-time-to-open 17
   eventMap   off:Auf on:Zu stop:Stop go-my:Sonne
   model      somfyshutter
   positionInverse 0
   room       Rolladen
   verbose    5
   webCmd     Auf: Stop: Zu:Sonne

Code Auswählen Erweitern

2017.02.09 20:21:16 4: SOMFY_set: rollBuero -> entering with mode :send: cmd :off:  arg1 ::  pos :200:
2017.02.09 20:21:16 4: SOMFY_set: handled command off --> move :off:  newState :200:
2017.02.09 20:21:16 5: SOMFY_set: handled for drive/udpate:  updateState :0:  drivet :0: updatet :17:
2017.02.09 20:21:16 4: SOMFY_UpdateState: rollBuero enter with  newState:200:   updatestate:0:   move:off:
2017.02.09 20:21:16 4: SOMFY_UpdateState: rollBuero after conversions  newState:200:  rounded:200:  stateTrans:closed:
2017.02.09 20:21:16 4: SOMFY_sendCommand: rollBuero -> cmd :off:
2017.02.09 20:21:16 5: SOMFY_sendCommand: rollBuero -> message :sAE20011E000059:
2017.02.09 20:21:16 4: SOMFY_set: rollBuero -> update state in 17 sec
2017.02.09 20:21:16 4: SOMFY_set: rollBuero -> entering with mode :virtual: cmd :off:  arg1 ::  pos :200:
2017.02.09 20:21:16 4: SOMFY_set: handled command off --> move :off:  newState :188:
2017.02.09 20:21:16 5: SOMFY_set: handled for drive/udpate:  updateState :0:  drivet :0: updatet :16.65:
2017.02.09 20:21:16 4: SOMFY_UpdateState: rollBuero enter with  newState:188:   updatestate:0:   move:off:
2017.02.09 20:21:16 4: SOMFY_UpdateState: rollBuero after conversions  newState:188:  rounded:150:  stateTrans:down:
2017.02.09 20:21:16 4: SOMFY_set: rollBuero -> update state in 16.65 sec
2017.02.09 20:21:17 1: usb create starting
2017.02.09 20:21:17 3: Probing CUL device /dev/ttyAMA0
2017.02.09 20:21:17 3: Probing TCM_ESP3 device /dev/ttyAMA0
2017.02.09 20:21:17 3: Probing FRM device /dev/ttyAMA0
2017.02.09 20:21:23 1: usb create end
2017.02.09 20:21:23 3: CUL_0: Unknown code YsAE24011E590000, help me!
2017.02.09 20:21:23 4: SOMFY_TimedUpdate
2017.02.09 20:21:23 5: SOMFY_TimedUpdate : pos so far : 188
2017.02.09 20:21:23 5: SOMFY_TimedUpdate : delta time : 6.78   new rounde pos (rounded): 70.4285714285714
2017.02.09 20:21:23 4: SOMFY_UpdateState: rollBuero enter with  newState:70.4285714285714:   updatestate:0:   move:off:
2017.02.09 20:21:23 4: SOMFY_UpdateState: rollBuero after conversions  newState:70.4285714285714:  rounded:70:  stateTrans:70:
2017.02.09 20:21:23 4: SOMFY_TimedUpdate: rollBuero -> update state in 9.87 sec
2017.02.09 20:21:23 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486668086.23194
2017.02.09 20:21:23 5: SOMFY_TimedUpdate DONE
2017.02.09 20:21:26 4: SOMFY_TimedUpdate
2017.02.09 20:21:26 5: SOMFY_TimedUpdate : pos so far : 70.4285714285714
2017.02.09 20:21:26 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 49.0714285714286
2017.02.09 20:21:26 4: SOMFY_UpdateState: rollBuero enter with  newState:49.0714285714286:   updatestate:0:   move:off:
2017.02.09 20:21:26 4: SOMFY_UpdateState: rollBuero after conversions  newState:49.0714285714286:  rounded:50:  stateTrans:50:
2017.02.09 20:21:26 4: SOMFY_TimedUpdate: rollBuero -> update state in 6.88 sec
2017.02.09 20:21:26 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486668089.22582
2017.02.09 20:21:26 5: SOMFY_TimedUpdate DONE
2017.02.09 20:21:29 4: SOMFY_TimedUpdate
2017.02.09 20:21:29 5: SOMFY_TimedUpdate : pos so far : 49.0714285714286
2017.02.09 20:21:29 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 27.7142857142857
2017.02.09 20:21:29 4: SOMFY_UpdateState: rollBuero enter with  newState:27.7142857142857:   updatestate:0:   move:off:
2017.02.09 20:21:29 4: SOMFY_UpdateState: rollBuero after conversions  newState:27.7142857142857:  rounded:30:  stateTrans:30:
2017.02.09 20:21:29 4: SOMFY_TimedUpdate: rollBuero -> update state in 3.89 sec
2017.02.09 20:21:29 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486668092.21979
2017.02.09 20:21:29 5: SOMFY_TimedUpdate DONE
2017.02.09 20:21:32 4: SOMFY_TimedUpdate
2017.02.09 20:21:32 5: SOMFY_TimedUpdate : pos so far : 27.7142857142857
2017.02.09 20:21:32 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 6.35714285714284
2017.02.09 20:21:32 4: SOMFY_UpdateState: rollBuero enter with  newState:6.35714285714284:   updatestate:0:   move:off:
2017.02.09 20:21:32 4: SOMFY_UpdateState: rollBuero after conversions  newState:6.35714285714284:  rounded:10:  stateTrans:10:
2017.02.09 20:21:32 4: SOMFY_TimedUpdate: rollBuero -> update state in 0.899999999999997 sec
2017.02.09 20:21:32 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486668093.11372
2017.02.09 20:21:32 5: SOMFY_TimedUpdate DONE
2017.02.09 20:21:33 4: SOMFY_TimedUpdate
2017.02.09 20:21:33 5: SOMFY_TimedUpdate : pos so far : 6.35714285714284
2017.02.09 20:21:33 5: SOMFY_TimedUpdate : delta time : 0.89   new rounde pos (rounded): 0
2017.02.09 20:21:33 4: SOMFY_UpdateState: rollBuero enter with  newState:0:   updatestate:<undef>:   move:stop:
2017.02.09 20:21:33 4: SOMFY_UpdateState: rollBuero after conversions  newState:0:  rounded:0:  stateTrans:open:
2017.02.09 20:21:33 5: SOMFY_TimedUpdate DONE
2017.02.09 20:21:45 4: SOMFY_set: rollBuero -> entering with mode :send: cmd :pos:  arg1 :50:  pos :0:
2017.02.09 20:21:45 4: SOMFY_set: handled command pos --> move :on:  newState :0:
2017.02.09 20:21:45 5: SOMFY_set: handled for drive/udpate:  updateState :50:  drivet :6.5: updatet :0:
2017.02.09 20:21:45 4: SOMFY_UpdateState: rollBuero enter with  newState:0:   updatestate:50:   move:on:
2017.02.09 20:21:45 4: SOMFY_UpdateState: rollBuero after conversions  newState:0:  rounded:0:  stateTrans:open:
2017.02.09 20:21:45 4: SOMFY_sendCommand: rollBuero -> cmd :on:
2017.02.09 20:21:45 5: SOMFY_sendCommand: rollBuero -> message :sAF40011F000059:
2017.02.09 20:21:45 4: SOMFY_set: rollBuero -> stopping in 6.5 sec
2017.02.09 20:21:45 4: SOMFY_set: rollBuero -> entering with mode :virtual: cmd :on:  arg1 ::  pos :0:
2017.02.09 20:21:45 4: SOMFY_set: handled command on --> move :on:  newState :2:
2017.02.09 20:21:45 5: SOMFY_set: handled for drive/udpate:  updateState :200:  drivet :0: updatet :14.79:
2017.02.09 20:21:45 4: SOMFY_UpdateState: rollBuero enter with  newState:2:   updatestate:200:   move:on:
2017.02.09 20:21:45 4: SOMFY_UpdateState: rollBuero after conversions  newState:2:  rounded:0:  stateTrans:open:
2017.02.09 20:21:45 4: SOMFY_set: rollBuero -> update state in 14.79 sec
2017.02.09 20:21:46 1: usb create starting
2017.02.09 20:21:47 3: Probing CUL device /dev/ttyAMA0
2017.02.09 20:21:47 3: Probing TCM_ESP3 device /dev/ttyAMA0
2017.02.09 20:21:47 3: Probing FRM device /dev/ttyAMA0
2017.02.09 20:21:52 1: usb create end
2017.02.09 20:21:52 3: CUL_0: Unknown code YsAF42011F590000, help me!
2017.02.09 20:21:52 4: SOMFY_TimedUpdate
2017.02.09 20:21:52 5: SOMFY_TimedUpdate : pos so far : 2
2017.02.09 20:21:52 5: SOMFY_TimedUpdate : delta time : 6.92   new rounde pos (rounded): 55.2307692307692
2017.02.09 20:21:52 4: SOMFY_UpdateState: rollBuero enter with  newState:55.2307692307692:   updatestate:200:   move:on:
2017.02.09 20:21:52 4: SOMFY_UpdateState: rollBuero after conversions  newState:55.2307692307692:  rounded:60:  stateTrans:60:
2017.02.09 20:21:52 4: SOMFY_TimedUpdate: rollBuero -> update state in 7.87 sec
2017.02.09 20:21:52 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486668115.76246
2017.02.09 20:21:52 5: SOMFY_TimedUpdate DONE
2017.02.09 20:21:55 4: SOMFY_TimedUpdate
2017.02.09 20:21:55 5: SOMFY_TimedUpdate : pos so far : 55.2307692307692
2017.02.09 20:21:55 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 78.2307692307692
2017.02.09 20:21:55 4: SOMFY_UpdateState: rollBuero enter with  newState:78.2307692307692:   updatestate:200:   move:on:
2017.02.09 20:21:55 4: SOMFY_UpdateState: rollBuero after conversions  newState:78.2307692307692:  rounded:80:  stateTrans:80:
2017.02.09 20:21:55 4: SOMFY_TimedUpdate: rollBuero -> update state in 4.88 sec
2017.02.09 20:21:55 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486668118.75676
2017.02.09 20:21:55 5: SOMFY_TimedUpdate DONE
2017.02.09 20:21:58 4: SOMFY_TimedUpdate
2017.02.09 20:21:58 5: SOMFY_TimedUpdate : pos so far : 78.2307692307692
2017.02.09 20:21:58 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 108
2017.02.09 20:21:58 4: SOMFY_UpdateState: rollBuero enter with  newState:108:   updatestate:200:   move:on:
2017.02.09 20:21:58 4: SOMFY_UpdateState: rollBuero after conversions  newState:108:  rounded:150:  stateTrans:down:
2017.02.09 20:21:58 4: SOMFY_TimedUpdate: rollBuero -> update state in 1.89 sec
2017.02.09 20:21:58 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486668120.64087
2017.02.09 20:21:58 5: SOMFY_TimedUpdate DONE
2017.02.09 20:22:00 4: SOMFY_TimedUpdate
2017.02.09 20:22:00 5: SOMFY_TimedUpdate : pos so far : 108
2017.02.09 20:22:00 5: SOMFY_TimedUpdate : delta time : 1.88   new rounde pos (rounded): 200
2017.02.09 20:22:00 4: SOMFY_UpdateState: rollBuero enter with  newState:200:   updatestate:<undef>:   move:stop:
2017.02.09 20:22:00 4: SOMFY_UpdateState: rollBuero after conversions  newState:200:  rounded:200:  stateTrans:closed:
2017.02.09 20:22:00 5: SOMFY_TimedUpdate DONE

[BlackStone]

Der übersicht wegen mal extra post.

mir ist grade was komisches aufgefallen,

der cul arbeitet sauber, keine probleme mit der Fußbodenheizung. den homatic wandthermostaten usw.

nur im log tauch genau für die somfys ne reihe iodev fehler auf.

Code Auswählen Erweitern


2017.02.09 21:13:25 1: Including ./log/fhem.save
2017.02.09 21:13:26 3: No I/O device found for rollBuero
2017.02.09 21:13:26 3: No I/O device found for rollHauptbad
2017.02.09 21:13:26 3: No I/O device found for rollEsszimmer
2017.02.09 21:13:26 3: No I/O device found for rollVorrat
2017.02.09 21:13:26 3: No I/O device found for rollWohnzimmer
2017.02.09 21:13:26 3: No I/O device found for rollKueche
2017.02.09 21:13:26 3: No I/O device found for rollGaesteBad
2017.02.09 21:13:26 3: No I/O device found for rollWohnzimmer_Tuer
2017.02.09 21:13:26 3: No I/O device found for rollSchlafz_links
2017.02.09 21:13:26 3: No I/O device found for rollSchlafz_rechts

für die fernbedinungsn habe ich immer ein fb_ davor so das es ausgeschlossensein kann das das vom fhemduino kommt.

[viegener]

@blockstone: In deinem log stehen weiter die selben Meldungen zum an/abmelden des culs - macht keine Sinn einen Fehler zu suchen, wenn der cul nicht sauber funktioniert:

Code Auswählen Erweitern


2017.02.09 20:21:45 4: SOMFY_set: rollBuero -> update state in 14.79 sec
2017.02.09 20:21:46 1: usb create starting
2017.02.09 20:21:47 3: Probing CUL device /dev/ttyAMA0
2017.02.09 20:21:47 3: Probing TCM_ESP3 device /dev/ttyAMA0
2017.02.09 20:21:47 3: Probing FRM device /dev/ttyAMA0
2017.02.09 20:21:52 1: usb create end
2017.02.09 20:21:52 3: CUL_0: Unknown code YsAF42011F590000, help me!


[BlackStone]

Warum taucht das nur beim Somfy auf und nur dann ?
hinzu kommt das dort kein abmelden vorhanden ist, dann wäre ein vermerk auf disabled oder so da.

Code Auswählen Erweitern

2017.02.09 22:35:48 4: SOMFY_UpdateState: rollBuero enter with  newState:178:   updatestate:0:   move:off:
2017.02.09 22:35:48 4: SOMFY_UpdateState: rollBuero after conversions  newState:178:  rounded:150:  stateTrans:down:
2017.02.09 22:35:48 4: SOMFY_set: rollBuero -> update state in 16.36 sec
2017.02.09 22:35:48 1: usb create starting
2017.02.09 22:35:49 3: Probing CUL device /dev/ttyAMA0
2017.02.09 22:35:49 3: Probing TCM_ESP3 device /dev/ttyAMA0
2017.02.09 22:35:49 3: Probing FRM device /dev/ttyAMA0
2017.02.09 22:35:54 1: usb create end
2017.02.09 22:35:54 3: CUL_0: Unknown code YsA1270121590000, help me!
2017.02.09 22:35:54 4: SOMFY_TimedUpdate
2017.02.09 22:35:54 5: SOMFY_TimedUpdate : pos so far : 178
2017.02.09 22:35:54 5: SOMFY_TimedUpdate : delta time : 6.51   new rounde pos (rounded): 70.2142857142857
2017.02.09 22:35:54 4: SOMFY_UpdateState: rollBuero enter with  newState:70.2142857142857:   updatestate:0:   move:off:
2017.02.09 22:35:54 4: SOMFY_UpdateState: rollBuero after conversions  newState:70.2142857142857:  rounded:70:  stateTrans:70:
2017.02.09 22:35:54 4: SOMFY_TimedUpdate: rollBuero -> update state in 9.85 sec
2017.02.09 22:35:54 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486676157.83429
2017.02.09 22:35:54 5: SOMFY_TimedUpdate DONE
2017.02.09 22:35:57 4: SOMFY_TimedUpdate
2017.02.09 22:35:57 5: SOMFY_TimedUpdate : pos so far : 70.2142857142857
2017.02.09 22:35:57 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 48.8571428571429
2017.02.09 22:35:57 4: SOMFY_UpdateState: rollBuero enter with  newState:48.8571428571429:   updatestate:0:   move:off:
2017.02.09 22:35:57 4: SOMFY_UpdateState: rollBuero after conversions  newState:48.8571428571429:  rounded:50:  stateTrans:50:
2017.02.09 22:35:57 4: SOMFY_TimedUpdate: rollBuero -> update state in 6.86 sec
2017.02.09 22:35:57 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486676160.82688
2017.02.09 22:35:57 5: SOMFY_TimedUpdate DONE
2017.02.09 22:36:00 4: SOMFY_TimedUpdate
2017.02.09 22:36:00 5: SOMFY_TimedUpdate : pos so far : 48.8571428571429
2017.02.09 22:36:00 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 27.5
2017.02.09 22:36:00 4: SOMFY_UpdateState: rollBuero enter with  newState:27.5:   updatestate:0:   move:off:
2017.02.09 22:36:00 4: SOMFY_UpdateState: rollBuero after conversions  newState:27.5:  rounded:30:  stateTrans:30:
2017.02.09 22:36:00 4: SOMFY_TimedUpdate: rollBuero -> update state in 3.87 sec
2017.02.09 22:36:00 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486676163.81953
2017.02.09 22:36:00 5: SOMFY_TimedUpdate DONE
2017.02.09 22:36:03 4: SOMFY_TimedUpdate
2017.02.09 22:36:03 5: SOMFY_TimedUpdate : pos so far : 27.5
2017.02.09 22:36:03 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 6.14285714285715
2017.02.09 22:36:03 4: SOMFY_UpdateState: rollBuero enter with  newState:6.14285714285715:   updatestate:0:   move:off:
2017.02.09 22:36:03 4: SOMFY_UpdateState: rollBuero after conversions  newState:6.14285714285715:  rounded:10:  stateTrans:10:
2017.02.09 22:36:03 4: SOMFY_TimedUpdate: rollBuero -> update state in 0.879999999999999 sec
2017.02.09 22:36:03 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486676164.69227
2017.02.09 22:36:03 5: SOMFY_TimedUpdate DONE
2017.02.09 22:36:04 4: SOMFY_TimedUpdate
2017.02.09 22:36:04 5: SOMFY_TimedUpdate : pos so far : 6.14285714285715
2017.02.09 22:36:04 5: SOMFY_TimedUpdate : delta time : 0.87   new rounde pos (rounded): 0
2017.02.09 22:36:04 4: SOMFY_UpdateState: rollBuero enter with  newState:0:   updatestate:<undef>:   move:stop:
2017.02.09 22:36:04 4: SOMFY_UpdateState: rollBuero after conversions  newState:0:  rounded:0:  stateTrans:open:
2017.02.09 22:36:04 5: SOMFY_TimedUpdate DONE
2017.02.09 22:36:24 4: SOMFY_set: rollBuero -> entering with mode :send: cmd :pos:  arg1 :50:  pos :0:
2017.02.09 22:36:24 4: SOMFY_set: handled command pos --> move :on:  newState :0:
2017.02.09 22:36:24 5: SOMFY_set: handled for drive/udpate:  updateState :50:  drivet :6.5: updatet :0:
2017.02.09 22:36:24 4: SOMFY_UpdateState: rollBuero enter with  newState:0:   updatestate:50:   move:on:
2017.02.09 22:36:24 4: SOMFY_UpdateState: rollBuero after conversions  newState:0:  rounded:0:  stateTrans:open:
2017.02.09 22:36:24 4: SOMFY_sendCommand: rollBuero -> cmd :on:
2017.02.09 22:36:24 5: SOMFY_sendCommand: rollBuero -> message :sA2400122000059:
2017.02.09 22:36:24 4: SOMFY_set: rollBuero -> stopping in 6.5 sec
2017.02.09 22:36:25 4: SOMFY_set: rollBuero -> entering with mode :virtual: cmd :on:  arg1 ::  pos :0:
2017.02.09 22:36:25 4: SOMFY_set: handled command on --> move :on:  newState :2:
2017.02.09 22:36:25 5: SOMFY_set: handled for drive/udpate:  updateState :200:  drivet :0: updatet :14.65:
2017.02.09 22:36:25 4: SOMFY_UpdateState: rollBuero enter with  newState:2:   updatestate:200:   move:on:
2017.02.09 22:36:25 4: SOMFY_UpdateState: rollBuero after conversions  newState:2:  rounded:0:  stateTrans:open:
2017.02.09 22:36:25 4: SOMFY_set: rollBuero -> update state in 14.65 sec
2017.02.09 22:36:25 1: usb create starting
2017.02.09 22:36:26 3: Probing CUL device /dev/ttyAMA0
2017.02.09 22:36:26 3: Probing TCM_ESP3 device /dev/ttyAMA0
2017.02.09 22:36:26 3: Probing FRM device /dev/ttyAMA0
2017.02.09 22:36:32 1: usb create end
2017.02.09 22:36:32 3: CUL_0: Unknown code YsA2410122590000, help me!
2017.02.09 22:36:32 4: SOMFY_TimedUpdate
2017.02.09 22:36:32 5: SOMFY_TimedUpdate : pos so far : 2
2017.02.09 22:36:32 5: SOMFY_TimedUpdate : delta time : 6.75   new rounde pos (rounded): 53.9230769230769
2017.02.09 22:36:32 4: SOMFY_UpdateState: rollBuero enter with  newState:53.9230769230769:   updatestate:200:   move:on:
2017.02.09 22:36:32 4: SOMFY_UpdateState: rollBuero after conversions  newState:53.9230769230769:  rounded:50:  stateTrans:50:
2017.02.09 22:36:32 4: SOMFY_TimedUpdate: rollBuero -> update state in 7.9 sec
2017.02.09 22:36:32 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486676195.02196
2017.02.09 22:36:32 5: SOMFY_TimedUpdate DONE
2017.02.09 22:36:35 4: SOMFY_TimedUpdate
2017.02.09 22:36:35 5: SOMFY_TimedUpdate : pos so far : 53.9230769230769
2017.02.09 22:36:35 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 76.9230769230769
2017.02.09 22:36:35 4: SOMFY_UpdateState: rollBuero enter with  newState:76.9230769230769:   updatestate:200:   move:on:
2017.02.09 22:36:35 4: SOMFY_UpdateState: rollBuero after conversions  newState:76.9230769230769:  rounded:80:  stateTrans:80:
2017.02.09 22:36:35 4: SOMFY_TimedUpdate: rollBuero -> update state in 4.91 sec
2017.02.09 22:36:35 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486676198.01448
2017.02.09 22:36:35 5: SOMFY_TimedUpdate DONE
2017.02.09 22:36:38 4: SOMFY_TimedUpdate
2017.02.09 22:36:38 5: SOMFY_TimedUpdate : pos so far : 76.9230769230769
2017.02.09 22:36:38 5: SOMFY_TimedUpdate : delta time : 2.99   new rounde pos (rounded): 99.9230769230769
2017.02.09 22:36:38 4: SOMFY_UpdateState: rollBuero enter with  newState:99.9230769230769:   updatestate:200:   move:on:
2017.02.09 22:36:38 4: SOMFY_UpdateState: rollBuero after conversions  newState:99.9230769230769:  rounded:100:  stateTrans:100:
2017.02.09 22:36:38 4: SOMFY_TimedUpdate: rollBuero -> update state in 1.92 sec
2017.02.09 22:36:38 5: SOMFY_TimedUpdate: rollBuero -> next time to stop: 1486676199.92704
2017.02.09 22:36:38 5: SOMFY_TimedUpdate DONE
2017.02.09 22:36:39 4: SOMFY_TimedUpdate
2017.02.09 22:36:39 5: SOMFY_TimedUpdate : pos so far : 99.9230769230769
2017.02.09 22:36:39 5: SOMFY_TimedUpdate : delta time : 1.91   new rounde pos (rounded): 195
2017.02.09 22:36:39 4: SOMFY_UpdateState: rollBuero enter with  newState:200:   updatestate:<undef>:   move:stop:
2017.02.09 22:36:39 4: SOMFY_UpdateState: rollBuero after conversions  newState:200:  rounded:200:  stateTrans:closed:
2017.02.09 22:36:39 5: SOMFY_TimedUpdate DONE
2017.02.09 22:44:29 3: CUL_HM set Therm_Buero_Climate desired-temp 30.0
2017.02.09 22:46:08 3: CUL_HM set Therm_Buero_Climate desired-temp 19.0

ich habe jetzt das ganze auf globan verbose 5 gezimmert, und knapp 20 minuten gewartet, es kam keine cul meldung, dann einmal beim somfy auf mygo /stop getriggert und bäng ist die cul meldung da.
man sieht auch das der cul sich nicht abgemeldet hat.

Code Auswählen Erweitern

2017.02.09 23:07:15 4: WEB_192.168.178.86_64626 POST /fhem?cmd.rollBuero=set%20rollBuero%20%20Stop&room=Rolladen&XHR=1&fw_id=508; BUFLEN:0
2017.02.09 23:07:15 5: Cmd: >set rollBuero  Stop<
2017.02.09 23:07:15 4: SOMFY_set: rollBuero -> entering with mode :send: cmd :stop:  arg1 ::  pos :200:
2017.02.09 23:07:15 4: SOMFY_set: handled command stop --> move :stop:  newState :200:
2017.02.09 23:07:15 5: SOMFY_set: handled for drive/udpate:  updateState ::  drivet :0: updatet :0:
2017.02.09 23:07:15 4: SOMFY_UpdateState: rollBuero enter with  newState:200:   updatestate:<undef>:   move:stop:
2017.02.09 23:07:15 4: SOMFY_UpdateState: rollBuero after conversions  newState:200:  rounded:200:  stateTrans:closed:
2017.02.09 23:07:15 4: SOMFY_sendCommand: rollBuero -> cmd :stop:
2017.02.09 23:07:15 4: SOMFY set rollBuero stop: sA3110123000059
2017.02.09 23:07:15 5: SOMFY_sendCommand: rollBuero -> message :sA3110123000059:
2017.02.09 23:07:15 5: CUL_0 sending YsA3110123000059
2017.02.09 23:07:15 4: CUL_send:  CUL_0Ys A3 11 0123 000059 
2017.02.09 23:07:15 4: name: /fhem?cmd.rollBuero=set%20rollBuero%20%20Stop&room=Rolladen&XHR=1&fw_id=508 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2017.02.09 23:07:16 4: FHEMduino_SomfyR - Roll_Buero found SOMFY device rollBuero sent command :stop:
2017.02.09 23:07:16 4: Roll_Buero: call setFn virtual in SOMFY   - YsA0B00000590000
2017.02.09 23:07:16 4: SOMFY_set: rollBuero -> entering with mode :virtual: cmd :stop:  arg1 ::  pos :200:
2017.02.09 23:07:16 4: SOMFY_set: handled command stop --> move :stop:  newState :200:
2017.02.09 23:07:16 5: SOMFY_set: handled for drive/udpate:  updateState ::  drivet :0: updatet :0:
2017.02.09 23:07:16 4: SOMFY_UpdateState: rollBuero enter with  newState:200:   updatestate:<undef>:   move:stop:
2017.02.09 23:07:16 4: SOMFY_UpdateState: rollBuero after conversions  newState:200:  rounded:200:  stateTrans:closed:
2017.02.09 23:07:16 5: Starting notify loop for Roll_Buero, 2 event(s), first is 11
2017.02.09 23:07:16 5: ZE.Activity: not on any display, ignoring notify
2017.02.09 23:07:16 5: ZE.Batterie: not on any display, ignoring notify
2017.02.09 23:07:16 5: End notify loop for Roll_Buero
2017.02.09 23:07:16 4: FHEMduino_SomfyR reject duplicate message :Ys a3 10 0123 590000:
2017.02.09 23:07:16 4: FHEMduino_SomfyR reject duplicate message :Ys a3 10 0123 590000:
2017.02.09 23:07:16 4: FHEMduino_SomfyR reject duplicate message :Ys a3 10 0123 590000:
2017.02.09 23:07:16 4: FHEMduino_SomfyR reject duplicate message :Ys a3 10 0123 590000:
2017.02.09 23:07:16 4: FHEMduino_SomfyR reject duplicate message :Ys a3 10 0123 590000:
2017.02.09 23:07:16 5: CUL/RAW: /YsA3140123590000

2017.02.09 23:07:16 4: CUL_Parse: CUL_0 Y sA 31 4012 359000 0
2017.02.09 23:07:16 5: CUL_0: dispatch YsA3140123590000
2017.02.09 23:07:16 5: Starting notify loop for CUL_0, 1 event(s), first is UNKNOWNCODE YsA3140123590000
2017.02.09 23:07:16 5: ZE.Activity: not on any display, ignoring notify
2017.02.09 23:07:16 5: ZE.Batterie: not on any display, ignoring notify
2017.02.09 23:07:16 5: Triggering initialUsbCheck
2017.02.09 23:07:16 4: initialUsbCheck exec usb create
2017.02.09 23:07:16 5: Cmd: >usb create<
2017.02.09 23:07:16 1: usb create starting
2017.02.09 23:07:17 4: ### ttyACM0: checking if it is a CUL
2017.02.09 23:07:17 4: ttyACM0 is already used by the fhem device CUL_0
2017.02.09 23:07:17 4: ### ttyAMA0: checking if it is a CUL
2017.02.09 23:07:17 3: Probing CUL device /dev/ttyAMA0
2017.02.09 23:07:17 5: SW: 0a
2017.02.09 23:07:17 5: SW: 560a
2017.02.09 23:07:17 4: got wrong answer for a CUL
2017.02.09 23:07:17 4: ### ttyAMA0: checking if it is a TCM_ESP3
2017.02.09 23:07:17 3: Probing TCM_ESP3 device /dev/ttyAMA0
2017.02.09 23:07:17 5: SW: 5500010005700838
2017.02.09 23:07:17 4: got wrong answer for a TCM_ESP3
2017.02.09 23:07:17 4: ### ttyAMA0: checking if it is a FRM
2017.02.09 23:07:17 3: Probing FRM device /dev/ttyAMA0
2017.02.09 23:07:17 5: SW: f9
2017.02.09 23:07:22 5: SW: f079f7
2017.02.09 23:07:22 4: got wrong answer for a FRM
2017.02.09 23:07:22 4: ### ttyUSB0: checking if it is a TCM_ESP3
2017.02.09 23:07:22 4: ttyUSB0 is already used by the fhem device FHEMduino
2017.02.09 23:07:22 1: usb create end
2017.02.09 23:07:22 5: End notify loop for CUL_0
2017.02.09 23:07:22 3: CUL_0: Unknown code YsA3140123590000, help me!
2017.02.09 23:07:22 4: WEB_192.168.178.86_64626 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2017-02.log; BUFLEN:

[viegener]

Ist schon ein komisches Problem, sieht auch ein bisschen so aus, als ob es mehrere Probleme sind. Ich versuche mal zu beschreiben was auffällig ist:

- Bei jedem Somfy-Befehl über den CUL startet ein USB-Check -> ich muss mal schauen was das triggert, aber normal ist das nicht und das System hängt dort wohl auch für ein paar Sekunden?
- Von irgendwo wird der Somfy-Device nach absetzen eines Kommandos wieder aufgerufen:
Hier kommt der Pos 50 Befehl

Code Auswählen Erweitern

22:36:24 4: SOMFY_set: rollBuero -> entering with mode :send: cmd :pos:  arg1 :50:  pos :0:
Dann kommt die Behandlung, Kommando wird geschickt und der Timer für 6.5 Sec wird gesetzt

Code Auswählen Erweitern

2017.02.09 22:36:24 4: SOMFY_set: rollBuero -> stopping in 6.5 sec
Damit ist der pos 50-Befehl erstmal durch (Timer läuft).

ABER: Direkt danach wird ein "on" (ohne pos beschränkung) aufgerufen und zwar virtuell - sprich ohne Sendekommandos (d.h. es kommt vielleicht von einem FHEMDuino ?)

Code Auswählen Erweitern

2017.02.09 22:36:25 4: SOMFY_set: rollBuero -> entering with mode :virtual: cmd :on:  arg1 ::  pos :0:
Das sorgt natürlich dafür dass der pos 50-Befehl überschrieben wird und der Rolladen bis in die Endposition läuft.

Ich kann nicht sagen, ob die beiden Problem zusammenhängen aber beides habe ich so noch nicht gesehen. Das USB-Problem erscheint mir erstmal schlimmer, da das ganze FHEM wohl für Sekunden steht und nicht klar ist, ob es mit dem 2. Problem zu tun hat!

[viegener]

- Bei jedem Somfy-Befehl über den CUL startet ein USB-Check -> ich muss mal schauen was das triggert, aber normal ist das nicht und das System hängt dort wohl auch für ein paar Sekunden?

Ich habe mal gerade ein wenig geschaut: Die usb...-Meldungen kommen wohl aus dem autocreate-Modul (normalerweise werden diese über den Befehl "usb create" getriggert). Autocreate wird aber normalerweise auch aus dem Empfang von unbekannten Devices getriggert. Vielleicht hängt das zusammen, auch wenn ich es nicht verstehe...

[BlackStone]

Schaut fast so aus s als ob der cul seine eigene Sendung empfangen würde .

Code Auswählen Erweitern

2017.02.09 23:07:22 3: CUL_0: Unknown code YsA3140123590000, help me![\code]
Denn 000059 ist die Adresse die ich im Somfy für den Büro shutter angelegt habe. Sitze direkt daneben deshalb teste ich meist daran.  Der raspi ist im HWR,  bessere funkausbreitung.

Bei den anderen reagiert es genauso,  nur mit den entsprechenden Adressen.

Gesendet von meinem E6853 mit Tapatalk



[BlackStone]

und nun kommt es, der fhemduino wird wohl vom somfymodule getriggert.
ich habe des nun auf die harte variante getestet, und abgezogen, nun stoppt der wie er soll.

jedoch warum sendet der fhemduino den somfycode zusätzlich, bringt ja eigendlich keinen sinn.