Avmfritz: Power item does not get refreshed

Motte001 · March 9, 2017, 4:47pm

I’m using oh2 and avmfritz binding with openjdk-8-jre. I have configured the bridge and a dect 200 with paperui and set logging to debug.
I can see that every three minutes a http request is successfully made. However the linked power item stays at 0.000

Other problem: I have set pollingInterval to 15 so shouldnt be there a request every 15 seconds?

cweitkamp · March 11, 2017, 11:12pm

Hey Motte,

can you please post a complete log of the refresh polling?

Did I understand you correctly: The current power consumption of your DECT200 should be 1833550409, but the item shows 0?

Motte001 · March 12, 2017, 1:10pm

There seems to be a problem with copy/paste from putty to here. This is why I posted “1833550409”. It should be a xml tag

gist.github.com

https://gist.github.com/Motte001/fa4def6fd061147e1b54146df641f348

openhab avmfritz debug log

2017-03-12 14:04:50.079 [DEBUG] [ternal.discovery.AvmDiscoveryService] - starting scan on bridge avmfritz:fritzbox:192_168_178_1
2017-03-12 14:04:50.080 [DEBUG] [ternal.hardware.FritzahaWebInterface] - GETting URL http://192.168.178.1:80/webservices/homeautoswitch.lua?switchcmd=getdevicelistinfos&sid=foo
2017-03-12 14:04:50.370 [DEBUG] [nal.hardware.FritzahaContentExchange] - HTTP response 200
2017-03-12 14:04:50.370 [DEBUG] [nal.hardware.FritzahaContentExchange] - HTTP response 200
2017-03-12 14:04:50.370 [DEBUG] [nal.hardware.FritzahaContentExchange] - response complete: <devicelist version="1"><device identifier="foo" id="16" functionbitmask="2944" fwversion="03.83" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>foo</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>0</power><energy>1325</energy></powermeter><temperature><celsius>200</celsius><offset>0</offset></temperature></device></devicelist>

2017-03-12 14:04:50.371 [DEBUG] [.callbacks.FritzAhaDiscoveryCallback] - discovery callback response <devicelist version="1"><device identifier="foo" id="16" functionbitmask="2944" fwversion="03.83" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>foo</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>0</power><energy>1325</energy></powermeter><temperature><celsius>200</celsius><offset>0</offset></temperature></device></devicelist>

In this case the power is zero, but I have some other log entries with power > 0, but the item doesn’t get updated

cweitkamp · March 12, 2017, 7:24pm

Hi Motte,

thanks for the info. The response of your FRITZ!Box looks good. But - I am sorry - the interesting part of the log file is missing. The lines with prefix nal.hardware.FritzahaContentExchange are normally followed by some lines with .binding.avmfritz.handler.BoxHandler. Can you please append them to your extract?

Fact is, that I am sure I can explain you the problem, but cannot solve it directly. It is discussed in issue #1885. It only occurs sometimes and it can be traced back to a configuration error for the used XML-Parser in the binding implementation. I already uploaded a refactored version. It hopefully will be merged with pull request 1887 in the near future.

Motte001 · March 12, 2017, 7:40pm

Do I need the log to be set to Debug or Trace?

cweitkamp · March 12, 2017, 7:55pm

I think DEBUG is enough.

Motte001 · March 15, 2017, 1:26pm

egrep -i “FritzahaContentExchange|handler.BoxHandler” /var/log/openhab2/openhab.log

I dont see any “binding.avmfritz.handler.BoxHandler”.
Just “HTTP reponse 200” twice followed by “response complete:”… with correct values

I just found entry from ternal.discovery.AvmDiscoveryService and ternal.hardware.FritzahaWebInterface

cweitkamp · March 16, 2017, 6:31am

That is wired. And maybe the problem. My log looks like this:

gist.github.com

https://gist.github.com/cweitkamp/f812b8a20b61141f545c1b9b5faf7c7d

openhab.log

2017-03-16 07:09:22.499 [DEBUG] [ternal.hardware.FritzahaWebInterface] - GETting URL http://XXX/webservices/homeautoswitch.lua?switchcmd=getdevicelistinfos&sid=ba68d08ecaddc59f
2017-03-16 07:09:22.801 [DEBUG] [nal.hardware.FritzahaContentExchange] - HTTP response 200
2017-03-16 07:09:22.813 [DEBUG] [nal.hardware.FritzahaContentExchange] - HTTP response 200
2017-03-16 07:09:22.814 [DEBUG] [nal.hardware.FritzahaContentExchange] - response complete: <devicelist version="1"><device identifier="XXX" id="16" functionbitmask="2944" fwversion="03.83" manufacturer="AVM" productname="FRITZ!DECT 200"><present>1</present><name>FRITZ!DECT 200 #1</name><switch><state>1</state><mode>manuell</mode><lock>0</lock><devicelock>0</devicelock></switch><powermeter><power>42050</power><energy>44325</energy></powermeter><temperature><celsius>205</celsius><offset>-10</offset></temperature></device></devicelist>

2017-03-16 07:09:22.989 [DEBUG] [.binding.avmfritz.handler.BoxHandler] - set device model: org.openhab.binding.avmfritz.internal.ahamodel.DeviceModel@196aa86[ain=XXX,bitmask=2944,isDectRepeater=false,isPowermeter=true,isTempSensor=true,isSwitchableOutlet=true,isHeatingThermostat=false,id=16,manufacturer=AVM,productname=FRITZ!DECT 200,fwversion=03.83,present=1,name=FRITZ!DECT 200 #1,org.openhab.binding.avmfritz.internal.ahamodel.SwitchModel@1755599[state=1,mode=manuell,lock=0],org.openhab.binding.avmfritz.internal.ahamodel.PowerMeterModel@5ad0bd[power=42.050,energy=44.325],org.openhab.binding.avmfritz.internal.ahamodel.TemperatureModel@6eb54b[celsius=20.5,offset=-1.0],<null>]
2017-03-16 07:09:22.992 [DEBUG] [.binding.avmfritz.handler.BoxHandler] - update thing avmfritz:FRITZ_DECT_200:192_168_178_1:XXX with device model: org.openhab.binding.avmfritz.internal.ahamodel.DeviceModel@196aa86[ain=XXX,bitmask=2944,isDectRepeater=false,isPowermeter=true,isTempSensor=true,isSwitchableOutlet=true,isHeatingThermostat=false,id=16,manufacturer=AVM,productname=FRITZ!DECT 200,fwversion=03.83,present=1,name=FRITZ!DECT 200 #1,org.openhab.binding.avmfritz.internal.ahamodel.SwitchModel@1755599[state=1,mode=manuell,lock=0],org.openhab.binding.avmfritz.internal.ahamodel.PowerMeterModel@5ad0bd[power=42.050,energy=44.325],org.openhab.binding.avmfritz.internal.ahamodel.TemperatureModel@6eb54b[celsius=20.5,offset=-1.0],<null>]

The binding parses the response XML and automatically generates JavaObject out of it. In issue #1885 the PowerMeterModel is “null” and the values are not updated. In your case there seems to be nothing and the log should contain a warning “no model in response”.

Motte001 · March 16, 2017, 1:54pm

Does it matter how I configure it? Since there seems to be a problem with the syntax in .things file (https://github.com/openhab/openhab2-addons/pull/1963) I used paperUI to configure the bridge, the socket and created and linked the items

cweitkamp · March 16, 2017, 7:26pm

Yes it does. But using PaperUI should be the best way. I did the same except linking the items. I will test creating the channel links with PaperUI and give feedback.

Motte001 · July 15, 2017, 7:48am

After updating to oh 2.1 I get some binding.avmfritz.handler.BoxHandler messages.
I deactivated my workaround parsing the logfile with bash and I will see if it works now

cweitkamp · July 26, 2017, 9:43am

Hi Motte,

any success with it? Can you give positive feedback?

Motte001 · August 2, 2017, 8:29am

Doesn’t work
For security reasons I disabled paperui whenever I dont need it. Might this be an issue?