Dear Community,
I have been battling for some time to get my self-built nanocul working on my openhab 1.8.0 installation on a raspberry pi 2 with little success. I intend to use this to monitor the ELV FHT devices I have installed.
I am able to talk to the device via a terminal program (minicom) and the nanocul responds (e.g. SHIFT V returns nanocul version number “V 1.66 nanoCUL868”). However I receive the following error message in the debug logs
FHT binding:
2016-01-31 16:11:05 DEBUG o.o.b.f.internal.FHTActivator[:34]- FHT binding has been started.
2016-01-31 16:11:05 ERROR o.o.b.fht.internal.FHTBinding[:150]- Can’t open CUL
org.openhab.io.transport.cul.CULDeviceException: gnu.io.UnsupportedCommOperationException: Invalid Parameter
_ at org.openhab.io.transport.cul.internal.CULSerialHandlerImpl.openHardware(CULSerialHandlerImpl.java:273)_
_ at org.openhab.io.transport.cul.internal.AbstractCULHandler.open(AbstractCULHandler.java:154)_
_ at org.openhab.io.transport.cul.CULManager.createNewHandler(CULManager.java:170)_
_ at org.openhab.io.transport.cul.CULManager.getOpenCULHandler(CULManager.java:89)_
_ at org.openhab.binding.fht.internal.FHTBinding.bindCULHandler(FHTBinding.java:146)_
_ at org.openhab.binding.fht.internal.FHTBinding.setNewDeviceName(FHTBinding.java:172)_
Caused by: gnu.io.UnsupportedCommOperationException: Invalid Parameter
_ at gnu.io.RXTXPort.setSerialPortParams(RXTXPort.java:212)_
_ at org.openhab.io.transport.cul.internal.CULSerialHandlerImpl.openHardware(CULSerialHandlerImpl.java:258)_
_ at org.openhab.io.transport.cul.internal.AbstractCULHandler.open(AbstractCULHandler.java:154)_
_ at org.openhab.io.transport.cul.CULManager.createNewHandler(CULManager.java:170)_
_ at org.openhab.io.transport.cul.CULManager.getOpenCULHandler(CULManager.java:89)_
_ at org.openhab.binding.fht.internal.FHTBinding.bindCULHandler(FHTBinding.java:146)_
2016-01-31 16:11:05 ERROR o.o.b.fht.internal.FHTBinding[:159]- CUL device is not accessible
2016-01-31 16:12:05 ERROR o.o.b.fht.internal.FHTBinding[:159]- CUL device is not accessible
CUL binding:
2016-02-23 12:58:55 DEBUG o.o.i.t.cul.CULActivator[:35]- CUL transport has been started.
2016-02-23 12:58:56 DEBUG o.o.i.transport.cul.CULManager[:132]- Registering class org.openhab.io.transport.cul.internal.CULSerialHandlerImpl for device type serial
2016-02-23 12:58:56 DEBUG o.o.i.transport.cul.CULManager[:132]- Registering class org.openhab.io.transport.cul.internal.CULNetworkHandlerImpl for device type network
2016-02-23 12:58:57 DEBUG o.o.i.transport.cul.CULManager[:74]- Trying to open device serial:/dev/ttyUSB0 in mode SLOW_RF
2016-02-23 12:58:57 DEBUG o.o.i.transport.cul.CULManager[:140]- Searching class for device type serial
2016-02-23 12:58:57 INFO o.o.i.t.c.i.CULSerialHandlerImpl[:123]- Update config, baudrate = 38400
2016-02-23 12:58:57 INFO o.o.i.t.c.i.CULSerialHandlerImpl[:131]- Update config, parity = NONE (0)
2016-02-23 12:58:57 DEBUG o.o.i.t.c.i.CULSerialHandlerImpl[:234]- Opening serial CUL connection for /dev/ttyUSB0
I have the following configuration settings:
- nanocul is on a powered usb hub (have also tried locally mounted)
- cul and fht addons are 1.8.0 jar files with 555 permissions set (as other addons)
- I am using raspbian jessie
- nanocul is on ttyusb0
- I have added the line *-Dgnu.io.rxtx.SerialPorts=/dev/ttyUSB0 * to JAVA ARGS in the file /etc/init.d/openhab (I have also tried using the cul without this line)
- ttyUSB0 is locked when openhab is running
- java version is: "java version “1.8.0”, Java™ SE Runtime Environment (build 1.8.0-b132), Java HotSpot™ Client VM (build 25.0-b70, mixed mode)
- openhab account is a member of the dialout group (I also run a USB Z-Wave stick on ttyACM0 and that is working fine)
- The cul is setup in openhab.cfg as follows:
fht:device=serial:/dev/ttyUSB0
fht:baudrate=38400
fht:parity=0
fht:housecode=1223 - My openhab.log file:
2016-02-23 12:58:09.624 [INFO ] [.o.core.internal.CoreActivator] - openHAB runtime has been started (v1.8.0).
2016-02-23 12:58:16.611 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl] - mDNS service has been started
2016-02-23 12:58:17.259 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl] - Service Discovery initialization completed.
2016-02-23 12:58:24.432 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'default.sitemap’
2016-02-23 12:58:28.959 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'rrd4j.persist’
2016-02-23 12:58:29.124 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'exec.persist’
2016-02-23 12:58:29.169 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'myopenhab.persist’
2016-02-23 12:58:29.538 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'default.script’
2016-02-23 12:58:30.311 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'default.items’
2016-02-23 12:58:31.838 [INFO ] [penhab.io.rest.RESTApplication] - Started REST API at /rest
2016-02-23 12:58:37.374 [INFO ] [.o.u.w.i.servlet.WebAppServlet] - Started Classic UI at /classicui/openhab.app
2016-02-23 12:58:40.950 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'default.rules’
2016-02-23 12:58:43.532 [INFO ] [.service.AbstractActiveService] - FritzBox refresh Service has been started
2016-02-23 12:58:43.536 [INFO ] [o.b.f.internal.FritzboxBinding] - Fritzbox conditional deActivate: true
2016-02-23 12:58:43.558 [INFO ] [o.b.f.internal.FritzboxBinding] - Attempting connection to FritzBox on 192.168.178.1:1012…
2016-02-23 12:58:43.597 [INFO ] [o.b.f.internal.FritzboxBinding] - Connected to FritzBox on xxx.xxx.xxx.x:1012
2016-02-23 12:58:45.876 [INFO ] [.myopenhab.internal.MyOHClient] - Connected to my.openHAB service (UUID = 03b5e0d1-3c09-4fbf-9573-c86afe4d3492, local base URL = http://localhost:8080)
2016-02-23 12:58:47.283 [INFO ] [.o.io.habmin.HABminApplication] - Started HABmin REST API at /services/habmin
2016-02-23 12:58:52.632 [INFO ] [.service.AbstractActiveService] - NTP Refresh Service has been started
2016-02-23 12:58:54.433 [INFO ] [.p.rrd4j.internal.RRD4jService] - Removing invalid defintion component = null heartbeat = 0 min/max = 0.0/0.0 step = 0 0 archives(s) = [] 0 items(s) = []
2016-02-23 12:58:55.004 [INFO ] [.service.AbstractActiveService] - HTTP Refresh Service has been started
2016-02-23 12:58:55.245 [INFO ] [.service.AbstractActiveService] - Exec Refresh Service has been started
2016-02-23 12:58:55.450 [INFO ] [.z.internal.ZWaveActiveBinding] - Update config, port = /dev/ttyACM0
2016-02-23 12:58:55.453 [INFO ] [.z.internal.ZWaveActiveBinding] - Update config, setSUC = true
2016-02-23 12:58:55.456 [INFO ] [.z.internal.ZWaveActiveBinding] - Update config, softReset = false
2016-02-23 12:58:55.458 [INFO ] [.z.internal.ZWaveActiveBinding] - Update config, masterController = true
2016-02-23 12:58:55.463 [INFO ] [.service.AbstractActiveService] - ZWave Refresh Service has been started
2016-02-23 12:58:55.631 [INFO ] [b.z.i.protocol.ZWaveController] - Starting Z-Wave controller
2016-02-23 12:58:55.634 [INFO ] [b.z.i.protocol.ZWaveController] - Z-Wave timeout is set to 5000ms. Soft reset is false.
2016-02-23 12:58:55.636 [INFO ] [b.z.i.protocol.ZWaveController] - Connecting to serial port /dev/ttyACM0
2016-02-23 12:58:56.743 [INFO ] [b.z.i.protocol.ZWaveController] - Serial port is initialized
2016-02-23 12:58:57.066 [INFO ] [.service.AbstractActiveService] - NetworkHealth Refresh Service has been started
2016-02-23 12:58:57.277 [INFO ] [.service.AbstractActiveService] - FHT Refresh Service has been started
2016-02-23 12:58:57.279 [INFO ] [.p.internal.PanasonicTVBinding] - TV registered ‘lounge_tv’ with IP 'xxx.xxx.xxx.xx’
2016-02-23 12:59:00.206 [INFO ] [rialApiGetInitDataMessageClass] - NODE 1: Node found
2016-02-23 12:59:02.467 [INFO ] [rialApiGetInitDataMessageClass] - NODE 6: Node found
2016-02-23 12:59:02.468 [INFO ] [rialApiGetInitDataMessageClass] - NODE 7: Node found
2016-02-23 12:59:02.472 [INFO ] [rialApiGetInitDataMessageClass] - ZWave Controller using Controller API
2016-02-23 12:59:02.474 [INFO ] [rialApiGetInitDataMessageClass] - ZWave Controller is Primary Controller
2016-02-23 12:59:02.476 [INFO ] [rialApiGetInitDataMessageClass] - ------------Number of Nodes Found Registered to ZWave Controller------------
2016-02-23 12:59:02.478 [INFO ] [rialApiGetInitDataMessageClass] - # Nodes = 3
2016-02-23 12:59:02.480 [INFO ] [rialApiGetInitDataMessageClass] - ----------------------------------------------------------------------------
2016-02-23 12:59:04.592 [WARN ] [i.p.s.IsFailedNodeMessageClass] - NODE 6: Is currently marked as failed by the controller!
2016-02-23 13:00:02.558 [ERROR] [.myopenhab.internal.MyOHClient] - Socket.IO error: io.socket.engineio.client.EngineIOException: websocket error
2016-02-23 13:00:02.574 [INFO ] [.myopenhab.internal.MyOHClient] - Disconnected from my.openHAB service (UUID = 03b5e0d1-3c09-4fbf-9573-c86afe4d3492, local base URL = http://localhost:8080)
2016-02-23 13:00:21.850 [INFO ] [.myopenhab.internal.MyOHClient] - Connected to my.openHAB service (UUID = 03b5e0d1-3c09-4fbf-9573-c86afe4d3492, local base URL = http://localhost:8080)
I would be very grateful if someone could give me some assistance here as I would be tearing my hair out (if I had any)
br
vbevan