Nibe uplink binding

Tags: #<Tag:0x00007fc8fb451d18> #<Tag:0x00007fc8fb451c28>

@AlexF concerning the datatype for status channels:

i understand your point however i believe you have somehow a better way in openhab to do what you want using the MAP transforms.
Let’s suppose from your example that the temporary lux mode channel would return a number (not a string type as it is at present)

Number TemporaryLuxMode "Temporary Lux Mode" {channel="nibeuplink:f1145:nibe:hotwater#48132"}

then to map the corresponding labels you can define a transformation map (a .map file) that you place in the transform directory of your config (e.g. in /etc/openhab2/transform). Let’s call it TemporaryLuxMode.map with the following content:

0.0=off
0=off
1.0=3h
1=3h
2.0=6h
2=6h
3.0=12h
3=12h
4.0=once
4=once
-=invalid     

Note: there is apparently a bug/limitation with this map transform (when using persistence services for the Items if i remember well) such that that it is recommended to define both the decimal x.0 and integer x translation. I found this hint in another post.

Now, having defined the transform, you can recall it in your items definition file:

Number TemporaryLuxMode "Temporary Lux Mode [MAP(TemporaryLuxMode.map):%s]" {channel="nibeuplink:f1145:nibe:hotwater#48132"}

The advantage besides the translation being cleanly defined in a .map file is that you get both representations to coexist. The Item will be automatically displayed (e.g. on the sitemap) with the string translated value (i.e. with its label ‘off’, ‘3h’, ‘6h’, etc) while it can be stored with its native number type in the database (when you use openhab persistence), and this without having to use rules to translate between representations.

You can also access either representations in rules, for instance:

val mode = TemporaryLuxMode.state as Number // this sets the variable mode as a Number 0,1,2,3, etc
val modeStr = transform(“MAP”,”TemporaryLuxMode.map”,TemporaryLuxMode.state.toString) // this sets the variable modeStr as the String equivalent representation of the mode (using the MAP defined transform). i.e. if the state is 1, then modeStr will be set to the String '3h'
TemporaryLuxMode.sendCommand(transform(“MAP”,”TemporaryLuxMode.map”,”3h”)) // this sends the command 1 (translated from the map file)
TemporaryLuxMode.sendCommand(1) // this does the same

So in this way i think you get what you would want while using the concepts embedded in openhab with the transform approach to play with the item representations.

The other important thing i wanted to highlight in my previous posts is that in the current 2.4 and the last 2.3 versions, and although the status channels are indeed defined as String type, you actually don’t get the string equivalent. I have not checked for the temporary lux mode channel, but for instance for the channel ‘Compressor Status Blocked’ (channel ‘compressor#10012’) which is defined as String:

I have defined the Item consistently with String type as required:

String NibeCompressorStatusBlockedTmp "Compressor Status Blocked" {channel="nibeuplink:f1145:nibe:compressor#10012"

and this is what i see in the events.log when the item initialises after startup:

2018-05-31 16:59:21.419 [vent.ItemStateChangedEvent] - NibeCompressorStatusBlockedTmp changed from NULL to 0

A query from the Karaf console is also consistent with this:

openhab> smarthome:status NibeCompressorStatusBlockedTmp                                                                                                                                                                    
0

you will note i don’t get ‘No’ or ‘Off’ which would have been the string representation. What i get is the String ‘0’. I’m not sure this is what you would be expecting from what you explained in your posts, as we should have rather expected to have the item state get the string value ‘No’ (or ‘Off’). So overall i would think that giving directly a Number type in this case (the number 0 or 1) would be more consistent and as well more convenient.

Please don’t hesitate to shout if I miss something

Hi

i installed new blank 2.4 snapshot with 2.4.0 snapshot binding. No data for F750. Is there any possibilities that something has gone wrong when added new channels. No data with automatic linking or manual. I can see that data is fetched OK but it does not go to item value. There is nothing in events log for Nibe. when i change back to 2.3.0 Snapshot everythin works exept there is no fixed channels and multipliers.

1 Like

I can confirm this issue and for F1155.

1 Like

Is there any error in the logs?
I have not yet tried the openhab 2.4 but I am using openhab 2.3 + the 2.4 version of my binding

BR
Alex

@oliviercommelarbre:

You are right, using those map files seems to be a good idea. Users still have to define those maps on their own because I do not know any way to include them in the binding.
But at least it is possible to translate the status values to your own language. :slight_smile:

I will put this change on my list but first I will focus on the issues which currently exist.

@Mikko_Jokinen, @Giedrius and @AlexF: for info if it can help, i still use openhab 2.2.0 with the new version 2.4 of the snapshot and it works like a charm on my raspberry pi, the Nibe is an F1145.
Another info, sometimes things in openhab resolve miraculously after applying the procedure advertised in this post: . I have not had to use it in my case when switching from 2.3.0 snapshot but you never know… it has solved many other problems for me in the past, especially after i had awkward symptoms similar to what you have described.

I tried this with my old stable installation and all new installation in another laptop without other bindings. The 0.2.4 version is not working and 0.2.3 works. Il try to catch errors or log. No visible errors in openhab.log with default logging.

1 Like

Hi @AlexF

indeed there is the added value you spotted for specialising the statuses in your own language (which i realise is a really important one)
Thanks for that, will be looking forward to the future change. No rush for me, as said 2.4 already works for me with a few workarounds via rules handling type translations.

I take the opportunity to record the parameters of the F1145 that i would ideally like to see in the default list of channels. All are i think very relevant to the general monitoring of an F1145, operating a brine underground water system as the heat energy source (or sink when cooling), the 2 last ones for general monitoring of the cooling process. Probably all are equally relevant to an F1155.

These are:

40015     Number      °C     /10   EB100-EP14-BT10 Brine In Temperature
40016     Number      °C     /10   EB100-EP14-BT11 Brine Out Temperature
43439     Number      %            EP14-GP2 Brine Pump Status EP14 (Brine Pump Speed)
43437     Number      %            Supply Pump Speed EP14 (Climate supply water pump)
44270     Number      °C     /10   Calculated Cooling Supply Temperature S1 (equivalent of general#43009 but for cooling as opposed to heating) 
43103     Number                   HPAC state (state of the HPAC cooling accessory)

I have added a column to map the parameter scaling when applicable.
it would be great if you could add these to the default list!

cheers for now!

Same here. I’ve got a VVM320+F2040-8.
With Version 0.2.3 it works, with 0.2.4 it doesn’t.
I can see in the Debug Logs that die Bindung gets some new values for my Items.
But the values doesn’t seem to be published to the Items.

As I stepped back to Version 0.2.3 (Source: http://friese-de.eu/openhab2 ) it lost the availability to control my VVM320. Seems to be, that the 0.2.3 isn’t the same as the old one.
Could that be?

Hi @AzraelMasters

This is really weird, because you have exactly the same setup like me. I also have the VVM320+F2040-8.

I reverted back to version 2.3 and everything works again. There are no errors in Debug logs for 2.4. The only difference in Debug logs is that in case of 2.4 logs ends on “Handling channel update” and in 2.3 it continues with each channel details:

[DEBUG] [euplink.handler.GenericUplinkHandler] - Handling channel update.
[DEBUG] [euplink.handler.GenericUplinkHandler] - Channel is to be updated: general#44302: 0
[DEBUG] [euplink.handler.GenericUplinkHandler] - Channel is to be updated: compressor#43136: 0
[DEBUG] [euplink.handler.GenericUplinkHandler] - Channel is to be updated: general#44304: 0

@Giedrius: Thanks for your hint. There is indeed a slight change in code where the channel update takes place. Still a little bit weird becuase this is the same for all models and I do not have any problems. But this is also related to the custom channels. I will check the code once again.

BR
Alex

Well I did not find any issue. I improved the logging, “Handling channel update” now announces the number of channels to be updated. In your case I guess it is 0.

This is what my log looks like. In my DEV the logging is in general set to a high level so I get a lot of messages from openhab itself, e.g. the ItemStateEvent.

2018-06-12 17:20:53.093 [DEBUG] [.o.b.n.i.c.GenericStatusUpdate:153  ] - JSON String: {"Values":[{"VariableId":40004,"CurrentValue":"19.4°C","CurrentIntValue":194,"IsLoading":false},{"VariableId":43005,"CurrentValue":"0GM","CurrentIntValue":0,"IsLoading":false},.....]}
2018-06-12 17:20:53.094 [DEBUG] [.n.i.model.GenericDataResponse:67   ] - Channel 40004 updated to: 194 (19.4°C)
2018-06-12 17:20:53.094 [DEBUG] [.n.i.model.GenericDataResponse:67   ] - Channel 40067 updated to: 185 (18.5°C)
2018-06-12 17:20:53.094 [DEBUG] [.n.i.model.GenericDataResponse:67   ] - Channel 43005 updated to: 0 (0GM)
...
2018-06-12 17:22:53.524 [DEBUG] [n.handler.GenericUplinkHandler:180  ] - Handling channel update. (70 Channels)
2018-06-12 17:22:53.524 [DEBUG] [n.handler.GenericUplinkHandler:190  ] - Channel is to be updated: general#44302: 13820
2018-06-12 17:22:53.525 [DEBUG] [n.handler.GenericUplinkHandler:190  ] - Channel is to be updated: general#44304: 0
2018-06-12 17:22:53.525 [DEBUG] [n.handler.GenericUplinkHandler:190  ] - Channel is to be updated: compressor#44700: 99
...
2018-06-12 17:23:53.253 [INFO ] [smarthome.event.ItemStateEvent:51   ] - NIBE_HM_COOL updated to 1382.0
2018-06-12 17:23:53.253 [INFO ] [smarthome.event.ItemStateEvent:51   ] - NIBE_VENT_BT23 updated to 19.1
2018-06-12 17:23:53.253 [INFO ] [smarthome.event.ItemStateEvent:51   ] - NIBE_VENT_BT20 updated to 25.3
2018-06-12 17:23:53.253 [INFO ] [smarthome.event.ItemStateEvent:51   ] - NIBE_OUT_TEMP_AVG updated to 18.5
2018-06-12 17:23:53.254 [INFO ] [smarthome.event.ItemStateEvent:51   ] - NIBE_COMP_STARTS updated to 11130.0
...

One additional remark:
If you are using custom channels keep in mind that those channels are now numbers. I would expect at least a warning in the logs if there was a mismatch…

Last line in logs is now this:

2018-06-13 09:17:55.068 [DEBUG] [euplink.handler.GenericUplinkHandler] - Handling channel update. (40 Channels)

To be on safe side I also removed 2 custom channels I had. Still result is the same.

Hello AlexF,
thanks for your fast Response.

Could you provide me a compiled dev-version 2.3 with WriteAccess?
The 2.3 from http://friese-de.eu/openhab2/ seems to have only readaccess?
I’m sorry but I lost the 2.3-Dev-Version from March.

I tried your latest 2.4.0-SNAPSHOT 2018-06-12.
It still does not publish the values :frowning:

That’s what I get from Logfile:

2018-06-13 10:59:02.550 [DEBUG] [org.openhab.binding.nibeuplink      ] - BundleEvent STARTING - org.openhab.binding.nibeuplink
2018-06-13 10:59:02.558 [DEBUG] [org.openhab.binding.nibeuplink      ] - BundleEvent STARTED - org.openhab.binding.nibeuplink
2018-06-13 10:59:02.595 [DEBUG] [org.openhab.binding.nibeuplink      ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=369, service.bundleid=237, service.scope=bundle, component.name=org.openhab.binding.nibeuplink.internal.NibeUplinkHandlerFactory, component.id=239} - org.openhab.binding.nibeuplink
2018-06-13 10:59:04.319 [INFO ] [euplink.handler.GenericUplinkHandler] - About to initialize NibeUplink
2018-06-13 10:59:04.330 [DEBUG] [euplink.handler.GenericUplinkHandler] - NibeUplink initialized with configuration: org.openhab.binding.nibeuplink.config.NibeUplinkConfiguration@46ffb0[user=MyUsername,password=MyPassword,nibeId=MyNibeId,pollingInterval=300,houseKeepingInterval=3600,asyncTimeout=120,syncTimeout=120]
2018-06-13 10:59:04.375 [DEBUG] [euplink.handler.GenericUplinkHandler] - start polling job at intervall 300
2018-06-13 10:59:04.380 [DEBUG] [euplink.handler.GenericUplinkHandler] - start deadChannelHouseKeeping job at intervall 3600
2018-06-13 10:59:05.174 [DEBUG] [ng.nibeuplink.internal.command.Login] - received content, length: 125
2018-06-13 10:59:05.180 [DEBUG] [ng.nibeuplink.internal.command.Login] - HTTP response 302
2018-06-13 10:59:05.382 [DEBUG] [ing.nibeuplink.handler.UplinkPolling] - polling NibeUplink org.openhab.binding.nibeuplink.config.NibeUplinkConfiguration@72df73[user=MyUsername,password=MyPassword,nibeId=MyNibeId,pollingInterval=300,houseKeepingInterval=3600,asyncTimeout=120,syncTimeout=120]
2018-06-13 10:59:06.354 [DEBUG] [internal.command.GenericStatusUpdate] - received content, length: 1176
2018-06-13 10:59:06.365 [DEBUG] [internal.command.GenericStatusUpdate] - received content, length: 2621
2018-06-13 10:59:06.373 [DEBUG] [internal.command.GenericStatusUpdate] - received content, length: 4062
2018-06-13 10:59:06.383 [DEBUG] [internal.command.GenericStatusUpdate] - received content, length: 5515
2018-06-13 10:59:06.386 [DEBUG] [internal.command.GenericStatusUpdate] - HTTP response 200
2018-06-13 10:59:06.391 [DEBUG] [internal.command.GenericStatusUpdate] - onComplete()
2018-06-13 10:59:06.403 [DEBUG] [internal.command.GenericStatusUpdate] - JSON String: {"Values":[{"VariableId":40004,"CurrentValue":"16.9°C","CurrentIntValue":169,"IsLoading":false},{"VariableId":40067,"CurrentValue":"17.4°C","CurrentIntValue":174,"IsLoading":false},{"VariableId":43005,"CurrentValue":"0GM","CurrentIntValue":0,"IsLoading":false},{"VariableId":43009,"CurrentValue":"23.5°C","CurrentIntValue":235,"IsLoading":false},{"VariableId":40071,"CurrentValue":"--","CurrentIntValue":-32768,"IsLoading":false},{"VariableId":40033,"CurrentValue":"23.0°C","CurrentIntValue":230,"IsLoading":false},{"VariableId":43161,"CurrentValue":"Nein","CurrentIntValue":0,"IsLoading":false},{"VariableId":40008,"CurrentValue":"22.0°C","CurrentIntValue":220,"IsLoading":false},{"VariableId":40012,"CurrentValue":"22.9°C","CurrentIntValue":229,"IsLoading":false},{"VariableId":40072,"CurrentValue":"0.0l/m","CurrentIntValue":0,"IsLoading":false},{"VariableId":40079,"CurrentValue":"0.0A","CurrentIntValue":0,"IsLoading":false},{"VariableId":40081,"CurrentValue":"0.0A","CurrentIntValue":0,"IsLoading":false},{"VariableId":40083,"CurrentValue":"0.0A","CurrentIntValue":0,"IsLoading":false},{"VariableId":10033,"CurrentValue":"ja","CurrentIntValue":1,"IsLoading":false},{"VariableId":44270,"CurrentValue":"20.0°C","CurrentIntValue":200,"IsLoading":false},{"VariableId":43081,"CurrentValue":"597.6h","CurrentIntValue":5976,"IsLoading":false},{"VariableId":43084,"CurrentValue":"0.0kW","CurrentIntValue":0,"IsLoading":false},{"VariableId":47212,"CurrentValue":"9.0kW","CurrentIntValue":900,"IsLoading":false},{"VariableId":48914,"CurrentValue":"2.0kW","CurrentIntValue":200,"IsLoading":false},{"VariableId":40121,"CurrentValue":"22.7ºC","CurrentIntValue":227,"IsLoading":false},{"VariableId":43437,"CurrentValue":"0%","CurrentIntValue":0,"IsLoading":false},{"VariableId":44308,"CurrentValue":"2187.4kWh","CurrentIntValue":21874,"IsLoading":false},{"VariableId":44304,"CurrentValue":"0.0kWh","CurrentIntValue":0,"IsLoading":false},{"VariableId":44302,"CurrentValue":"715.5kWh","CurrentIntValue":7155,"IsLoading":false},{"VariableId":44300,"CurrentValue":"7594.9kWh","CurrentIntValue":75949,"IsLoading":false},{"VariableId":47011,"CurrentValue":"0","CurrentIntValue":0,"IsLoading":false},{"VariableId":47394,"CurrentValue":"ja","CurrentIntValue":1,"IsLoading":false},{"VariableId":47402,"CurrentValue":"0.0","CurrentIntValue":0,"IsLoading":false},{"VariableId":48793,"CurrentValue":"0.0","CurrentIntValue":0,"IsLoading":false},{"VariableId":48043,"CurrentValue":"inaktiv","CurrentIntValue":0,"IsLoading":false},{"VariableId":40013,"CurrentValue":"43.1°C","CurrentIntValue":431,"IsLoading":false},{"VariableId":40014,"CurrentValue":"42.4°C","CurrentIntValue":424,"IsLoading":false},{"VariableId":44306,"CurrentValue":"1706.0kWh","CurrentIntValue":17060,"IsLoading":false},{"VariableId":44298,"CurrentValue":"1783.5kWh","CurrentIntValue":17835,"IsLoading":false},{"VariableId":48132,"CurrentValue":"aus","CurrentIntValue":0,"IsLoading":false},{"VariableId":47041,"CurrentValue":"Sparm.","CurrentIntValue":0,"IsLoading":false},{"VariableId":44362,"CurrentValue":"16.8°C","CurrentIntValue":168,"IsLoading":false},{"VariableId":44396,"CurrentValue":"0%","CurrentIntValue":0,"IsLoading":false},{"VariableId":44703,"CurrentValue":"Nein","CurrentIntValue":0,"IsLoading":false},{"VariableId":44073,"CurrentValue":"382h","CurrentIntValue":382,"IsLoading":false},{"VariableId":40737,"CurrentValue":"184h","CurrentIntValue":184,"IsLoading":false},{"VariableId":44071,"CurrentValue":"1257h","CurrentIntValue":1257,"IsLoading":false},{"VariableId":44069,"CurrentValue":"2924","CurrentIntValue":2924,"IsLoading":false},{"VariableId":44061,"CurrentValue":"27.6°C","CurrentIntValue":276,"IsLoading":false},{"VariableId":44060,"CurrentValue":"20.8°C","CurrentIntValue":208,"IsLoading":false},{"VariableId":44059,"CurrentValue":"33.1°C","CurrentIntValue":331,"IsLoading":false},{"VariableId":44058,"CurrentValue":"22.4°C","CurrentIntValue":224,"IsLoading":false},{"VariableId":44055,"CurrentValue":"22.7°C","CurrentIntValue":227,"IsLoading":false},{"VariableId":44363,"CurrentValue":"16.7°C","CurrentIntValue":167,"IsLoading":false},{"VariableId":44699,"CurrentValue":"13.0bar","CurrentIntValue":130,"IsLoading":false},{"VariableId":40782,"CurrentValue":"0Hz","CurrentIntValue":0,"IsLoading":false},{"VariableId":44701,"CurrentValue":"0Hz","CurrentIntValue":0,"IsLoading":false},{"VariableId":44702,"CurrentValue":"Nein","CurrentIntValue":0,"IsLoading":false},{"VariableId":44700,"CurrentValue":"9.9bar","CurrentIntValue":99,"IsLoading":false},{"VariableId":44457,"CurrentValue":"0.0","CurrentIntValue":0,"IsLoading":false},{"VariableId":10012,"CurrentValue":"Nein","CurrentIntValue":0,"IsLoading":false},{"VariableId":40025,"CurrentValue":"22.8°C","CurrentIntValue":228,"IsLoading":false},{"VariableId":40026,"CurrentValue":"20.9°C","CurrentIntValue":209,"IsLoading":false},{"VariableId":40075,"CurrentValue":"20.9°C","CurrentIntValue":209,"IsLoading":false},{"VariableId":40183,"CurrentValue":"18.0°C","CurrentIntValue":180,"IsLoading":false},{"VariableId":40311,"CurrentValue":"60%","CurrentIntValue":60,"IsLoading":false},{"VariableId":40312,"CurrentValue":"60%","CurrentIntValue":60,"IsLoading":false},{"VariableId":40942,"CurrentValue":"Nein","CurrentIntValue":0,"IsLoading":false},{"VariableId":47260,"CurrentValue":"normal (65%)","CurrentIntValue":0,"IsLoading":false},{"VariableId":43086,"CurrentValue":"--","CurrentIntValue":-32768,"IsLoading":false},{"VariableId":0,"CurrentValue":"","CurrentIntValue":null,"IsLoading":true}]}
2018-06-13 10:59:06.428 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40004 updated to: 169 (16.9°C)
2018-06-13 10:59:06.431 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40067 updated to: 174 (17.4°C)
2018-06-13 10:59:06.433 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43005 updated to: 0 (0GM)
2018-06-13 10:59:06.435 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43009 updated to: 235 (23.5°C)
2018-06-13 10:59:06.438 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40071 updated to: -32768 (--)
2018-06-13 10:59:06.440 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40033 updated to: 230 (23.0°C)
2018-06-13 10:59:06.443 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43161 updated to: 0 (Nein)
2018-06-13 10:59:06.445 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40008 updated to: 220 (22.0°C)
2018-06-13 10:59:06.447 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40012 updated to: 229 (22.9°C)
2018-06-13 10:59:06.448 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40072 updated to: 0 (0.0l/m)
2018-06-13 10:59:06.451 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40079 updated to: 0 (0.0A)
2018-06-13 10:59:06.452 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40081 updated to: 0 (0.0A)
2018-06-13 10:59:06.454 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40083 updated to: 0 (0.0A)
2018-06-13 10:59:06.457 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 10033 updated to: 1 (ja)
2018-06-13 10:59:06.459 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44270 updated to: 200 (20.0°C)
2018-06-13 10:59:06.461 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43081 updated to: 5976 (597.6h)
2018-06-13 10:59:06.463 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43084 updated to: 0 (0.0kW)
2018-06-13 10:59:06.465 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47212 updated to: 900 (9.0kW)
2018-06-13 10:59:06.467 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 48914 updated to: 200 (2.0kW)
2018-06-13 10:59:06.469 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40121 updated to: 227 (22.7ºC)
2018-06-13 10:59:06.471 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43437 updated to: 0 (0%)
2018-06-13 10:59:06.473 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44308 updated to: 21874 (2187.4kWh)
2018-06-13 10:59:06.475 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44304 updated to: 0 (0.0kWh)
2018-06-13 10:59:06.477 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44302 updated to: 7155 (715.5kWh)
2018-06-13 10:59:06.479 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44300 updated to: 75949 (7594.9kWh)
2018-06-13 10:59:06.481 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47011 updated to: 0 (0)
2018-06-13 10:59:06.483 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47394 updated to: 1 (ja)
2018-06-13 10:59:06.485 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47402 updated to: 0 (0.0)
2018-06-13 10:59:06.487 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 48793 updated to: 0 (0.0)
2018-06-13 10:59:06.488 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 48043 updated to: 0 (inaktiv)
2018-06-13 10:59:06.490 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40013 updated to: 431 (43.1°C)
2018-06-13 10:59:06.492 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40014 updated to: 424 (42.4°C)
2018-06-13 10:59:06.494 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44306 updated to: 17060 (1706.0kWh)
2018-06-13 10:59:06.496 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44298 updated to: 17835 (1783.5kWh)
2018-06-13 10:59:06.498 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 48132 updated to: 0 (aus)
2018-06-13 10:59:06.500 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47041 updated to: 0 (Sparm.)
2018-06-13 10:59:06.502 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44362 updated to: 168 (16.8°C)
2018-06-13 10:59:06.504 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44396 updated to: 0 (0%)
2018-06-13 10:59:06.508 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44703 updated to: 0 (Nein)
2018-06-13 10:59:06.510 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44073 updated to: 382 (382h)
2018-06-13 10:59:06.512 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40737 updated to: 184 (184h)
2018-06-13 10:59:06.514 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44071 updated to: 1257 (1257h)
2018-06-13 10:59:06.516 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44069 updated to: 2924 (2924)
2018-06-13 10:59:06.518 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44061 updated to: 276 (27.6°C)
2018-06-13 10:59:06.520 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44060 updated to: 208 (20.8°C)
2018-06-13 10:59:06.522 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44059 updated to: 331 (33.1°C)
2018-06-13 10:59:06.524 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44058 updated to: 224 (22.4°C)
2018-06-13 10:59:06.526 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44055 updated to: 227 (22.7°C)
2018-06-13 10:59:06.528 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44363 updated to: 167 (16.7°C)
2018-06-13 10:59:06.530 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44699 updated to: 130 (13.0bar)
2018-06-13 10:59:06.533 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40782 updated to: 0 (0Hz)
2018-06-13 10:59:06.535 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44701 updated to: 0 (0Hz)
2018-06-13 10:59:06.537 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44702 updated to: 0 (Nein)
2018-06-13 10:59:06.539 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44700 updated to: 99 (9.9bar)
2018-06-13 10:59:06.541 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44457 updated to: 0 (0.0)
2018-06-13 10:59:06.543 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 10012 updated to: 0 (Nein)
2018-06-13 10:59:06.545 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40025 updated to: 228 (22.8°C)
2018-06-13 10:59:06.547 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40026 updated to: 209 (20.9°C)
2018-06-13 10:59:06.549 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40075 updated to: 209 (20.9°C)
2018-06-13 10:59:06.551 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40183 updated to: 180 (18.0°C)
2018-06-13 10:59:06.552 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40311 updated to: 60 (60%)
2018-06-13 10:59:06.554 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40312 updated to: 60 (60%)
2018-06-13 10:59:06.556 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40942 updated to: 0 (Nein)
2018-06-13 10:59:06.558 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47260 updated to: 0 (normal (65%))
2018-06-13 10:59:06.560 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43086 updated to: -32768 (--)
2018-06-13 10:59:06.563 [DEBUG] [euplink.handler.GenericUplinkHandler] - Handling channel update. (65 Channels)

This is really weird. This is the relevanmt code:

    /**
     * will update all channels provided in the map
     */
    @Override
    public void updateChannelStatus(Map<String, String> values) {
        logger.debug("Handling channel update. ({} Channels)", values.size());

        for (String key : values.keySet()) {
            logger.debug("Getting all channels which match ID '{}'", key);
            List<Channel> channels = getAllSpecificChannels(key);
            logger.debug("Found {} matching channels for ID '{}'", channels.size(), key);
            if (channels.size() == 0) {
                logger.info("Could not identify channel: {} for model {}", key,
                        getThing().getThingTypeUID().getAsString());
            }
            for (Channel channel : channels) {
                String value = values.get(key);
                logger.debug("Channel is to be updated: {}: {}", channel.getFQName(), value);
                if (value != null && !value.equals(NO_VALUE)) {
                    if (channel.getValueType().equals(ValueType.STRING)) {
                        updateState(channel.getFQName(), new StringType(value));
                    } else {
                        try {
                            updateState(channel.getFQName(), convertToDecimal(value, channel.getValueType()));
                        } catch (NumberFormatException ex) {
                            logger.warn("Could not update channel {} - invalid number: '{}'", channel.getFQName(),
                                    value);
                            updateState(channel.getFQName(), UnDefType.UNDEF);
                        }
                    }
                } else {
                    logger.debug("Value is null or not provided by heatpump (channel: {})", channel.getFQName());
                    updateState(channel.getFQName(), UnDefType.UNDEF);
                    deadChannels.add(channel);
                }
            }
        }
    }

It looks like the code never enters the outer loop. To be sure I have added more lines of logging.

logger.debug("Getting all channels which match ID '{}'", key);
...
logger.debug("Found {} matching channels for ID '{}'", channels.size(), key);

But nevertheless it is really strange as the first log message already states that the map contains 65 entries in your case.
The inner loop should be entered if “channels” contains at least one element and otherwise there is again a log message printed.

Hello Alex,

only 1 Line is new after "Handling Channel update. (64 Channels):

2018-06-13 13:28:45.592 [DEBUG] [org.openhab.binding.nibeuplink      ] - BundleEvent INSTALLED - org.openhab.binding.nibeuplink
2018-06-13 13:28:45.878 [DEBUG] [org.openhab.binding.nibeuplink      ] - BundleEvent RESOLVED - org.openhab.binding.nibeuplink
2018-06-13 13:28:45.911 [DEBUG] [org.openhab.binding.nibeuplink      ] - BundleEvent [unknown:512] - org.openhab.binding.nibeuplink
2018-06-13 13:28:45.970 [DEBUG] [org.openhab.binding.nibeuplink      ] - BundleEvent STARTING - org.openhab.binding.nibeuplink
2018-06-13 13:28:45.992 [DEBUG] [org.openhab.binding.nibeuplink      ] - BundleEvent STARTED - org.openhab.binding.nibeuplink
2018-06-13 13:28:46.065 [DEBUG] [org.openhab.binding.nibeuplink      ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=381, service.bundleid=249, service.scope=bundle, component.name=org.openhab.binding.nibeuplink.internal.NibeUplinkHandlerFactory, component.id=251} - org.openhab.binding.nibeuplink
2018-06-13 13:28:48.962 [INFO ] [euplink.handler.GenericUplinkHandler] - About to initialize NibeUplink
2018-06-13 13:28:48.973 [DEBUG] [euplink.handler.GenericUplinkHandler] - NibeUplink initialized with configuration: org.openhab.binding.nibeuplink.config.NibeUplinkConfiguration@337927[user=MyUser,password=MyPassword,nibeId=MyId,pollingInterval=300,houseKeepingInterval=3600,asyncTimeout=120,syncTimeout=120]
2018-06-13 13:28:49.019 [DEBUG] [euplink.handler.GenericUplinkHandler] - start polling job at intervall 300
2018-06-13 13:28:49.024 [DEBUG] [euplink.handler.GenericUplinkHandler] - start deadChannelHouseKeeping job at intervall 3600
2018-06-13 13:28:49.782 [DEBUG] [ng.nibeuplink.internal.command.Login] - received content, length: 125
2018-06-13 13:28:49.790 [DEBUG] [ng.nibeuplink.internal.command.Login] - HTTP response 302
2018-06-13 13:28:50.025 [DEBUG] [ing.nibeuplink.handler.UplinkPolling] - polling NibeUplink org.openhab.binding.nibeuplink.config.NibeUplinkConfiguration@58ca3d[user=user=MyUser,password=MyPassword,nibeId=MyId,pollingInterval=300,houseKeepingInterval=3600,asyncTimeout=120,syncTimeout=120]
2018-06-13 13:28:51.066 [DEBUG] [internal.command.GenericStatusUpdate] - received content, length: 1188
2018-06-13 13:28:51.072 [DEBUG] [internal.command.GenericStatusUpdate] - received content, length: 2644
2018-06-13 13:28:51.107 [DEBUG] [internal.command.GenericStatusUpdate] - received content, length: 5443
2018-06-13 13:28:51.111 [DEBUG] [internal.command.GenericStatusUpdate] - HTTP response 200
2018-06-13 13:28:51.114 [DEBUG] [internal.command.GenericStatusUpdate] - onComplete()
2018-06-13 13:28:51.121 [DEBUG] [internal.command.GenericStatusUpdate] - JSON String: {"Values":[{"VariableId":40004,"CurrentValue":"17.9°C","CurrentIntValue":179,"IsLoading":false},{"VariableId":40067,"CurrentValue":"17.4°C","CurrentIntValue":174,"IsLoading":false},{"VariableId":43005,"CurrentValue":"0GM","CurrentIntValue":0,"IsLoading":false},{"VariableId":43009,"CurrentValue":"22.5°C","CurrentIntValue":225,"IsLoading":false},{"VariableId":40071,"CurrentValue":"--","CurrentIntValue":-32768,"IsLoading":false},{"VariableId":40033,"CurrentValue":"23.0°C","CurrentIntValue":230,"IsLoading":false},{"VariableId":43161,"CurrentValue":"Nein","CurrentIntValue":0,"IsLoading":false},{"VariableId":40008,"CurrentValue":"23.5°C","CurrentIntValue":235,"IsLoading":false},{"VariableId":40012,"CurrentValue":"33.4°C","CurrentIntValue":334,"IsLoading":false},{"VariableId":40072,"CurrentValue":"13.8l/m","CurrentIntValue":138,"IsLoading":false},{"VariableId":40079,"CurrentValue":"0.0A","CurrentIntValue":0,"IsLoading":false},{"VariableId":40081,"CurrentValue":"0.0A","CurrentIntValue":0,"IsLoading":false},{"VariableId":40083,"CurrentValue":"0.0A","CurrentIntValue":0,"IsLoading":false},{"VariableId":10033,"CurrentValue":"ja","CurrentIntValue":1,"IsLoading":false},{"VariableId":44270,"CurrentValue":"20.0°C","CurrentIntValue":200,"IsLoading":false},{"VariableId":43081,"CurrentValue":"597.6h","CurrentIntValue":5976,"IsLoading":false},{"VariableId":43084,"CurrentValue":"0.0kW","CurrentIntValue":0,"IsLoading":false},{"VariableId":47212,"CurrentValue":"9.0kW","CurrentIntValue":900,"IsLoading":false},{"VariableId":48914,"CurrentValue":"2.0kW","CurrentIntValue":200,"IsLoading":false},{"VariableId":40121,"CurrentValue":"53.5ºC","CurrentIntValue":535,"IsLoading":false},{"VariableId":43437,"CurrentValue":"0%","CurrentIntValue":0,"IsLoading":false},{"VariableId":44308,"CurrentValue":"2187.4kWh","CurrentIntValue":21874,"IsLoading":false},{"VariableId":44304,"CurrentValue":"0.0kWh","CurrentIntValue":0,"IsLoading":false},{"VariableId":44302,"CurrentValue":"715.5kWh","CurrentIntValue":7155,"IsLoading":false},{"VariableId":44300,"CurrentValue":"7594.9kWh","CurrentIntValue":75949,"IsLoading":false},{"VariableId":47011,"CurrentValue":"0","CurrentIntValue":0,"IsLoading":false},{"VariableId":47394,"CurrentValue":"ja","CurrentIntValue":1,"IsLoading":false},{"VariableId":47402,"CurrentValue":"0.0","CurrentIntValue":0,"IsLoading":false},{"VariableId":48793,"CurrentValue":"0.0","CurrentIntValue":0,"IsLoading":false},{"VariableId":48043,"CurrentValue":"inaktiv","CurrentIntValue":0,"IsLoading":false},{"VariableId":40013,"CurrentValue":"47.6°C","CurrentIntValue":476,"IsLoading":false},{"VariableId":40014,"CurrentValue":"44.4°C","CurrentIntValue":444,"IsLoading":false},{"VariableId":44306,"CurrentValue":"1707.9kWh","CurrentIntValue":17079,"IsLoading":false},{"VariableId":44298,"CurrentValue":"1785.3kWh","CurrentIntValue":17853,"IsLoading":false},{"VariableId":48132,"CurrentValue":"aus","CurrentIntValue":0,"IsLoading":false},{"VariableId":47041,"CurrentValue":"Sparm.","CurrentIntValue":0,"IsLoading":false},{"VariableId":44362,"CurrentValue":"17.3°C","CurrentIntValue":173,"IsLoading":false},{"VariableId":44396,"CurrentValue":"77%","CurrentIntValue":77,"IsLoading":false},{"VariableId":44703,"CurrentValue":"Nein","CurrentIntValue":0,"IsLoading":false},{"VariableId":44073,"CurrentValue":"383h","CurrentIntValue":383,"IsLoading":false},{"VariableId":40737,"CurrentValue":"184h","CurrentIntValue":184,"IsLoading":false},{"VariableId":44071,"CurrentValue":"1258h","CurrentIntValue":1258,"IsLoading":false},{"VariableId":44069,"CurrentValue":"2925","CurrentIntValue":2925,"IsLoading":false},{"VariableId":44061,"CurrentValue":"13.6°C","CurrentIntValue":136,"IsLoading":false},{"VariableId":44060,"CurrentValue":"49.1°C","CurrentIntValue":491,"IsLoading":false},{"VariableId":44059,"CurrentValue":"77.9°C","CurrentIntValue":779,"IsLoading":false},{"VariableId":44058,"CurrentValue":"54.3°C","CurrentIntValue":543,"IsLoading":false},{"VariableId":44055,"CurrentValue":"48.9°C","CurrentIntValue":489,"IsLoading":false},{"VariableId":44363,"CurrentValue":"9.9°C","CurrentIntValue":99,"IsLoading":false},{"VariableId":44699,"CurrentValue":"32.2bar","CurrentIntValue":322,"IsLoading":false},{"VariableId":40782,"CurrentValue":"45Hz","CurrentIntValue":45,"IsLoading":false},{"VariableId":44701,"CurrentValue":"45Hz","CurrentIntValue":450,"IsLoading":false},{"VariableId":44702,"CurrentValue":"Nein","CurrentIntValue":0,"IsLoading":false},{"VariableId":44700,"CurrentValue":"9.9bar","CurrentIntValue":99,"IsLoading":false},{"VariableId":44457,"CurrentValue":"1.0","CurrentIntValue":1,"IsLoading":false},{"VariableId":10012,"CurrentValue":"Nein","CurrentIntValue":0,"IsLoading":false},{"VariableId":40025,"CurrentValue":"22.8°C","CurrentIntValue":228,"IsLoading":false},{"VariableId":40026,"CurrentValue":"20.9°C","CurrentIntValue":209,"IsLoading":false},{"VariableId":40075,"CurrentValue":"21.4°C","CurrentIntValue":214,"IsLoading":false},{"VariableId":40183,"CurrentValue":"18.5°C","CurrentIntValue":185,"IsLoading":false},{"VariableId":40311,"CurrentValue":"80%","CurrentIntValue":80,"IsLoading":false},{"VariableId":40312,"CurrentValue":"80%","CurrentIntValue":80,"IsLoading":false},{"VariableId":40942,"CurrentValue":"Nein","CurrentIntValue":0,"IsLoading":false},{"VariableId":47260,"CurrentValue":"Geschw. 4 (100%)","CurrentIntValue":4,"IsLoading":false},{"VariableId":0,"CurrentValue":"","CurrentIntValue":null,"IsLoading":true}]}
2018-06-13 13:28:51.144 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40004 updated to: 179 (17.9°C)
2018-06-13 13:28:51.147 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40067 updated to: 174 (17.4°C)
2018-06-13 13:28:51.149 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43005 updated to: 0 (0GM)
2018-06-13 13:28:51.151 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43009 updated to: 225 (22.5°C)
2018-06-13 13:28:51.153 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40071 updated to: -32768 (--)
2018-06-13 13:28:51.154 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40033 updated to: 230 (23.0°C)
2018-06-13 13:28:51.156 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43161 updated to: 0 (Nein)
2018-06-13 13:28:51.158 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40008 updated to: 235 (23.5°C)
2018-06-13 13:28:51.161 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40012 updated to: 334 (33.4°C)
2018-06-13 13:28:51.162 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40072 updated to: 138 (13.8l/m)
2018-06-13 13:28:51.164 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40079 updated to: 0 (0.0A)
2018-06-13 13:28:51.166 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40081 updated to: 0 (0.0A)
2018-06-13 13:28:51.168 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40083 updated to: 0 (0.0A)
2018-06-13 13:28:51.170 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 10033 updated to: 1 (ja)
2018-06-13 13:28:51.172 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44270 updated to: 200 (20.0°C)
2018-06-13 13:28:51.174 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43081 updated to: 5976 (597.6h)
2018-06-13 13:28:51.176 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43084 updated to: 0 (0.0kW)
2018-06-13 13:28:51.177 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47212 updated to: 900 (9.0kW)
2018-06-13 13:28:51.179 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 48914 updated to: 200 (2.0kW)
2018-06-13 13:28:51.181 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40121 updated to: 535 (53.5ºC)
2018-06-13 13:28:51.183 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 43437 updated to: 0 (0%)
2018-06-13 13:28:51.185 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44308 updated to: 21874 (2187.4kWh)
2018-06-13 13:28:51.186 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44304 updated to: 0 (0.0kWh)
2018-06-13 13:28:51.188 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44302 updated to: 7155 (715.5kWh)
2018-06-13 13:28:51.190 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44300 updated to: 75949 (7594.9kWh)
2018-06-13 13:28:51.192 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47011 updated to: 0 (0)
2018-06-13 13:28:51.194 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47394 updated to: 1 (ja)
2018-06-13 13:28:51.196 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47402 updated to: 0 (0.0)
2018-06-13 13:28:51.198 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 48793 updated to: 0 (0.0)
2018-06-13 13:28:51.200 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 48043 updated to: 0 (inaktiv)
2018-06-13 13:28:51.202 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40013 updated to: 476 (47.6°C)
2018-06-13 13:28:51.204 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40014 updated to: 444 (44.4°C)
2018-06-13 13:28:51.206 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44306 updated to: 17079 (1707.9kWh)
2018-06-13 13:28:51.208 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44298 updated to: 17853 (1785.3kWh)
2018-06-13 13:28:51.210 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 48132 updated to: 0 (aus)
2018-06-13 13:28:51.212 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47041 updated to: 0 (Sparm.)
2018-06-13 13:28:51.214 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44362 updated to: 173 (17.3°C)
2018-06-13 13:28:51.216 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44396 updated to: 77 (77%)
2018-06-13 13:28:51.218 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44703 updated to: 0 (Nein)
2018-06-13 13:28:51.219 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44073 updated to: 383 (383h)
2018-06-13 13:28:51.222 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40737 updated to: 184 (184h)
2018-06-13 13:28:51.223 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44071 updated to: 1258 (1258h)
2018-06-13 13:28:51.225 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44069 updated to: 2925 (2925)
2018-06-13 13:28:51.227 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44061 updated to: 136 (13.6°C)
2018-06-13 13:28:51.229 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44060 updated to: 491 (49.1°C)
2018-06-13 13:28:51.231 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44059 updated to: 779 (77.9°C)
2018-06-13 13:28:51.233 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44058 updated to: 543 (54.3°C)
2018-06-13 13:28:51.235 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44055 updated to: 489 (48.9°C)
2018-06-13 13:28:51.236 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44363 updated to: 99 (9.9°C)
2018-06-13 13:28:51.238 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44699 updated to: 322 (32.2bar)
2018-06-13 13:28:51.240 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40782 updated to: 45 (45Hz)
2018-06-13 13:28:51.242 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44701 updated to: 450 (45Hz)
2018-06-13 13:28:51.244 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44702 updated to: 0 (Nein)
2018-06-13 13:28:51.247 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44700 updated to: 99 (9.9bar)
2018-06-13 13:28:51.249 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 44457 updated to: 1 (1.0)
2018-06-13 13:28:51.252 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 10012 updated to: 0 (Nein)
2018-06-13 13:28:51.254 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40025 updated to: 228 (22.8°C)
2018-06-13 13:28:51.256 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40026 updated to: 209 (20.9°C)
2018-06-13 13:28:51.258 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40075 updated to: 214 (21.4°C)
2018-06-13 13:28:51.261 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40183 updated to: 185 (18.5°C)
2018-06-13 13:28:51.263 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40311 updated to: 80 (80%)
2018-06-13 13:28:51.266 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40312 updated to: 80 (80%)
2018-06-13 13:28:51.268 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 40942 updated to: 0 (Nein)
2018-06-13 13:28:51.270 [DEBUG] [k.internal.model.GenericDataResponse] - Channel 47260 updated to: 4 (Geschw. 4 (100%))
2018-06-13 13:28:51.272 [DEBUG] [euplink.handler.GenericUplinkHandler] - Handling channel update. (64 Channels)
2018-06-13 13:28:51.275 [DEBUG] [euplink.handler.GenericUplinkHandler] - Getting all channels which match ID '44302'

My Item Equivalent Looks like:

Number Nibe_44302_HeatMeterCoolingCprEP14   "Kühlung, nur Verd. [%.2f kWh]"                 <energy>{channel="nibeuplink:vvm320:NibeF2040-8:general#44302"}

Well, extended logging once again (VVM320 only).
Also added a “try-catch” clause as it seems that there is an exception thrown which is silently caught somewhere else. Otherwise there is no explanation for that abortion of code execution.

BR
Alex

1 Like

And there it is :slight_smile:
For each Channel I got the following loglines below.
I have currently no custom Channels in my Item / Thing Files.

2018-06-13 15:36:15.287 [DEBUG] [euplink.handler.GenericUplinkHandler] - Handling channel update. (64 Channels)
2018-06-13 15:36:15.290 [DEBUG] [euplink.handler.GenericUplinkHandler] - Getting all channels which match ID '44302'
2018-06-13 15:36:15.292 [DEBUG] [ing.nibeuplink.handler.VVM320Handler] - trying to find a VVM320 specific channel
2018-06-13 15:36:15.295 [DEBUG] [ing.nibeuplink.handler.VVM320Handler] - found a VVM320 specific channel
2018-06-13 15:36:15.298 [DEBUG] [ing.nibeuplink.handler.VVM320Handler] - trying to find a custom channel
2018-06-13 15:36:15.301 [WARN ] [euplink.handler.GenericUplinkHandler] - null
java.lang.NullPointerException: null
	at org.openhab.binding.nibeuplink.internal.model.CustomChannels.fromId(CustomChannels.java:75) [252:org.openhab.binding.nibeuplink:2.4.0.201806131321]
	at org.openhab.binding.nibeuplink.handler.VVM320Handler.getAllSpecificChannels(VVM320Handler.java:48) [252:org.openhab.binding.nibeuplink:2.4.0.201806131321]
	at org.openhab.binding.nibeuplink.handler.GenericUplinkHandler.updateChannelStatus(GenericUplinkHandler.java:185) [252:org.openhab.binding.nibeuplink:2.4.0.201806131321]
	at org.openhab.binding.nibeuplink.internal.command.GenericStatusUpdate.onComplete(GenericStatusUpdate.java:92) [252:org.openhab.binding.nibeuplink:2.4.0.201806131321]
	at org.eclipse.jetty.client.ResponseNotifier.notifyComplete(ResponseNotifier.java:193) [74:org.eclipse.jetty.client:9.3.21.v20170918]
	at org.eclipse.jetty.client.ResponseNotifier.notifyComplete(ResponseNotifier.java:185) [74:org.eclipse.jetty.client:9.3.21.v20170918]
	at org.eclipse.jetty.client.HttpReceiver.terminateResponse(HttpReceiver.java:458) [74:org.eclipse.jetty.client:9.3.21.v20170918]
	at org.eclipse.jetty.client.HttpReceiver.responseSuccess(HttpReceiver.java:405) [74:org.eclipse.jetty.client:9.3.21.v20170918]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.messageComplete(HttpReceiverOverHTTP.java:277) [74:org.eclipse.jetty.client:9.3.21.v20170918]
	at org.eclipse.jetty.http.HttpParser.handleContentMessage(HttpParser.java:599) [77:org.eclipse.jetty.http:9.3.21.v20170918]
	at org.eclipse.jetty.http.HttpParser.parseContent(HttpParser.java:1526) [77:org.eclipse.jetty.http:9.3.21.v20170918]
	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1350) [77:org.eclipse.jetty.http:9.3.21.v20170918]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.parse(HttpReceiverOverHTTP.java:159) [74:org.eclipse.jetty.client:9.3.21.v20170918]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.process(HttpReceiverOverHTTP.java:120) [74:org.eclipse.jetty.client:9.3.21.v20170918]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.receive(HttpReceiverOverHTTP.java:70) [74:org.eclipse.jetty.client:9.3.21.v20170918]
	at org.eclipse.jetty.client.http.HttpChannelOverHTTP.receive(HttpChannelOverHTTP.java:90) [74:org.eclipse.jetty.client:9.3.21.v20170918]
	at org.eclipse.jetty.client.http.HttpConnectionOverHTTP.onFillable(HttpConnectionOverHTTP.java:115) [74:org.eclipse.jetty.client:9.3.21.v20170918]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [78:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [78:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251) [78:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [78:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [78:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [78:org.eclipse.jetty.io:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [89:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [89:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [89:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [89:org.eclipse.jetty.util:9.3.21.v20170918]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [89:org.eclipse.jetty.util:9.3.21.v20170918]
	at java.lang.Thread.run(Thread.java:748) [?:?]