OH 1.8.3 nanoCUL MAX! Window-Contact not working

Hello,
I try to get a nanoCUL working with a MAX! Window-ContactBC-SC-Rd-WM-2 on a RasperryPi under Raspian Jessie. I have two nanoCULs, both working with Fhem unter Raspian Weezy.
Problem: I don’t get them working with openHAB 1.8.3 (or any other, tried also older ones and OH2)
I try to explain, what I did. So the text will be a little longer :wink:
Setting up Jessie to SD-Card, make it booting from USB-Stick, extracting openHAB_1.8.3 to “/opt/oh1”. Copied “org.openhab.binding.maxcul-1.9.0-SNAPSHOT.jar” and “org.openhab.io.transport.cul-1.9.0-SNAPSHOT.jar “ to /opt/oh1/addons
Created “openhab1.service” in /lib/systemd/system/ with user “pi”

[Unit]
Description=Starts and stops the openHAB Home Automation Bus
Documentation=http://www.openhab.org
Wants=network-online.target
After=network-online.target

[Service]
Type=simple
GuessMainPID=yes
User=pi
ExecStart=/opt/oh1/start.sh
ExecStop=kill -SIGINT $MAINPID
Restart=on-failure
WorkingDirectory=/opt/oh1

[Install]
WantedBy=multi-user.target

After bootup, the nanoCUL is not recognized by the Raspi. (“lsusb” does not show up the FT232 FTDI chip). After disconnecting and connecting it shows up as “ttyUSB0” or under /dev/serial/by-id/usb-FTDI_FT232R……….
So this might be the first problem ( see openhab.log 2016-06-08 01:10:33.402). How can I solve this, so the nanoCUL ist enumerated direct when booting up the Raspi???

Next:
I modified the /opt/oh1/configurations/logback.xml with:

${openhab.logdir:-logs}/maxcul.log


${openhab.logdir:-logs}/events-%d{yyyy-ww}.log.zip

30


%d{yyyy-MM-dd HH:mm:ss} - %msg%n


and:


Then I created a directory /opt/oh1/etc/maxcul where the pairing information should be stored by openHab, but is not on my Raspi.
I configurated openHab close to the tutorial from technpol:
heating.items:
Group All
Group HeatingElements (All)
Switch swPair “Pair” (HeatingElements) { maxcul=“PairMode” }
Switch swListen “Listen” (HeatingElements) { maxcul=“ListenMode” }

Number valveSetpoint “Valve Current Setpoint [%.1f °C]” (HeatingElements) { maxcul=“RadiatorThermostat:TBD” }
Switch valveBattery “Valve Battery” (HeatingElements) { maxcul=“RadiatorThermostat:TBD:feature=battery” }
Number valveTemp “Valve Current Temp [%.1f °C]” (HeatingElements) { maxcul=“RadiatorThermostat:TBD:feature=temperature” }
Number valvePosition “Valve Position [%.1f %%]” (HeatingElements) { maxcul=“RadiatorThermostat:TBD:feature=valvepos” }

Number wallSetpoint “Wall Current Setpoint [%.1f °C]” (HeatingElements) { maxcul=“WallThermostat:TBD” }
Switch wallBattery “Wall Battery Low” (HeatingElements) { maxcul=“WallThermostat:TBD:feature=battery” }
Number wallTemp “Wall Current Temp [%.1f °C]” (HeatingElements) { maxcul=“WallThermostat:TBD:feature=temperature” }
Switch wallDisplay “Wall Display Setting” (HeatingElements) { maxcul=“WallThermostat:TBD:feature=displaySetting” }

Sitemap.default:
sitemap heating label=“Home Heating” {
Frame {
Group item=HeatingElements label=“Heating” icon="none"
Setpoint item=valveSetpoint icon=“temperature” minValue=5 maxValue=30 step=0.5
Setpoint item=wallSetpoint icon=“temperature” minValue=5 maxValue=30 step=0.5
}
}

I modified some right for groups:
OH is running as user pi.

“cat /etc/passwd | grep pi” returns:
pi:x:1000:1000:,:/home/pi:/bin/bash

“cat /etc/group | grep pi” returns:
adm:x:4:pi
tty:x:5:pi
dialout:x:20:pi
cdrom:x:24:pi
sudo:x:27:pi
audio:x:29:pi,pulse
video:x:44:pi
plugdev:x:46:pi
games:x:60:pi
users:x:100:pi
input:x:101:pi
netdev:x:108:pi
pi:x:1000:
spi:x:999:pi
i2c:x:998:pi
gpio:x:997:pi

“ls -l /dev/ttyUSB0” returns:
crw-rw---- 1 root dialout 188, 0 Jun 8 01:14 /dev/ttyUSB0

So I think, openHab has the right to work with the CUL, right?

I klick the “pair switch” in openHab and press the pairing button on the window-contact. But nothing happens. No entry in the “maxcul” directory, no entry of pairing info or errors in the logfiles.
maxcul.log:
2016-06-08 01:10:31 - MaxCul binding has been started.
2016-06-08 01:10:31 - Processing item swPair
2016-06-08 01:10:31 - Pair Mode switch found
2016-06-08 01:10:31 - Processing item swListen
2016-06-08 01:10:31 - Listen Mode switch found
2016-06-08 01:10:31 - Processing item valveSetpoint
2016-06-08 01:10:31 - Found real device
2016-06-08 01:10:31 - Part 0/1 -> RadiatorThermostat
2016-06-08 01:10:31 - Part 1/1 -> TBD
2016-06-08 01:10:31 - Unable to locate information for RADIATOR_THERMOSTAT TBD it may not yet be paired
2016-06-08 01:10:31 - Processing item valveBattery
2016-06-08 01:10:31 - Found real device
2016-06-08 01:10:31 - Part 0/2 -> RadiatorThermostat
2016-06-08 01:10:31 - Part 1/2 -> TBD
2016-06-08 01:10:31 - Part 2/2 -> feature=battery
trunced here…
2016-06-08 01:10:32 - Part 0/2 -> WallThermostat
2016-06-08 01:10:32 - Part 1/2 -> TBD
2016-06-08 01:10:32 - Part 2/2 -> feature=displaySetting
2016-06-08 01:10:32 - Unable to locate information for WALL_THERMOSTAT TBD it may not yet be paired
2016-06-08 01:10:32 - Activating MaxCul binding
2016-06-08 01:10:32 - MaxCUL Reading config
2016-06-08 01:18:49 - Received command ON for item swPair
2016-06-08 01:18:49 - Found config for swPair
2016-06-08 01:18:49 - swPair pairMode enabled & timeout scheduled
2016-06-08 01:19:49 - swPair pairMode timeout executed

Openhab.log:
2016-06-08 01:08:12.934 [INFO ] [.o.core.internal.CoreActivator] - openHAB runtime has been started (v1.8.3).
2016-06-08 01:09:10.464 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl] - mDNS service has been started
2016-06-08 01:09:21.553 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl] - Service Discovery initialization completed.
2016-06-08 01:09:21.596 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'heating.items’
2016-06-08 01:09:50.699 [INFO ] [c.internal.ModelRepositoryImpl] - Loading model 'default.sitemap’
2016-06-08 01:10:10.177 [INFO ] [penhab.io.rest.RESTApplication] - Started REST API at /rest
2016-06-08 01:10:16.660 [INFO ] [.o.u.w.i.servlet.WebAppServlet] - Started Classic UI at /classicui/openhab.app
2016-06-08 01:10:30.696 [INFO ] [.service.AbstractActiveService] - HTTP Refresh Service has been started
2016-06-08 01:10:30.955 [INFO ] [.service.AbstractActiveService] - NTP Refresh Service has been started
2016-06-08 01:10:31.399 [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-06-08 01:10:31.543 [DEBUG] [o.b.m.internal.MaxCulActivator] - MaxCul binding has been started.
2016-06-08 01:10:31.725 [DEBUG] [i.MaxCulGenericBindingProvider] - Processing item swPair
2016-06-08 01:10:31.778 [DEBUG] [.m.i.MaxCulBindingConfigParser] - Pair Mode switch found

2016-06-08 01:10:32.257 [DEBUG] [.m.i.MaxCulBindingConfigParser] - Part 2/2 -> feature=displaySetting
2016-06-08 01:10:32.267 [WARN ] [m.internal.MaxCulBindingConfig] - Unable to locate information for WALL_THERMOSTAT TBD it may not yet be paired
2016-06-08 01:10:32.669 [DEBUG] [.maxcul.internal.MaxCulBinding] - Activating MaxCul binding
2016-06-08 01:10:32.715 [DEBUG] [.maxcul.internal.MaxCulBinding] - MaxCUL Reading config
2016-06-08 01:10:33.402 [ERROR] [.o.i.t.cul.CULLifecycleManager] - Can’t open CUL
org.openhab.io.transport.cul.CULDeviceException: gnu.io.NoSuchPortException
at org.openhab.io.transport.cul.internal.serial.CULSerialHandlerImpl.openHardware(CULSerialHandlerImpl.java:100) ~[bundlefile:na]
at org.openhab.io.transport.cul.internal.AbstractCULHandler.open(AbstractCULHandler.java:139) ~[bundlefile:na]
at org.openhab.io.transport.cul.internal.CULManager.createNewHandler(CULManager.java:149) ~[bundlefile:na]
at org.openhab.io.transport.cul.internal.CULManager.getOpenCULHandler(CULManager.java:84) ~[bundlefile:na]
at org.openhab.io.transport.cul.CULLifecycleManager.open(CULLifecycleManager.java:85) [bundlefile:na]
at org.openhab.io.transport.cul.CULLifecycleManager.config(CULLifecycleManager.java:74) [bundlefile:na]
at org.openhab.binding.maxcul.internal.MaxCulBinding.updated(MaxCulBinding.java:265) [bundlefile:na]
at org.eclipse.equinox.internal.cm.ManagedServiceTracker$1.run(ManagedServiceTracker.java:183) [org.eclipse.equinox.cm_1.0.400.v20120522-1841.jar:na]
at org.eclipse.equinox.internal.cm.SerializedTaskQueue$1.run(SerializedTaskQueue.java:36) [org.eclipse.equinox.cm_1.0.400.v20120522-1841.jar:na]
Caused by: gnu.io.NoSuchPortException: null
at gnu.io.CommPortIdentifier.getPortIdentifier(CommPortIdentifier.java:273) ~[nrjavaserial-3.9.3.jar:na]
at org.openhab.io.transport.cul.internal.serial.CULSerialHandlerImpl.openHardware(CULSerialHandlerImpl.java:78) ~[bundlefile:na]
… 8 common frames omitted
2016-06-08 01:18:49.552 [DEBUG] [.maxcul.internal.MaxCulBinding] - Received command ON for item swPair
2016-06-08 01:18:49.561 [DEBUG] [.maxcul.internal.MaxCulBinding] - Found config for swPair
2016-06-08 01:18:49.577 [DEBUG] [.maxcul.internal.MaxCulBinding] - swPair pairMode enabled & timeout scheduled
2016-06-08 01:19:49.582 [DEBUG] [.maxcul.internal.MaxCulBinding] - swPair pairMode timeout executed

Ouput of dmesg (truncated, because of lots of mount errors, because of missing externals)

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Initializing cgroup subsys cpuacct
[ 0.000000] Linux version 4.4.11+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #888 Mon May 23 20:02:58 BST 2016
[ 0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
[ 0.000000] Machine model: Raspberry Pi Model B Plus Rev 1.2
[ 0.000000] cma: Reserved 8 MiB at 0x1b400000
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] On node 0 totalpages: 114688
[ 0.000000] free_area_init_node: node 0, pgdat c0880270, node_mem_map db010000
[ 0.000000] Normal zone: 1008 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 114688 pages, LIFO batch:31
[ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 113680
[ 0.000000] Kernel command line: dma.dmachans=0x7f35 bcm2708_fb.fbwidth=1680 bcm2708_fb.fbheight=1050 bcm2708.boardrev=0x10 bcm2708.serial=0xc6bcc799 smsc95xx.macaddr=B8:27:EB:BC:C7:99 bcm2708_fb.fbswap=1 bcm2708.uart_clock=3000000 bcm2708.disk_led_gpio=47 bcm2708.disk_led_active_low=0 vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000 dwc_otg.lpm_enable=0 console=ttyAMA0,115200 console=tty1 root=/dev/sda1 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet
[ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Memory: 436540K/458752K available (6051K kernel code, 436K rwdata, 1840K rodata, 380K init, 726K bss, 14020K reserved, 8192K cma-reserved)
[ 0.000000] Virtual kernel memory layout:
vector : 0xffff0000 - 0xffff1000 ( 4 kB)
fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
vmalloc : 0xdc800000 - 0xff800000 ( 560 MB)
lowmem : 0xc0000000 - 0xdc000000 ( 448 MB)
modules : 0xbf000000 - 0xc0000000 ( 16 MB)
.text : 0xc0008000 - 0xc07bcddc (7892 kB)
.init : 0xc07bd000 - 0xc081c000 ( 380 kB)
.data : 0xc081c000 - 0xc0889348 ( 437 kB)
.bss : 0xc0889348 - 0xc093eef0 ( 727 kB)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] NR_IRQS:16 nr_irqs:16 16
[ 0.000027] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[ 0.000074] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[ 0.000174] bcm2835: system timer (irq = 27)
[ 0.000481] Console: colour dummy device 80x30
[ 0.000729] console [tty1] enabled
[ 0.000760] Calibrating delay loop… 697.95 BogoMIPS (lpj=3489792)
[ 0.060312] pid_max: default: 32768 minimum: 301
[ 0.060688] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.060719] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.061704] Disabling cpuset control group subsystem
[ 0.061754] Initializing cgroup subsys io
[ 0.061791] Initializing cgroup subsys memory
[ 0.061848] Initializing cgroup subsys devices
[ 0.061882] Initializing cgroup subsys freezer
[ 0.061914] Initializing cgroup subsys net_cls
[ 0.062001] CPU: Testing write buffer coherency: ok
[ 0.062077] ftrace: allocating 20616 entries in 61 pages
[ 0.173445] Setting up static identity map for 0x81c0 - 0x81f8
[ 0.175318] devtmpfs: initialized
[ 0.183968] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
[ 0.184513] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.186266] pinctrl core: initialized pinctrl subsystem
[ 0.187128] NET: Registered protocol family 16
[ 0.192660] DMA: preallocated 4096 KiB pool for atomic coherent allocations
[ 0.201045] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
[ 0.201075] hw-breakpoint: maximum watchpoint size is 4 bytes.
[ 0.201247] Serial: AMBA PL011 UART driver
[ 0.201657] 20201000.uart: ttyAMA0 at MMIO 0x20201000 (irq = 81, base_baud = 0) is a PL011 rev2
[ 0.202035] console [ttyAMA0] enabled
[ 0.202790] bcm2835-mbox 2000b880.mailbox: mailbox enabled
[ 0.244946] bcm2835-dma 20007000.dma: DMA legacy API manager at f2007000, dmachans=0x1
[ 0.247183] SCSI subsystem initialized
[ 0.247530] usbcore: registered new interface driver usbfs
[ 0.247664] usbcore: registered new interface driver hub
[ 0.247871] usbcore: registered new device driver usb
[ 0.253363] raspberrypi-firmware soc:firmware: Attached to firmware from 2016-05-20 19:01
[ 0.281159] clocksource: Switched to clocksource timer
[ 0.332513] FS-Cache: Loaded
[ 0.332932] CacheFiles: Loaded
[ 0.352620] NET: Registered protocol family 2
[ 0.353959] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.354061] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.354164] TCP: Hash tables configured (established 4096 bind 4096)
[ 0.354267] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.354306] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.354669] NET: Registered protocol family 1
[ 0.355283] RPC: Registered named UNIX socket transport module.
[ 0.355311] RPC: Registered udp transport module.
[ 0.355324] RPC: Registered tcp transport module.
[ 0.355336] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.356665] hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available
[ 0.358077] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.374610] VFS: Disk quotas dquot_6.6.0
[ 0.374997] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.377813] FS-Cache: Netfs ‘nfs’ registered for caching
[ 0.379199] NFS: Registering the id_resolver key type
[ 0.379301] Key type id_resolver registered
[ 0.379320] Key type id_legacy registered
[ 0.383672] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[ 0.384094] io scheduler noop registered
[ 0.384134] io scheduler deadline registered (default)
[ 0.384554] io scheduler cfq registered
[ 0.387308] BCM2708FB: allocated DMA memory 5b800000
[ 0.387386] BCM2708FB: allocated DMA channel 0 @ f2007000
[ 0.411244] Console: switching to colour frame buffer device 210x65
[ 1.337214] bcm2835-rng 20104000.rng: hwrng registered
[ 1.337527] vc-cma: Videocore CMA driver
[ 1.337552] vc-cma: vc_cma_base = 0x00000000
[ 1.337565] vc-cma: vc_cma_size = 0x00000000 (0 MiB)
[ 1.337579] vc-cma: vc_cma_initial = 0x00000000 (0 MiB)
[ 1.338014] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB)
[ 1.364248] brd: module loaded
[ 1.377005] loop: module loaded
[ 1.378237] vchiq: vchiq_init_state: slot_zero = 0xdb880000, is_master = 0
[ 1.380707] Loading iSCSI transport class v2.0-870.
[ 1.382123] usbcore: registered new interface driver smsc95xx
[ 1.382238] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[ 1.582974] Core Release: 2.80a
[ 1.583013] Setting default values for core params
[ 1.583064] Finished setting default values for core params
[ 1.783442] Using Buffer DMA mode
[ 1.783471] Periodic Transfer Interrupt Enhancement - disabled
[ 1.783486] Multiprocessor Interrupt Enhancement - disabled
[ 1.783502] OTG VER PARAM: 0, OTG VER FLAG: 0
[ 1.783601] Dedicated Tx FIFOs mode
[ 1.784206] WARN::dwc_otg_hcd_init:1047: FIQ DMA bounce buffers: virt = 0xdb814000 dma = 0x5b814000 len=9024
[ 1.784248] FIQ FSM acceleration enabled for :
Non-periodic Split Transactions
Periodic Split Transactions
High-Speed Isochronous Endpoints
Interrupt/Control Split Transaction hack enabled
[ 1.784290] dwc_otg: Microframe scheduler enabled
[ 1.784375] WARN::hcd_init_fiq:413: FIQ on core 0 at 0xc0407c10
[ 1.784399] WARN::hcd_init_fiq:414: FIQ ASM at 0xc0407eec length 36
[ 1.784435] WARN::hcd_init_fiq:439: MPHI regs_base at 0xdc898000
[ 1.784513] dwc_otg 20980000.usb: DWC OTG Controller
[ 1.784591] dwc_otg 20980000.usb: new USB bus registered, assigned bus number 1
[ 1.784671] dwc_otg 20980000.usb: irq 56, io mem 0x00000000
[ 1.784737] Init: Port Power? op_state=1
[ 1.784751] Init: Power Port (0)
[ 1.785179] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 1.785209] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.785232] usb usb1: Product: DWC OTG Controller
[ 1.785251] usb usb1: Manufacturer: Linux 4.4.11+ dwc_otg_hcd
[ 1.785271] usb usb1: SerialNumber: 20980000.usb
[ 1.786514] hub 1-0:1.0: USB hub found
[ 1.786640] hub 1-0:1.0: 1 port detected
[ 1.787586] dwc_otg: FIQ enabled
[ 1.787609] dwc_otg: NAK holdoff enabled
[ 1.787622] dwc_otg: FIQ split-transaction FSM enabled
[ 1.787678] Module dwc_common_port init
[ 1.788235] usbcore: registered new interface driver usb-storage
[ 1.788769] mousedev: PS/2 mouse device common for all mice
[ 1.790007] bcm2835-cpufreq: min=700000 max=700000
[ 1.790415] sdhci: Secure Digital Host Controller Interface driver
[ 1.790438] sdhci: Copyright© Pierre Ossman
[ 1.790960] sdhost: log_buf @ db813000 (5b813000)
[ 1.871236] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[ 1.871834] sdhci-pltfm: SDHCI platform and OF driver helper
[ 1.872695] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.873027] hidraw: raw HID events driver © Jiri Kosina
[ 1.873372] usbcore: registered new interface driver usbhid
[ 1.873395] usbhid: USB HID core driver
[ 1.894739] Initializing XFRM netlink socket
[ 1.894819] NET: Registered protocol family 17
[ 1.895042] Key type dns_resolver registered
[ 1.897226] registered taskstats version 1
[ 1.897578] vc-sm: Videocore shared memory driver
[ 1.897608] [vc_sm_connected_init]: start
[ 1.898673] [vc_sm_connected_init]: end - returning 0
[ 1.899364] of_cfs_init
[ 1.899533] of_cfs_init: OK
[ 1.901017] Waiting for root device /dev/sda1…
[ 1.982138] mmc0: host does not support reading read-only switch, assuming write-enable
[ 1.986243] mmc0: new high speed SD card at address aaaa
[ 1.987328] mmcblk0: mmc0:aaaa SU02G 1.84 GiB
[ 1.989405] mmcblk0: p1
[ 1.991470] Indeed it is in host mode hprt0 = 00021501
[ 2.171232] usb 1-1: new high-speed USB device number 2 using dwc_otg
[ 2.171450] Indeed it is in host mode hprt0 = 00001101
[ 2.371710] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
[ 2.371765] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 2.373234] hub 1-1:1.0: USB hub found
[ 2.373416] hub 1-1:1.0: 5 ports detected
[ 2.651233] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[ 2.751685] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
[ 2.751720] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 2.754965] smsc95xx v1.0.4
[ 2.816355] smsc95xx 1-1.1:1.0 eth0: register ‘smsc95xx’ at usb-20980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:bc:c7:99
[ 2.911233] usb 1-1.2: new full-speed USB device number 4 using dwc_otg
[ 2.991247] usb 1-1.2: device descriptor read/64, error -32
[ 3.181234] usb 1-1.2: device descriptor read/64, error -32
[ 3.371314] usb 1-1.2: new full-speed USB device number 5 using dwc_otg
[ 3.451248] usb 1-1.2: device descriptor read/64, error -32
[ 3.641230] usb 1-1.2: device descriptor read/64, error -32
[ 3.831235] usb 1-1.2: new full-speed USB device number 6 using dwc_otg
[ 4.251295] usb 1-1.2: device not accepting address 6, error -32
[ 4.351241] usb 1-1.2: new full-speed USB device number 7 using dwc_otg
[ 4.771237] usb 1-1.2: device not accepting address 7, error -32
[ 4.777562] usb 1-1-port2: unable to enumerate USB device
[ 5.021321] usb 1-1.3: new high-speed USB device number 8 using dwc_otg
[ 5.122714] usb 1-1.3: New USB device found, idVendor=0781, idProduct=5581
[ 5.122749] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 5.122772] usb 1-1.3: Product: Ultra
[ 5.122791] usb 1-1.3: Manufacturer: SanDisk
[ 5.122812] usb 1-1.3: SerialNumber: 4C531001581119122551
[ 5.124220] usb-storage 1-1.3:1.0: USB Mass Storage device detected
[ 5.125079] scsi host0: usb-storage 1-1.3:1.0
[ 5.221246] usb 1-1.4: new low-speed USB device number 9 using dwc_otg
[ 5.343714] usb 1-1.4: New USB device found, idVendor=04f3, idProduct=0103
[ 5.343751] usb 1-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0
[ 5.343775] usb 1-1.4: Product: USB Keyboard
[ 5.366654] input: USB Keyboard as /devices/platform/soc/20980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/0003:04F3:0103.0001/input/input0
[ 5.368185] hid-generic 0003:04F3:0103.0001: input,hidraw0: USB HID v1.11 Keyboard [USB Keyboard] on usb-20980000.usb-1.4/input0
[ 5.398127] input: USB Keyboard as /devices/platform/soc/20980000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:04F3:0103.0002/input/input1
[ 5.398599] hid-generic 0003:04F3:0103.0002: input,hidraw1: USB HID v1.11 Device [USB Keyboard] on usb-20980000.usb-1.4/input1
[ 5.491242] usb 1-1.5: new low-speed USB device number 10 using dwc_otg
[ 5.607243] usb 1-1.5: New USB device found, idVendor=046d, idProduct=c00e
[ 5.607317] usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 5.607342] usb 1-1.5: Product: USB-PS/2 Optical Mouse
[ 5.607361] usb 1-1.5: Manufacturer: Logitech
[ 5.621534] input: Logitech USB-PS/2 Optical Mouse as /devices/platform/soc/20980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/0003:046D:C00E.0003/input/input2
[ 5.622373] hid-generic 0003:046D:C00E.0003: input,hidraw2: USB HID v1.10 Mouse [Logitech USB-PS/2 Optical Mouse] on usb-20980000.usb-1.5/input0
[ 6.123060] scsi 0:0:0:0: Direct-Access SanDisk Ultra 1.00 PQ: 0 ANSI: 6
[ 6.125017] sd 0:0:0:0: [sda] 60062500 512-byte logical blocks: (30.8 GB/28.6 GiB)
[ 6.126948] sd 0:0:0:0: [sda] Write Protect is off
[ 6.126999] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
[ 6.127848] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn’t support DPO or FUA
[ 6.139500] sda: sda1
[ 6.143413] sd 0:0:0:0: [sda] Attached SCSI removable disk
[ 6.248207] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
[ 6.248322] VFS: Mounted root (ext4 filesystem) readonly on device 8:1.
[ 6.249866] devtmpfs: mounted
[ 6.251312] Freeing unused kernel memory: 380K (c07bd000 - c081c000)
[ 6.514107] random: systemd urandom read with 112 bits of entropy available
[ 6.633901] NET: Registered protocol family 10
[ 6.869973] uart-pl011 20201000.uart: no DMA platform data
[ 6.913508] random: nonblocking pool is initialized
[ 7.528989] systemd[1]: Configuration file /lib/systemd/system/openhab1.service is marked executable. Please remove executable permission bits. Proceeding anyway.
[ 7.529074] systemd[1]: Configuration file /lib/systemd/system/openhab1.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway.
[ 7.531354] systemd[1]: [/lib/systemd/system/openhab1.service:12] Executable path is not absolute, ignoring: kill -SIGINT $MAINPID
[ 7.628462] systemd[1]: media-HausLogs.automount: Directory /media/HausLogs to mount over is not empty, mounting anyway.
[ 7.633341] systemd[1]: media-Backup.automount: Directory /media/Backup to mount over is not empty, mounting anyway.
[ 8.050352] fuse init (API version 7.23)
[ 8.092302] i2c /dev entries driver
[ 9.491693] systemd-udevd[122]: starting version 215
[ 11.550220] bcm2835-wdt 20100000.watchdog: Broadcom BCM2835 watchdog timer
[ 11.581487] gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000
[ 11.588495] EXT4-fs (sda1): re-mounted. Opts: (null)
[ 12.840133] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 14.609891] systemd-journald[118]: Received request to flush runtime journal from PID 1
[ 19.034841] cfg80211: World regulatory domain updated:
[ 19.034887] cfg80211: DFS Master region: unset
[ 19.034904] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[ 19.034929] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[ 19.034948] cfg80211: (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[ 19.034969] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
[ 19.034992] cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[ 19.035013] cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[ 19.035032] cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
[ 19.035052] cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[ 19.035070] cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[ 20.150992] Adding 102396k swap on /var/swap. Priority:-1 extents:1 across:102396k FS
[ 23.089834] FS-Cache: Netfs ‘cifs’ registered for caching
[ 23.093180] Key type cifs.spnego registered
[ 23.093288] Key type cifs.idmap registered
[ 510.417762] CIFS VFS: Error connecting to socket. Aborting operation.
[ 510.418237] CIFS VFS: cifs_mount failed w/return code = -113
[ 537.407063] usb 1-1.2: new full-speed USB device number 11 using dwc_otg
[ 537.533162] usb 1-1.2: New USB device found, idVendor=0403, idProduct=6001
[ 537.533204] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 537.533228] usb 1-1.2: Product: FT232R USB UART
[ 537.533249] usb 1-1.2: Manufacturer: FTDI
[ 537.533270] usb 1-1.2: SerialNumber: A600CH13
[ 538.701925] usbcore: registered new interface driver usbserial
[ 538.702124] usbcore: registered new interface driver usbserial_generic
[ 538.702304] usbserial: USB Serial support registered for generic
[ 538.742317] usbcore: registered new interface driver ftdi_sio
[ 538.742536] usbserial: USB Serial support registered for FTDI USB Serial Device
[ 538.743459] ftdi_sio 1-1.2:1.0: FTDI USB Serial Device converter detected
[ 538.743823] usb 1-1.2: Detected FT232RL
[ 538.759521] usb 1-1.2: FTDI USB Serial Device converter now attached to ttyUSB0

When I stop OH (sudo systemctl stop openhab1), Cutecom has access to the CUL. Sending a ”V” returns the Version, sending “X80” return letter ranging a to p.

I am out of ideas ?. Any help appreciated.
Is there any way to to more debugging? Can be checked, if openHab is sending the correct starting parameter to the CUL?

Can anybody load up a working Image of openHab with MAX! config and nanoCUL anywhere?

Ingo

Ingo,

I can’t help you with your particular issue.
However I’m using Max! Window Contact (and Eco Switches) with https://www.homegear.eu/index.php/Main_Page and Openhab without issues.

(in my case no CUL but a cheap CC1101 on a RPI Zero, should not make a difference)

How is the CC1101 connected to the RPI when not by a CUL?
Can you upload an image of your SD-card /USB-Stick somewhere?

The CC1101 is directly attached to the RPI
https://forum.homegear.eu/viewtopic.php?f=6&t=10#p130 (german)

The image you can find here: https://www.homegear.eu/index.php/Downloads

Thanks, will have look at it. But this will not clear my actual problem :wink:

I tried homegear with my nanoCUL, it’s working also with low bat message. Now I tried it direct with the CC1101 to the pi. I enabled SPI via the config utility. After that the CPU usage went 100 % and stayed. Even after reboot, the pi is not working.
How did you enable SPI? and what did you put in the homegear\families\max.conf file?
And, are you using Jessie or Weezy???
Thanks.

He, sorry was a away. You still have the issue with cc1101 and homegear?

Well actually it’s working since some “days” :wink:
Now on Jessie, RPI3 and OH2

long Holiday?

You got it working with the actual OH2.
I did see the “can’t open CUL” error on my setups of OH2 until around last xmas. I could overcome that by changing to the user root for the OH service. But that is solved since then.

I don’t use the CUL anymore. The CC1101 is connected direct to the SPI of the RPI. That is working very good, anless the RPI enables the SPI-Bus. I hab problems when changing to RPI3 (booting from USB but bootloader on SD, same as on RPI2 not with the new bootloader). The content of the SD-card has to be different than the one on the RPI2. Took me some days to realise (including mixing some sd-cards :wink:
But when the SPI is working, it’s fine. And I save one USB-Port.

long Holiday?
No. Wrong forum notification settings :smile: