asterisk binding call item seems to provide wrong values

[GoogleCodeExporter]

What steps will reproduce the problem?
1. openhab started in debug mode with established asterisk binding
      Switch Incoming_Call    "Ringing"                        (Telefon)    { asterisk="active" }
      Call Active_Call        "Connected [to %1$s from %2$s]"  (Telefon)    { asterisk="active"  }
2. there's a rule to handle calls further
      rule "active call"
       when
        Item Active_Call received update
       then
        println(">>>>>>>" + Active_Call.state + "<")        
      end
3. establish call

What is the expected output? What do you see instead?
Expected behaviour would be that Active_Call gets the right values of initiator 
and called phone number.
Instead following happens:

16:21:55.847 INFO  runtime.busevents[:46] - Incoming_Call state updated to ON
16:21:55.848 INFO  runtime.busevents[:46] - Active_Call state updated to 
6000##017xxxxxx
16:21:55.850 INFO  runtime.busevents[:46] - Incoming_Call state updated to ON
16:21:55.850 WARN  o.a.m.i.EventBuilderImpl[:85] - Unable to set property 
'connectedlinename' to '<unknown>' on org.asteriskjava.man
ager.event.DialEvent: no setter. Please report at 
http://jira.reucon.org/browse/AJ
16:21:55.851 INFO  runtime.busevents[:46] - Active_Call state updated to 
null##null
16:21:55.851 WARN  o.a.m.i.EventBuilderImpl[:85] - Unable to set property 
'connectedlinenum' to '<unknown>' on org.asteriskjava.mana
ger.event.DialEvent: no setter. Please report at 
http://jira.reucon.org/browse/AJ
>>>>>>>null##null<
>>>>>>>null##null<
16:22:04.797 WARN  o.a.m.i.EventBuilderImpl[:85] - Unable to set property 
'connectedlinename' to '<unknown>' on org.asteriskjava.man
ager.event.HangupEvent: no setter. Please report at 
http://jira.reucon.org/browse/AJ
16:22:04.797 WARN  o.a.m.i.EventBuilderImpl[:85] - Unable to set property 
'connectedlinenum' to '<unknown>' on org.asteriskjava.mana
ger.event.HangupEvent: no setter. Please report at 
http://jira.reucon.org/browse/AJ
16:22:04.798 INFO  runtime.busevents[:46] - Incoming_Call state updated to ON
16:22:04.799 WARN  o.a.m.i.EventBuilderImpl[:85] - Unable to set property 
'connectedlinename' to '<unknown>' on org.asteriskjava.man
ager.event.HangupEvent: no setter. Please report at 
http://jira.reucon.org/browse/AJ
16:22:04.803 INFO  runtime.busevents[:46] - Active_Call state updated to 
6000##017xxxxxx
16:22:04.806 WARN  o.a.m.i.EventBuilderImpl[:85] - Unable to set property 
'connectedlinenum' to '<unknown>' on org.asteriskjava.mana
ger.event.HangupEvent: no setter. Please report at 
http://jira.reucon.org/browse/AJ
16:22:04.811 INFO  runtime.busevents[:46] - Incoming_Call state updated to OFF
>>>>>>>6000##017xxxxxx<
16:22:04.817 INFO  runtime.busevents[:46] - Active_Call state updated to ##
>>>>>>>##<

What version of the product are you using? On what operating system?
openhab and extensions => 1.1.0 snapshot (Windows)
asterisk => 1.8.10.1 (Linux)

Please provide any additional information below.
I'm pretty new to openhab (great work!), is there a problem within my setup?
I x'd the last diggits of my phone number, so there's no issue with that.

Original issue reported on code.google.com by dieter.k...@googlemail.com on 26 Aug 2012 at 2:49

[GoogleCodeExporter]

Original comment by kai.openhab on 26 Aug 2012 at 2:51

[GoogleCodeExporter]

Hi Dieter,

thanks for investigating this issue. Since i don't use Asterisk any longer, 
bugfixing is a bit tricky at the moment. I recently checked in a "blind-fix". 
If that it doesn't help we should try to do kind of remote debugging :-) (you 
should start openHAB from your IDE and we'll see what happens between openHAB 
and Asterisk).

It will have to do with to many NewChannelEvents (see 
AsteriskBinding.handleNewCall line 239. You could try to increase logging in 
Asterisk console (asterisk -r; core set verbose 50;) to identity the erroneous 
Asterisk-event. 

Please feel free to reopen this issue, if it still occurs.

Regards,

Thomas E.-E.

Original comment by teichsta on 28 Aug 2012 at 8:46

• Changed state: Fixed

[GoogleCodeExporter]

Hi Thomas,

thanks a lot, just x-checked behaviour (attached some logs below).
Disconnect might get improved, but call setup works perfectly fine.
As i intend to trigger on the call setup, fine for me.

Best regards,
Dieter

call setup:
10:18:21.481 INFO  runtime.busevents[:46] - Incoming_Call state updated to ON
10:18:21.482 INFO  runtime.busevents[:46] - Active_Call state updated to 
6000##017xxxxxxxx
>>>>>>>6000##017xxxxxxxx<

disconnect:
10:18:44.323 INFO  runtime.busevents[:46] - Incoming_Call state updated to ON
10:18:44.339 INFO  runtime.busevents[:46] - Active_Call state updated to 
6000##017xxxxxxxx
>>>>>>>6000##017xxxxxxxx<
10:18:44.339 INFO  runtime.busevents[:46] - Incoming_Call state updated to OFF
10:18:44.355 INFO  runtime.busevents[:46] - Active_Call state updated to ##
>>>>>>>##<

Original comment by dieter.k...@googlemail.com on 1 Sep 2012 at 8:35

[GoogleCodeExporter]

Hi,

can I download somewhere an updated .jar file in which this problem is solved?

Original comment by roland.b...@gmail.com on 6 Oct 2012 at 11:36

[GoogleCodeExporter]

Sure, it is in the addons package on our continuous integration server: 
https://openhab.ci.cloudbees.com/job/openHAB/

Original comment by kai.openhab on 6 Oct 2012 at 7:07