Problem with Comfoair Binding

Hi,
I followed the instructions given regarding the ComfoAir Binding. For whatever reason the respecitve sitemap reported measurement values from the Comfoair device for a few hours. Since then, I do not receive any values any more.

The Comfoair is connected to my Raspberry Pi via USBtoSerial on /dev/ttyUSB0.

I have no idea how to solve that problem. Could anyone provide support which steps to go in order to identify the problem?

Thank you so much!
Michael

Is the device still on /dev/ttyUSB0? If you unplug it and plug it back in while OH is still running it will show up on another device (e.g. /dev/ttyUSB1).

no, same device:
here is the result with Plugged in / Unplugged / Plugged in (all while OH2 is running):

   [22:40:18] openhabian@openHABianPi:~$ dmesg | grep tty
    [    0.000000] Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ea00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=PARTUUID=0a6e0447-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
    [    0.001241] console [tty1] enabled
    [    0.828179] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
    [    4.184387] usb 1-1.5: FTDI USB Serial Device converter now attached to ttyUSB0

[22:40:43] openhabian@openHABianPi:~$ dmesg | grep tty
[    0.000000] Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ea00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=PARTUUID=0a6e0447-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[    0.001241] console [tty1] enabled
[    0.828179] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
[    4.184387] usb 1-1.5: FTDI USB Serial Device converter now attached to ttyUSB0
[33958.512861] ftdi_sio ttyUSB0: FTDI USB Serial Device converter now disconnected from ttyUSB0

[22:44:01] openhabian@openHABianPi:~$ dmesg | grep tty
[    0.000000] Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ea00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=PARTUUID=0a6e0447-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[    0.001241] console [tty1] enabled
[    0.828179] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
[    4.184387] usb 1-1.5: FTDI USB Serial Device converter now attached to ttyUSB0
[33958.512861] ftdi_sio ttyUSB0: FTDI USB Serial Device converter now disconnected from ttyUSB0
[34036.544859] usb 1-1.5: FTDI USB Serial Device converter now attached to ttyUSB0

Exchange of the USB-Serial converter with a different converter has not changed anything. Restart of the Comfoair has not changed anything.

Update, in the meantime I removed and installed the Comfoair binding again. Now, after 2hrs I finally got some (not all!) values, however the /var/log/openhab2/events.log file shows, that I received values only once, and thereafter nothing any more. And I got a huge log file with entries like that:

2017-07-12 14:43:23.126 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d1 00 7e 07 0f Response:  07 f3 07 f0 00 d2 09 56 53 55 57 58 0f 28 28 28 bc 07 0f 07 f3 07 f0 00 da 11 00 00 00 00 00 $
2017-07-12 14:43:24.339 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d1 00 7e 07 0f Response:  07 f3 07 f0 00 04 02 00 00 b3 07 0f 07 f3 07 f0 00 3c 0a 81 10 bf 3f 3f 5b 00 00 00 e2 fe 07 $
2017-07-12 14:43:25.654 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d1 00 7e 07 0f Response:  07 f3 07 f0 00 da 11 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 98 07 0f 07 f3 07 f0 $
2017-07-12 14:43:26.889 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d1 00 7e 07 0f Response:  07 f3 07 f0 00 aa 08 00 00 00 00 00 00 00 00 5f 07 0f 07 f3 07 f0 00 98 14 00 00 00 00 00 00 $
2017-07-12 14:43:28.222 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d1 00 7e 07 0f Response:  07 f3 07 f0 00 e6 07 ee 8d 38 5f ab 00 00 57 07 0f 07 f3 07 f0 00 ce 0e 1e 37 44 1e 37 44 44 $
2017-07-12 14:43:29.342 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d1 00 7e 07 0f Response:  07 f3 07 f0 00 e6 07 ee 8d 38 5f ab 00 00 57 07 0f 07 f3 07 f0 00 d2 09 56 53 55 57 58 0f 28 $
2017-07-12 14:43:30.662 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d9 00 86 07 0f Response:  07 f3 07 f0 00 04 02 00 00 b3 07 0f 07 f3 07 f0 00 3c 0a 81 10 bf 3f 3f 5b 00 00 00 e2 fe 07 $
2017-07-12 14:43:31.887 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d9 00 86 07 0f Response:  07 f3 07 f0 00 ca 08 00 1e 00 2d 1a 0a 1e 00 0c 07 0f 07 f3 07 f0 00 d2 09 56 53 55 57 58 0f $
2017-07-12 14:43:33.110 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d9 00 86 07 0f Response:  07 f3 07 f0 00 e2 06 00 00 00 00 00 03 98 07 0f 07 f3 07 f0 00 ce 0e 1e 37 44 1e 37 44 44 44 $
2017-07-12 14:43:34.336 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d9 00 86 07 0f Response:  07 f3 07 f0 00 e6 07 ee 8d 38 5f ab 00 00 57 07 0f 07 f3 07 f0 00 ec 08 07 07 17 00 0a 00 00 $
2017-07-12 14:43:35.665 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d9 00 86 07 0f Response:  07 f3 07 f0 00 3c 0a 81 10 bf 3f 3f 5b 00 00 00 e2 fe 07 0f 07 f3 07 f0 00 e6 07 ee 8d 38 5f $
2017-07-12 14:43:36.783 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d9 00 86 07 0f Response:  07 f3 07 f0 00 3c 0a 81 10 bf 3f 3f 5b 00 00 00 e2 fe 07 0f 07 f3 07 f0 00 e6 07 ee 8d 38 5f $
2017-07-12 14:43:37.897 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 0b 00 b8 07 0f Response:  07 f3 07 f0 00 da 11 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 98 07 0f 07 f3 07 f0 $
2017-07-12 14:43:38.203 [WARN ] [.comfoair.handling.ComfoAirConnector] - Unable to handle data. Data size not valid
2017-07-12 14:43:38.206 [WARN ] [.comfoair.handling.ComfoAirConnector] - skip CMD: 0c DATA:  00 0c 06 44 44 03 0e 02 e8 42 0f f3 f0 00 9c 01 03 4d
2017-07-12 14:43:39.209 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 0b 00 b8 07 0f Response:  07 f3 07 f0 00 0c 06 44 44 03 0e 02 e8 42 07 0f 07 f3 07 f0 00 9c 01 03 4d 07 0f
2017-07-12 14:43:40.427 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 0b 00 b8 07 0f Response:  07 f3 07 f0 00 e2 06 00 00 00 00 00 03 98 07 0f 07 f3 07 f0 00 d2 09 56 53 55 57 58 0f 28 28 $
2017-07-12 14:43:41.645 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 0b 00 b8 07 0f Response:  07 f3 07 f0 00 aa 08 00 00 00 00 00 00 00 00 5f 07 0f 07 f3 07 f0 00 ec 08 07 07 17 00 0a 00 $
2017-07-12 14:43:42.761 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 0b 00 b8 07 0f Response:  07 f3 07 f0 00 e6 07 ee 8d 38 5f ab 00 00 57 07 0f 07 f3 07 f0 00 ce 0e 1e 37 44 1e 37 44 44 $
2017-07-12 14:43:44.074 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 0b 00 b8 07 0f Response:  07 f3 07 f0 00 04 02 00 00 b3 07 0f 07 f3 07 f0 00 3c 0a 81 10 bf 3f 3f 5b 00 00 00 e2 fe 07 $
2017-07-12 14:43:45.293 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 37 07 07 00 00 00 00 00 00 00 eb 07 0f Response:  07 f3 07 f0 00 98 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 $
2017-07-12 14:43:46.513 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 37 07 07 00 00 00 00 00 00 00 eb 07 0f Response:  07 f3 07 f0 00 e0 07 00 00 00 64 00 00 01 f9 07 0f 07 f3 07 f0 00 9e $
2017-07-12 14:43:47.627 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 37 07 07 00 00 00 00 00 00 00 eb 07 0f Response:  07 f3 07 f0 00 e6 07 ee 8d 38 5f ab 00 00 57 07 0f 07 f3 07 f0 00 e2 $
2017-07-12 14:43:48.837 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 37 07 07 00 00 00 00 00 00 00 eb 07 0f Response:  07 f3 07 f0 00 04 02 00 00 b3 07 0f 07 f3 07 f0 00 3c 0a 81 10 bf 3f $
2017-07-12 14:43:50.252 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 37 07 07 00 00 00 00 00 00 00 eb 07 0f Response:  07 f3 07 f0 00 ca 08 00 1e 00 2d 1a 0a 1e 00 0c 07 0f 07 f3 07 f0 00 $
2017-07-12 14:43:50.480 [ERROR] [b.core.service.AbstractActiveService] - Error while executing background thread ComfoAir Refresh Service
java.lang.ArrayIndexOutOfBoundsException: 50
        at org.openhab.binding.comfoair.handling.ComfoAirConnector.cleanupBlock(ComfoAirConnector.java:358)[222:org.openhab.binding.comfoair:1.10.0]
        at org.openhab.binding.comfoair.handling.ComfoAirConnector.sendCommand(ComfoAirConnector.java:216)[222:org.openhab.binding.comfoair:1.10.0]
        at org.openhab.binding.comfoair.internal.ComfoAirBinding.sendCommand(ComfoAirBinding.java:156)[222:org.openhab.binding.comfoair:1.10.0]
        at org.openhab.binding.comfoair.internal.ComfoAirBinding.execute(ComfoAirBinding.java:143)[222:org.openhab.binding.comfoair:1.10.0]
        at org.openhab.core.binding.AbstractActiveBinding$BindingActiveService.execute(AbstractActiveBinding.java:157)[176:org.openhab.core.compat1x:2.1.0]
        at org.openhab.core.service.AbstractActiveService$RefreshThread.run(AbstractActiveService.java:169)[176:org.openhab.core.compat1x:2.1.0]
2017-07-12 14:44:00.025 [ERROR] [ntime.internal.engine.ExecuteRuleJob] - Error during the execution of rule LĂĽfterstufe: org.eclipse.smarthome.core.library.types.DecimalType
2017-07-12 14:45:00.022 [ERROR] [ntime.internal.engine.ExecuteRuleJob] - Error during the execution of rule LĂĽfterstufe: org.eclipse.smarthome.core.library.types.DecimalType

Any idea what’s going on there?

If it’s still an issue: try disconnecting the CCEASE, it interferes with the RS232 and intercepts and/or manipulates the answers for the serial port.

@binderth I have a similar issue - we talked about it a while ago.
I get every value and can also set the target temperature. However for every minute (the cron job runs every minute in the airflow.rules) I get the same error [b.core.service.AbstractActiveService] as @meschmesch does.
I see also the same warnings but every information is provided as it should be (it takes seconds after startup to show everything, not hours as @meschmesch posted…)

@holger_hees do you probably have additional insights what might cause this error:

2017-10-10 13:47:14.358 [ERROR] [b.core.service.AbstractActiveService] - Error while executing background thread ComfoAir Refresh Service
java.lang.ArrayIndexOutOfBoundsException: 50
        at org.openhab.binding.comfoair.handling.ComfoAirConnector.cleanupBlock(ComfoAirConnector.java:358)[222:org.openhab.binding.comfoair:1.10.0]
        at org.openhab.binding.comfoair.handling.ComfoAirConnector.sendCommand(ComfoAirConnector.java:216)[222:org.openhab.binding.comfoair:1.10.0]
        at org.openhab.binding.comfoair.internal.ComfoAirBinding.sendCommand(ComfoAirBinding.java:156)[222:org.openhab.binding.comfoair:1.10.0]
        at org.openhab.binding.comfoair.internal.ComfoAirBinding.execute(ComfoAirBinding.java:143)[222:org.openhab.binding.comfoair:1.10.0]
        at org.openhab.core.binding.AbstractActiveBinding$BindingActiveService.execute(AbstractActiveBinding.java:157)[176:org.openhab.core.compat1x:2.1.0]
        at org.openhab.core.service.AbstractActiveService$RefreshThread.run(AbstractActiveService.java:169)[176:org.openhab.core.compat1x:2.1.0]

in combination with the unsuccessful request / response warnings:

2017-10-06 12:41:28.384 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d9 00 86 07 0f Response: null
2017-10-06 12:41:28.597 [WARN ] [.comfoair.handling.ComfoAirConnector] - Unable to handle data. Data size not valid
2017-10-06 12:41:28.601 [WARN ] [.comfoair.handling.ComfoAirConnector] - skip CMD: da DATA:  00 da 11 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 98 0f f3 f0 00 da 11 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 98
2017-10-06 12:41:29.604 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 d9 00 86 07 0f Response:  07 f3 07 f0 00 da 11 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 98 07 0f 07 f3 07 f0 00 da 11 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 98 07 0f
2017-10-06 12:41:30.913 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 0b 00 b8 07 0f Response: null
2017-10-06 12:41:31.120 [WARN ] [.comfoair.handling.ComfoAirConnector] - Unable to handle data. Data size not valid
2017-10-06 12:41:31.124 [WARN ] [.comfoair.handling.ComfoAirConnector] - skip CMD: 0c DATA:  00 0c 06 23 23 06 12 06 5a 7d 0f f3 f0 00 0c 06 23 23 05 fe 06 5c 6a
2017-10-06 12:41:32.132 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 0b 00 b8 07 0f Response:  07 f3 07 f0 00 0c 06 23 23 06 12 06 5a 7d 07 0f 07 f3 07 f0 00 0c 06 23 23 05 fe 06 5c 6a 07 0f
2017-10-06 12:41:33.457 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 eb 00 98 07 0f Response: null
2017-10-06 12:41:33.667 [WARN ] [.comfoair.handling.ComfoAirConnector] - Unable to handle data. Data size not valid
2017-10-06 12:41:33.670 [WARN ] [.comfoair.handling.ComfoAirConnector] - skip CMD: ec DATA:  00 ec 08 04 15 00 0a 00 00 12 05 db 0f f3 f0 00 ec 08 04 15 00 0a 00 00 12 05 db
2017-10-06 12:41:34.675 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 eb 00 98 07 0f Response:  07 f3 07 f0 00 ec 08 04 15 00 0a 00 00 12 05 db 07 0f 07 f3 07 f0 00 ec 08 04 15 00 0a 00 00 12 05 db 07 0f
2017-10-06 12:41:35.996 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 37 07 07 00 00 00 00 00 00 00 eb 07 0f Response: null
2017-10-06 12:41:36.205 [WARN ] [.comfoair.handling.ComfoAirConnector] - Unable to handle data. Data size not valid
2017-10-06 12:41:36.209 [WARN ] [.comfoair.handling.ComfoAirConnector] - skip CMD: 3c DATA:  00 3c 0a 81 10 bf 3f 3f 06 00 00 00 e0 a7 0f f3 f0 00 3c 0a 81 10 bf 3f 3f 06 00 00 00 e0 a7
2017-10-06 12:41:37.215 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 37 07 07 00 00 00 00 00 00 00 eb 07 0f Response:  07 f3 07 f0 00 3c 0a 81 10 bf 3f 3f 06 00 00 00 e0 a7 07 0f 07 f3 07 f0 00 3c 0a 81 10 bf 3f 3f 06 00 00 00 e0 a7 07 0f
2017-10-06 12:41:38.535 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 cd 00 7a 07 0f Response: null
2017-10-06 12:41:38.750 [WARN ] [.comfoair.handling.ComfoAirConnector] - Unable to handle data. Data size not valid
2017-10-06 12:41:38.758 [WARN ] [.comfoair.handling.ComfoAirConnector] - skip CMD: ce DATA:  00 ce 0e 0f 23 32 0f 23 32 23 23 02 01 46 46 00 00 26 0f f3 f0 00 ce 0e 0f 23 32 0f 23 32 23 23 02 01 46 46 00 00 26
2017-10-06 12:41:39.765 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 cd 00 7a 07 0f Response:  07 f3 07 f0 00 ce 0e 0f 23 32 0f 23 32 23 23 02 01 46 46 00 00 26 07 0f 07 f3 07 f0 00 ce 0e 0f 23 32 0f 23 32 23 23 02 01 46 46 00 00 26 07 0f
2017-10-06 12:41:41.081 [WARN ] [.comfoair.handling.ComfoAirConnector] - Retry cmd. Last call was not successful. Request:  07 f0 00 dd 00 8a 07 0f Response: null

It looks like everything works as expected however these warnings and the following error every minute sure flood the log files for a reason…and CCEASE is disconnected here.

Bernd

From discussions with other ComfoAir binding users, I can tell, that it depends an the CCEASE disconnected and with the type of connection cable you are using. I don’t have any such issues with my RS232-to-USB cable using the “Prolific” chipset, and others also report that one’s working without a hassle:

Try one of the cables with a PL-2303RA chip!

I finally got it up and running :fireworks:with no errors using the following setup:

  • Zehnder Comfoair 350 with attached Pi Zero W (Jessie Lite) at serial port of Comfoair
  • Disconnected CCEASE control unit
  • Micro USB to USB Adapter directly attached to the Pi Zero W port, using NO USB hub
  • Changing kernel parameter of Pi Zero W to USB 1.1 and some other dwc_otg parameters
  • FDDI based USB to serial cable (it works as well as the Prolific based cables)
  • Running Ser2net as service on the Pi Zero W attached to the Serial Port of the Comfoair with the following parameter:
 portnumber:raw:0:/dev/ttyUSB0:9600,8DATABITS,NONE,1STOPBIT
  • Sending Comfoair data via Wifi to openhabian Pi running Socat as service to receive the ser2net data:
OPTIONS="pty,link=/dev/comfoair,raw,echo=0,user=openhab,group=dialout,mode=777 tcp:ipaddressofpizero:portnumber"

I had errors every minute although the temperature values came correct (as you can see in the above posts) and tried to figure out the root cause for this. Similar setups worked well for other users here (either connected to NAS or directly attached to Pi’s). I finally searched for the issue in the HW of the sending Pi Zero again and replaced the attached USB hub (3x USB, 1x Ethernet) with a regular USB adapter cable (currently OTG cable). It turned out that the USB hub must have caused I/O / Interrupt issues as soon as the Comfoair USB to serial cable was attached (either Prolific or FDDI cable). :thinking:

Without the USB hub ComfoAir data is now send smoothly and errorfree over Wifi to openHAB :smiley:

Special thanks to @holger_hees for the patience and analyzing debug logs as well as @mstehle and @binderth for discussing options and sharing their setup. :+1:

Cheers, Bernd

2 Likes

glad, it worked out so well! and nice to read your solution of updating openHAB!