FSR71NP-2x-230V does not finish learn-mode

freisei commented 5 months ago

Contact Details

freisei@freisei.de

What happened?

I tried to add my FSR71NP-2x-230V. Cleared all sensors and cleared config from FSR71NP-2x-230V.

Added device in enocean-instance.
Got into learn-mode (lower wheel on CCW-Position "1", mid-wheel on LRN, tried upper wheel on 0,2,5,10,15 because there is no documentation)
red LED goes on blinking (waiting for sensor)

Successfully paried other eltako-devices with enocean-instance, so adapter seems to work fine. Successfully paired a PTM210 with FSR71NP-2x-230V, so i think the devices are OK.

Adapter Version

0.9.3

JS-Controller Version

5.0.17

Node JS Version

v18.19.0

NPM Version

10.4.0

Operating System

Ubuntu

Relevant log output

when i click "Add Device" there is no entry in debug-log.

Anything else?

No response

Jey-Cee commented 5 months ago

These steps should work:

Add Device
Choose the channel 1 or 2 on the lower switch
Choose LRN on the mid switch

You can try it again. But i think the problem is the learn telegram from the FSR71 is not known. I need the full log from starting the teachin process in adapter until you have set your FSR71 in teachin mode.

freisei commented 5 months ago

i´ve tried this procedure several times.

as i said. there is nothing in the log!

root@iobroker:~# tail -f  /opt/iobroker/log/iobroker.current.log |grep enocean
2024-01-26 22:00:30.175  - info: enocean.0 (546522) ["/dev/ttyACM0","/dev/ttyUSB0","/dev/ttyUSB1","/dev/serial/by-id/usb-0658_0200-if00","/dev/serial/by-id/usb-EnOcean_GmbH_EnOcean_USB_300_DB_FT2PTPWD-if00-port0","/dev/serial/by-id/usb-FTDI_FT232R_USB_UART_AI04PE9C-if00-port0"]
2024-01-26 22:02:07.502  - debug: enocean.0 (546522) 55000707017ad508058467d40000ffffffff4d009b

freisei commented 5 months ago

again with "silly" messages

2024-01-26 22:03:51.214  - info: host.iobroker stopInstance system.adapter.enocean.0 (force=false, process=true)
2024-01-26 22:03:51.228  - info: enocean.0 (546522) Got terminate signal TERMINATE_YOURSELF
2024-01-26 22:03:51.257  - info: enocean.0 (546522) cleaned everything up...
2024-01-26 22:03:51.258  - info: enocean.0 (546522) terminating
2024-01-26 22:03:51.263  - info: enocean.0 (546522) Terminated (ADAPTER_REQUESTED_TERMINATION): Without reason
2024-01-26 22:03:51.276  - info: enocean.0 (546522) The serial port was closed.
2024-01-26 22:03:51.387  - info: host.iobroker stopInstance system.adapter.enocean.0 send kill signal
2024-01-26 22:03:51.774  - info: enocean.0 (546522) terminating
2024-01-26 22:03:52.083  - info: host.iobroker instance system.adapter.enocean.0 terminated with code 11 (ADAPTER_REQUESTED_TERMINATION)
2024-01-26 22:03:54.891  - info: host.iobroker instance system.adapter.enocean.0 started with pid 1011231
2024-01-26 22:03:57.910  - debug: enocean.0 (1011231) Redis Objects: Use Redis connection: 127.0.0.1:9001
2024-01-26 22:03:58.008  - debug: enocean.0 (1011231) Objects client ready ... initialize now
2024-01-26 22:03:58.011  - debug: enocean.0 (1011231) Objects create System PubSub Client
2024-01-26 22:03:58.013  - debug: enocean.0 (1011231) Objects create User PubSub Client
2024-01-26 22:03:58.071  - debug: enocean.0 (1011231) Objects client initialize lua scripts
2024-01-26 22:03:58.082  - debug: enocean.0 (1011231) Objects connected to redis: 127.0.0.1:9001
2024-01-26 22:03:58.087  - silly: enocean.0 (1011231) redis psubscribe cfg.o.system.user.*
2024-01-26 22:03:58.128  - silly: enocean.0 (1011231) redis psubscribe cfg.o.enum.*
2024-01-26 22:03:58.132  - silly: enocean.0 (1011231) objectDB connected
2024-01-26 22:03:58.136  - debug: enocean.0 (1011231) Redis States: Use Redis connection: 127.0.0.1:9000
2024-01-26 22:03:58.159  - debug: enocean.0 (1011231) States create System PubSub Client
2024-01-26 22:03:58.161  - debug: enocean.0 (1011231) States create User PubSub Client
2024-01-26 22:03:58.243  - debug: enocean.0 (1011231) States connected to redis: 127.0.0.1:9000
2024-01-26 22:03:58.246  - silly: enocean.0 (1011231) statesDB connected
2024-01-26 22:03:58.773  - info: enocean.0 (1011231) starting. Version 0.9.3 in /opt/iobroker/node_modules/iobroker.enocean, node: v18.19.0, js-controller: 5.0.17
2024-01-26 22:03:58.824  - silly: enocean.0 (1011231) States system redis pmessage system.adapter.enocean.0.logLevel/system.adapter.enocean.0.logLevel:{"val":"silly","ack":true,"ts":1706303038805,"q":0,"from":"system.adapter.enocean.0","lc":1706303038805}
2024-01-26 22:03:59.143  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.teachin:{"val":false,"ack":true,"ts":1706303039139,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706302930450}
2024-01-26 22:03:59.535  - debug: enocean.0 (1011231) Sent data: 5500010005700309
2024-01-26 22:03:59.546  - debug: enocean.0 (1011231) Sent data: 5500010005700a36
2024-01-26 22:03:59.739  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.repeater.level:{"val":1,"ack":true,"ts":1706303039737,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706186743580}
2024-01-26 22:03:59.753  - debug: enocean.0 (1011231) Sent data: 5500030005a60900013d
2024-01-26 22:03:59.760  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.repeater.mode:{"val":0,"ack":true,"ts":1706303039756,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706186743649}
2024-01-26 22:03:59.766  - debug: enocean.0 (1011231) Sent data: 55000100057025fb
2024-01-26 22:03:59.770  - debug: enocean.0 (1011231) Sent data: 5500030005a60900013d
2024-01-26 22:03:59.789  - debug: enocean.0 (1011231) Sent data: 5500010005700838
2024-01-26 22:03:59.805  - debug: enocean.0 (1011231) Sent data: 5500010006790612
2024-01-26 22:03:59.816  - info: enocean.0 (1011231) {"BaseID":"fff7f280","Frequency":"868.3 Mhz","Protocol":"ERP1","AppVersion":"2.15.0.0","ApiVersion":"2.6.9.0","ChipID":"0514200a","ChipVersion":"454f0103","AppDescription":"GATEWAYCTRL"}
2024-01-26 22:04:07.860  - silly: enocean.0 (1011231) States system redis pmessage io.messagebox.system.adapter.enocean.0/io.messagebox.system.adapter.enocean.0:{"command":"autodetect","message":{"teachin_method":"MSC","device":"fsr71np2x230v","mfr":"eltako"},"from":"system.adapter.admin.0","callback":{"message":{"teachin_method":"MSC","device":"fsr71np2x230v","mfr":"eltako"},"id":17,"ack":false,"time":1706303047852},"_id":71254561}
2024-01-26 22:04:07.863  - silly: enocean.0 (1011231) sendTo "autodetect" to system.adapter.admin.0 from system.adapter.enocean.0
2024-01-26 22:04:07.887  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.teachin:{"val":true,"ack":true,"ts":1706303047882,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303047882}
2024-01-26 22:04:31.629  - debug: enocean.0 (1011231) 55000a0701eba5af7cff0b0185148e8000ffffffff4a008b
2024-01-26 22:04:32.171  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.lastTelegram:{"val":"55000a0701eba5af7cff0b0185148e8000ffffffff4a008b","ack":true,"ts":1706303071692,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303071692}
2024-01-26 22:04:32.254  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.lastID:{"val":"0185148e","ack":true,"ts":1706303072231,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303072231}
2024-01-26 22:05:07.908  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.teachin:{"val":false,"ack":true,"ts":1706303107905,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303107905}

freisei commented 5 months ago

and the logs until timeout of the device.

2024-01-26 22:07:54.412  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.*.CMD/enocean.0.0506576f.CMD:{"val":0,"ack":false,"ts":1706303274398,"q":0,"c":"script.js.Kueche.Arbeitslicht","from":"system.adapter.javascript.0","user":"system.user.admin","lc":1706303274398}
2024-01-26 22:07:54.413  - debug: enocean.0 (1011231) state 0506576f.CMD changed: 0 (ack = false) state: {"val":0,"ack":false,"ts":1706303274398,"q":0,"c":"script.js.Kueche.Arbeitslicht","from":"system.adapter.javascript.0","user":"system.user.admin","lc":1706303274398}
2024-01-26 22:07:54.433  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.*.CMD/enocean.0.0506576f.CMD:{"val":0,"ack":true,"ts":1706303274428,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303274398}
2024-01-26 22:07:54.459  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.*.CMD/enocean.0.0506576f.CMD:{"val":0,"ack":true,"ts":1706303274455,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303274398}
2024-01-26 22:07:54.489  - debug: enocean.0 (1011231) Sent data: 55000a0701eba501000008fff7f28200000506576fff0046
2024-01-26 22:07:54.511  - debug: enocean.0 (1011231) 5500010002650000
2024-01-26 22:07:54.513  - debug: enocean.0 (1011231) Packet type 2 received: {"code":"OK"}
2024-01-26 22:07:54.734  - debug: enocean.0 (1011231) 55000707017af6500506576f3000ffffffff560032
2024-01-26 22:07:55.062  - debug: enocean.0 (1011231) Message for ID 0506576f has been received. It was repeated 0 times.
2024-01-26 22:07:55.075  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.lastTelegram:{"val":"5500010002650000","ack":true,"ts":1706303275064,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303275064}
2024-01-26 22:07:55.076  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.lastTelegram:{"val":"55000707017af6500506576f3000ffffffff560032","ack":true,"ts":1706303275066,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303275066}
2024-01-26 22:07:55.131  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.lastID:{"val":"0506576f","ack":true,"ts":1706303275127,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303275127}
2024-01-26 22:08:05.126  - debug: enocean.0 (1011231) 55000707017af6700509f9983000ffffffff530077
2024-01-26 22:08:05.193  - debug: enocean.0 (1011231) Message for ID 0509f998 has been received. It was repeated 0 times.
2024-01-26 22:08:05.204  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.lastTelegram:{"val":"55000707017af6700509f9983000ffffffff530077","ack":true,"ts":1706303285201,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303285201}
2024-01-26 22:08:05.209  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.lastID:{"val":"0509f998","ack":true,"ts":1706303285202,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303285202}
2024-01-26 22:08:08.295  - info: javascript.0 (8906) script.js.Kueche.Arbeitslicht: setForeignState(id=enocean.0.0506576f.CMD, state={"val":1,"ack":false,"ts":1706303288295,"q":0,"from":"system.adapter.javascript.0","lc":1706303288295,"c":"script.js.Kueche.Arbeitslicht"})
2024-01-26 22:08:08.360  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.*.CMD/enocean.0.0506576f.CMD:{"val":1,"ack":false,"ts":1706303288295,"q":0,"c":"script.js.Kueche.Arbeitslicht","from":"system.adapter.javascript.0","user":"system.user.admin","lc":1706303288295}
2024-01-26 22:08:08.361  - debug: enocean.0 (1011231) state 0506576f.CMD changed: 1 (ack = false) state: {"val":1,"ack":false,"ts":1706303288295,"q":0,"c":"script.js.Kueche.Arbeitslicht","from":"system.adapter.javascript.0","user":"system.user.admin","lc":1706303288295}
2024-01-26 22:08:08.732  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.*.CMD/enocean.0.0506576f.CMD:{"val":1,"ack":true,"ts":1706303288712,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303288295}
2024-01-26 22:08:08.803  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.*.CMD/enocean.0.0506576f.CMD:{"val":1,"ack":true,"ts":1706303288800,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303288295}
2024-01-26 22:08:08.843  - debug: enocean.0 (1011231) Sent data: 55000a0701eba501000009fff7f28200000506576fff00d2
2024-01-26 22:08:08.866  - debug: enocean.0 (1011231) 5500010002650000
2024-01-26 22:08:08.867  - debug: enocean.0 (1011231) Packet type 2 received: {"code":"OK"}
2024-01-26 22:08:08.923  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.lastTelegram:{"val":"5500010002650000","ack":true,"ts":1706303288920,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303288920}
2024-01-26 22:08:09.107  - debug: enocean.0 (1011231) 55000707017af6700506576f3000ffffffff5500f3
2024-01-26 22:08:09.372  - debug: enocean.0 (1011231) Message for ID 0506576f has been received. It was repeated 0 times.
2024-01-26 22:08:09.404  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.lastTelegram:{"val":"55000707017af6700506576f3000ffffffff5500f3","ack":true,"ts":1706303289401,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303289401}
2024-01-26 22:08:09.454  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.lastID:{"val":"0506576f","ack":true,"ts":1706303289451,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303289451}

Jey-Cee commented 5 months ago

The state gateway.teachin should be set to true for 60 seconds when the teachin procedure was started within the config. Is this happen?

freisei commented 5 months ago

yes

i can see this in 'objects' as well as in the logs:

2024-01-26 22:03:59.143  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.teachin:{"val":false,"ack":true,"ts":1706303039139,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706302930450}
2024-01-26 22:04:07.887  - silly: enocean.0 (1011231) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.teachin:{"val":true,"ack":true,"ts":1706303047882,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706303047882}

freisei commented 5 months ago

before there where only about 8 seconds. tried again: now 90 seconds.

2024-01-26 23:04:56.761  - silly: enocean.0 (1111002) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.teachin:{"val":true,"ack":true,"ts":1706306696724,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706306696724}
2024-01-26 23:06:23.537  - silly: enocean.0 (1111002) States user redis pmessage enocean.0.gateway.*/enocean.0.gateway.teachin:{"val":false,"ack":true,"ts":1706306781364,"q":0,"from":"system.adapter.enocean.0","user":"system.user.admin","lc":1706306781364}

Jey-Cee commented 5 months ago

Ok thanks for the information i will have a closer look to this topic tomorrow. I also orderd a fsr71np for testing.

Jey-Cee commented 5 months ago

I checked the telegrams from your log, seems that the actuator does not send an teachin telegram. This could mean it is possibly a very old device, it wasn't in teachin mode or out of communication range.

On the device is a label with production period like 01/24, can you tell me what is printed on yours?

freisei commented 5 months ago

its mounted very special, i cant unmount it to look at the backside. It was purcased in january 2018, so i think i must be produced in 2017.

Do you need this part for investigation? When i have to unmount, i´m thinking about replacing it by a shelly-device. If i do so, i can borrow and send it to you.

Jey-Cee commented 5 months ago

It would ve nice but with the info you gave me i can look when there was a device update and if this could be the problem.

Jey-Cee commented 5 months ago

So you dont need to unmount it

Jey-Cee commented 5 months ago

Hm in 2017 there where 2 revisions of the device, so it is not clear which revision you own. I will change the teachin process for this device in future version of the adapter.

For now you can control your device while simulate a ptm210 switch in iobroker, which you can find in x_virtual in manufacturer selection.

freisei commented 5 months ago

finally i replaced the device by a shelly. The production-date is 25/17 Thank you very much!

Jey-Cee / ioBroker.enocean