[Freeswitch-dev] INCOMPATIBLE_DESTINATION after bridging LinkSys3102 and X-Lite

Fri Oct 29 03:53:28 PDT 2010

Anthony Minessale schrieb:
> your code appears out of date.
> please reproduce on latest GIT HEAD
>
>
> On Tue, Oct 26, 2010 at 10:51 AM, Achim Stamm <stamm at lyth.de> wrote:
>   
>> Anthony Minessale schrieb:
>>     
>>> Well then you should show a log of it happening with 2 x-lites then.
>>>
>>>
>>>
>>> On Tue, Oct 26, 2010 at 9:34 AM, Achim Stamm <stamm at lyth.de> wrote:
>>>
>>>       
>>>> Anthony Minessale schrieb:
>>>>
>>>>         
>>>>> what is mod_evb? if it's a proprietary endpoint i'd look into that code.
>>>>>
>>>>>
>>>>> On Tue, Oct 26, 2010 at 4:20 AM, Achim Stamm <stamm at lyth.de> wrote:
>>>>>
>>>>>
>>>>>           
>>>>>> Hello,
>>>>>>
>>>>>> I have following problem:
>>>>>>
>>>>>> An external Call from LinkSys 3102 (Analog phone) is bridged to a X-Lite
>>>>>> Phone.
>>>>>> X-Lite Phone is ringing and after pickup sometimes I get an error
>>>>>> INCOMPATIBLE_DESTINATION (see snippet of logfile).
>>>>>> Mostly all works fine, but I can't understand why this error
>>>>>> INCOMPATIBLE_DESTINATION sometimes occurs.
>>>>>> Is there a solution for avoiding INCOMPATIBLE_DESTINATION or an
>>>>>> explanation why this error sometimes occurs?
>>>>>>
>>>>>>
>>>>>> regards
>>>>>>
>>>>>> Achim Stamm
>>>>>>
>>>>>> snippet of logfile:
>>>>>> 2010-10-25 17:00:58.296875 [DEBUG] sofia.c:4153 Channel
>>>>>> sofia/internal/sip:60 at 192.168.1.114:52111 entering state [ready][200]
>>>>>> 2010-10-25 17:00:58.296875 [DEBUG] sofia_glue.c:3585 Audio Codec Compare
>>>>>> [PCMA:8:8000:20]/[PCMA:8:8000:20]
>>>>>> 2010-10-25 17:00:58.296875 [DEBUG] sofia_glue.c:2354 Set Codec
>>>>>> sofia/internal/sip:60 at 192.168.1.114:52111 PCMA/8000 20 ms 160 samples
>>>>>> 2010-10-25 17:00:58.296875 [DEBUG] sofia_glue.c:3518 Set 2833 dtmf send
>>>>>> payload to 101
>>>>>> 2010-10-25 17:00:58.296875 [DEBUG] sofia_glue.c:2594 AUDIO RTP
>>>>>> [sofia/internal/sip:60 at 192.168.1.114:52111] 192.168.1.114 port 26696 ->
>>>>>> 192.168.1.114 port 58588 codec: 8 ms: 20
>>>>>> 2010-10-25 17:00:58.296875 [DEBUG] switch_rtp.c:1182 Starting timer
>>>>>> [soft] 160 bytes per 20ms
>>>>>> 2010-10-25 17:00:58.296875 [DEBUG] sofia_glue.c:2774 Set 2833 dtmf send
>>>>>> payload to 101
>>>>>> 2010-10-25 17:00:58.296875 [DEBUG] sofia_glue.c:2779 Set 2833 dtmf
>>>>>> receive payload to 101
>>>>>> 2010-10-25 17:00:58.296875 [DEBUG] switch_channel.c:2324 Send signal
>>>>>> sofia/internal/50 at 192.168.1.114 [BREAK]
>>>>>> 2010-10-25 17:00:58.296875 [NOTICE] sofia.c:4733 Channel
>>>>>> [sofia/internal/sip:60 at 192.168.1.114:52111] has been answered
>>>>>> 2010-10-25 17:00:58.312500 [NOTICE] switch_channel.c:2424 Hangup
>>>>>> sofia/internal/50 at 192.168.1.114 [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
>>>>>> 2010-10-25 17:00:58.312500 [INFO] mod_ebv.cpp:1264 leavin
>>>>>> ebv_process_call_extern
>>>>>> 2010-10-25 17:00:58.312500 [DEBUG] switch_channel.c:2102 Send signal
>>>>>> sofia/internal/50 at 192.168.1.114 [KILL]
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] switch_core_state_machine.c:348
>>>>>> (sofia/internal/50 at 192.168.1.114) State EXECUTE going to sleep
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] switch_core_session.c:1021 Send
>>>>>> signal sofia/internal/50 at 192.168.1.114 [BREAK]
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] switch_core_state_machine.c:314
>>>>>> (sofia/internal/50 at 192.168.1.114) Running State Change CS_HANGUP
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] switch_ivr_originate.c:3111
>>>>>> sofia/internal/50 at 192.168.1.114 Media Establishment Failed.
>>>>>> 2010-10-25 17:01:05.375000 [NOTICE] switch_ivr_originate.c:3113 Hangup
>>>>>> sofia/internal/sip:60 at 192.168.1.114:52111 [CS_CONSUME_MEDIA]
>>>>>> [INCOMPATIBLE_DESTINATION]
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] switch_channel.c:2102 Send signal
>>>>>> sofia/internal/sip:60 at 192.168.1.114:52111 [KILL]
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] switch_core_session.c:1021 Send
>>>>>> signal sofia/internal/sip:60 at 192.168.1.114:52111 [BREAK]
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] switch_core_state_machine.c:314
>>>>>> (sofia/internal/sip:60 at 192.168.1.114:52111) Running State Change CS_HANGUP
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] switch_ivr_originate.c:3228 Originate
>>>>>> Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
>>>>>> 2010-10-25 17:01:05.375000 [INFO] mod_ebv.cpp:2218 Originate Failed.
>>>>>> Cause: INCOMPATIBLE_DESTINATION
>>>>>> 2010-10-25 17:01:05.375000 [INFO] mod_ebv.cpp:154 .... Bridge-Call
>>>>>> Thread beendet!!
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] switch_core_state_machine.c:499
>>>>>> (sofia/internal/50 at 192.168.1.114) State HANGUP
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] mod_sofia.c:414 Channel
>>>>>> sofia/internal/50 at 192.168.1.114 hanging up, cause: INCOMPATIBLE_DESTINATION
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] switch_core_state_machine.c:499
>>>>>> (sofia/internal/sip:60 at 192.168.1.114:52111) State HANGUP
>>>>>> 2010-10-25 17:01:05.375000 [DEBUG] mod_sofia.c:414 Channel
>>>>>> sofia/internal/sip:60 at 192.168.1.114:52111 hanging up, cause:
>>>>>> INCOMPATIBLE_DESTINATION
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] mod_sofia.c:476 Responding to INVITE
>>>>>> with: 488
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:46
>>>>>> sofia/internal/50 at 192.168.1.114 Standard HANGUP, cause:
>>>>>> INCOMPATIBLE_DESTINATION
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:499
>>>>>> (sofia/internal/50 at 192.168.1.114) State HANGUP going to sleep
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:333
>>>>>> (sofia/internal/50 at 192.168.1.114) State Change CS_HANGUP -> CS_REPORTING
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_session.c:1021 Send
>>>>>> signal sofia/internal/50 at 192.168.1.114 [BREAK]
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:314
>>>>>> (sofia/internal/50 at 192.168.1.114) Running State Change CS_REPORTING
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:590
>>>>>> (sofia/internal/50 at 192.168.1.114) State REPORTING
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:53
>>>>>> sofia/internal/50 at 192.168.1.114 Standard REPORTING, cause:
>>>>>> INCOMPATIBLE_DESTINATION
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:590
>>>>>> (sofia/internal/50 at 192.168.1.114) State REPORTING going to sleep
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:327
>>>>>> (sofia/internal/50 at 192.168.1.114) State Change CS_REPORTING -> CS_DESTROY
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_session.c:1021 Send
>>>>>> signal sofia/internal/50 at 192.168.1.114 [BREAK]
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_session.c:1164 Session 23
>>>>>> (sofia/internal/50 at 192.168.1.114) Locked, Waiting on external entities
>>>>>> 2010-10-25 17:01:05.390625 [NOTICE] switch_core_session.c:1182 Session
>>>>>> 23 (sofia/internal/50 at 192.168.1.114) Ended
>>>>>> 2010-10-25 17:01:05.390625 [NOTICE] switch_core_session.c:1184 Close
>>>>>> Channel sofia/internal/50 at 192.168.1.114 [CS_DESTROY]
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:428
>>>>>> (sofia/internal/50 at 192.168.1.114) Running State Change CS_DESTROY
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:439
>>>>>> (sofia/internal/50 at 192.168.1.114) State DESTROY
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] mod_sofia.c:341
>>>>>> sofia/internal/50 at 192.168.1.114 SOFIA DESTROY
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:60
>>>>>> sofia/internal/50 at 192.168.1.114 Standard DESTROY
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:439
>>>>>> (sofia/internal/50 at 192.168.1.114) State DESTROY going to sleep
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] mod_sofia.c:457 Sending BYE to
>>>>>> sofia/internal/sip:60 at 192.168.1.114:52111
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:46
>>>>>> sofia/internal/sip:60 at 192.168.1.114:52111 Standard HANGUP, cause:
>>>>>> INCOMPATIBLE_DESTINATION
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:499
>>>>>> (sofia/internal/sip:60 at 192.168.1.114:52111) State HANGUP going to sleep
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:333
>>>>>> (sofia/internal/sip:60 at 192.168.1.114:52111) State Change CS_HANGUP ->
>>>>>> CS_REPORTING
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_session.c:1021 Send
>>>>>> signal sofia/internal/sip:60 at 192.168.1.114:52111 [BREAK]
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:314
>>>>>> (sofia/internal/sip:60 at 192.168.1.114:52111) Running State Change
>>>>>> CS_REPORTING
>>>>>> 2010-10-25 17:01:05.390625 [DEBUG] switch_core_state_machine.c:590
>>>>>> (sofia/internal/sip:60 at 192.168.1.114:52111) State REPORTING
>>>>>> 2010-10-25 17:01:05.406250 [DEBUG] switch_core_state_machine.c:53
>>>>>> sofia/internal/sip:60 at 192.168.1.114:52111 Standard REPORTING, cause:
>>>>>> INCOMPATIBLE_DESTINATION
>>>>>> 2010-10-25 17:01:05.406250 [DEBUG] switch_core_state_machine.c:590
>>>>>> (sofia/internal/sip:60 at 192.168.1.114:52111) State REPORTING going to sleep
>>>>>> 2010-10-25 17:01:05.406250 [DEBUG] switch_core_state_machine.c:327
>>>>>> (sofia/internal/sip:60 at 192.168.1.114:52111) State Change CS_REPORTING ->
>>>>>> CS_DESTROY
>>>>>> 2010-10-25 17:01:05.406250 [DEBUG] switch_core_session.c:1021 Send
>>>>>> signal sofia/internal/sip:60 at 192.168.1.114:52111 [BREAK]
>>>>>> 2010-10-25 17:01:05.406250 [DEBUG] switch_core_session.c:1164 Session 24
>>>>>> (sofia/internal/sip:60 at 192.168.1.114:52111) Locked, Waiting on external
>>>>>> entities
>>>>>>
>>>>>> --
>>>>>> Achim Stamm, Dipl.-Inform. (FH)
>>>>>>
>>>>>>
>>>>>> Lyncker & Theis GmbH
>>>>>> Wilhelmstr. 16
>>>>>> 65185 Wiesbaden
>>>>>> Germany
>>>>>>
>>>>>> Fon +49 611/9006951
>>>>>> Fax +49 611/9406125
>>>>>>
>>>>>>
>>>>>> Handelsregister: HRB 23156 Amtsgericht Wiesbaden
>>>>>> Steuernummer: 04323897052
>>>>>> USt-IdNr.: DE255806399
>>>>>>
>>>>>> Geschäftsführer:
>>>>>> Filip Lyncker,
>>>>>> Armin Theis
>>>>>>
>>>>>>
>>>>>> _______________________________________________
>>>>>> FreeSWITCH-dev mailing list
>>>>>> FreeSWITCH-dev at lists.freeswitch.org
>>>>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev
>>>>>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev
>>>>>> http://www.freeswitch.org
>>>>>>
>>>>>>
>>>>>>
>>>>>>             
>>>>>
>>>>>           
>>>> Hello Anthony,
>>>>
>>>> mod_ebv is an own module implementation.
>>>> Here i used following code for bridging my current session to multiple
>>>> Voip Phones
>>>> in a freeswitch thread (same as bgapi):
>>>>
>>>> switch_ivr_originate(session, &peer_session, &cause,
>>>> "user/60 at 192.168.1.114,user/61 at 192.168.1.114", timelimit, NULL, NULL,
>>>> NULL, NULL, NULL, SOF_NONE,NULL) != SWITCH_STATUS_SUCCESS)
>>>>
>>>> Both Phones with number 60 and 61 rings.
>>>> The problem with INCOMPATIBLE_DESTINATION occurs also with two X-Lites.
>>>>
>>>> regards
>>>>
>>>> Achim Stamm
>>>>
>>>> --
>>>> Achim Stamm, Dipl.-Inform. (FH)
>>>>
>>>>
>>>> Lyncker & Theis GmbH
>>>> Wilhelmstr. 16
>>>> 65185 Wiesbaden
>>>> Germany
>>>>
>>>> Fon +49 611/9006951
>>>> Fax +49 611/9406125
>>>>
>>>>
>>>> Handelsregister: HRB 23156 Amtsgericht Wiesbaden
>>>> Steuernummer: 04323897052
>>>> USt-IdNr.: DE255806399
>>>>
>>>> Geschäftsführer:
>>>> Filip Lyncker,
>>>> Armin Theis
>>>>
>>>>
>>>> _______________________________________________
>>>> FreeSWITCH-dev mailing list
>>>> FreeSWITCH-dev at lists.freeswitch.org
>>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev
>>>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev
>>>> http://www.freeswitch.org
>>>>
>>>>
>>>>         
>>>
>>>
>>>       
>> Here is a full Log with two X-Lites:
>> EXECUTE sofia/internal/50 at 192.168.1.114 set(ebv_audiosync=10)
>> 2010-10-26 16:12:35.671875 [DEBUG] mod_dptools.c:816
>> sofia/internal/50 at 192.168.1.114 SET [ebv_audiosync]=[10]
>> EXECUTE sofia/internal/50 at 192.168.1.114 set(call_timeout=120)
>> 2010-10-26 16:12:35.671875 [DEBUG] mod_dptools.c:816
>> sofia/internal/50 at 192.168.1.114 SET [call_timeout]=[120]
>> EXECUTE sofia/internal/50 at 192.168.1.114 set(hangup_after_bridge=false)
>> 2010-10-26 16:12:35.687500 [DEBUG] mod_dptools.c:816
>> sofia/internal/50 at 192.168.1.114 SET [hangup_after_bridge]=[false]
>> EXECUTE sofia/internal/50 at 192.168.1.114 set(ignore_early_media=true)
>> 2010-10-26 16:12:35.687500 [DEBUG] mod_dptools.c:816
>> sofia/internal/50 at 192.168.1.114 SET [ignore_early_media]=[true]
>> 2010-10-26 16:12:35.687500 [DEBUG] switch_core_session.c:1751
>> Application easybyvoice Requires media! pre_answering channel
>> sofia/internal/50 at 192.168.1.114
>> 2010-10-26 16:12:35.703125 [INFO] switch_core_session.c:1753 Sending
>> early media
>> 2010-10-26 16:12:35.703125 [DEBUG] sofia_glue.c:2594 AUDIO RTP
>> [sofia/internal/50 at 192.168.1.114] 192.168.1.114 port 27666 ->
>> 192.168.1.114 port 51244 codec: 8 ms: 20
>> 2010-10-26 16:12:35.703125 [DEBUG] switch_rtp.c:1182 Starting timer
>> [soft] 160 bytes per 20ms
>> 2010-10-26 16:12:35.703125 [DEBUG] sofia_glue.c:2774 Set 2833 dtmf send
>> payload to 101
>> 2010-10-26 16:12:35.703125 [DEBUG] sofia_glue.c:2779 Set 2833 dtmf
>> receive payload to 101
>> 2010-10-26 16:12:35.703125 [DEBUG] mod_sofia.c:1904 Ring SDP:
>> v=0
>> o=FreeSWITCH 1288074689 1288074690 IN IP4 192.168.1.114
>> s=FreeSWITCH
>> c=IN IP4 192.168.1.114
>> t=0 0
>> m=audio 27666 RTP/AVP 8 101
>> a=rtpmap:8 PCMA/8000
>> a=rtpmap:101 telephone-event/8000
>> a=fmtp:101 0-16
>> a=silenceSupp:off - - - -
>> a=ptime:20
>> a=sendrecv
>>
>> 2010-10-26 16:12:35.703125 [NOTICE] mod_sofia.c:1907 Pre-Answer
>> sofia/internal/50 at 192.168.1.114!
>> 2010-10-26 16:12:35.703125 [DEBUG] switch_core_session.c:641 Send signal
>> sofia/internal/50 at 192.168.1.114 [BREAK]
>> EXECUTE sofia/internal/50 at 192.168.1.114
>> easybyvoice(EProcessCallExtern,50,70)
>> 2010-10-26 16:12:35.703125 [DEBUG] sofia.c:4148 Channel
>> sofia/internal/50 at 192.168.1.114 skipping state [early][183]
>> 2010-10-26 16:12:35.718750 [WARNING] mod_ebv.cpp:1536 mod_ebv built
>> 14.04.2010 20:15!
>> 2010-10-26 16:12:35.718750 [INFO] mod_ebv.cpp:1120 Die Nebenstelle
>> [user/60 at 192.168.1.114,user/61 at 192.168.1.114] wird angerufen.
>> 2010-10-26 16:12:35.718750 [INFO] Extension.cpp:481 Mailbox-Timer mit
>> [45] Sekunden wird gestartet.
>> 2010-10-26 16:12:35.718750 [INFO] mod_ebv.cpp:152 Starte Bridge-Call
>> Thread ....
>> 2010-10-26 16:12:35.718750 [DEBUG] switch_ivr_originate.c:1885 variable
>> string 0 = [presence_id=60 at 192.168.1.114]
>> 2010-10-26 16:12:35.734375 [NOTICE] switch_channel.c:669 New Channel
>> sofia/internal/sip:60 at 192.168.1.101:10488
>> [11d0f556-8770-408c-be71-f2d5689435d2]
>> 2010-10-26 16:12:35.734375 [DEBUG] mod_sofia.c:3384
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State Change CS_NEW -> CS_INIT
>> 2010-10-26 16:12:35.734375 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:60 at 192.168.1.101:10488 [BREAK]
>> 2010-10-26 16:12:35.734375 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/sip:60 at 192.168.1.101:10488) Running State Change CS_INIT
>> 2010-10-26 16:12:35.734375 [DEBUG] switch_core_state_machine.c:338
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State INIT
>> 2010-10-26 16:12:35.734375 [DEBUG] mod_sofia.c:83
>> sofia/internal/sip:60 at 192.168.1.101:10488 SOFIA INIT
>> 2010-10-26 16:12:35.734375 [DEBUG] mod_sofia.c:117
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State Change CS_INIT ->
>> CS_ROUTING
>> 2010-10-26 16:12:35.734375 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:60 at 192.168.1.101:10488 [BREAK]
>> 2010-10-26 16:12:35.734375 [DEBUG] switch_core_state_machine.c:338
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State INIT going to sleep
>> 2010-10-26 16:12:35.734375 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/sip:60 at 192.168.1.101:10488) Running State Change CS_ROUTING
>> 2010-10-26 16:12:35.734375 [DEBUG] sofia.c:4153 Channel
>> sofia/internal/sip:60 at 192.168.1.101:10488 entering state [calling][0]
>> 2010-10-26 16:12:35.734375 [DEBUG] switch_core_state_machine.c:341
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State ROUTING
>> 2010-10-26 16:12:35.734375 [DEBUG] switch_ivr_originate.c:1885 variable
>> string 0 = [presence_id=61 at 192.168.1.114]
>> 2010-10-26 16:12:35.734375 [DEBUG] mod_sofia.c:140
>> sofia/internal/sip:60 at 192.168.1.101:10488 SOFIA ROUTING
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_ivr_originate.c:66
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State Change CS_ROUTING ->
>> CS_CONSUME_MEDIA
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:60 at 192.168.1.101:10488 [BREAK]
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:341
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State ROUTING going to sleep
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/sip:60 at 192.168.1.101:10488) Running State Change
>> CS_CONSUME_MEDIA
>> 2010-10-26 16:12:35.750000 [NOTICE] switch_channel.c:669 New Channel
>> sofia/internal/sip:61 at 192.168.1.148:15716
>> [f56c3295-51e9-4e65-a6b8-d72e8ecc9832]
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:360
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State CONSUME_MEDIA
>> 2010-10-26 16:12:35.750000 [DEBUG] mod_sofia.c:3384
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State Change CS_NEW -> CS_INIT
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:61 at 192.168.1.148:15716 [BREAK]
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:360
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State CONSUME_MEDIA going to
>> sleep
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/sip:61 at 192.168.1.148:15716) Running State Change CS_INIT
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:338
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State INIT
>> 2010-10-26 16:12:35.750000 [DEBUG] mod_sofia.c:83
>> sofia/internal/sip:61 at 192.168.1.148:15716 SOFIA INIT
>> 2010-10-26 16:12:35.750000 [DEBUG] mod_sofia.c:117
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State Change CS_INIT ->
>> CS_ROUTING
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:61 at 192.168.1.148:15716 [BREAK]
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:338
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State INIT going to sleep
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/sip:61 at 192.168.1.148:15716) Running State Change CS_ROUTING
>> 2010-10-26 16:12:35.750000 [DEBUG] sofia.c:4153 Channel
>> sofia/internal/sip:61 at 192.168.1.148:15716 entering state [calling][0]
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:341
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State ROUTING
>> 2010-10-26 16:12:35.750000 [DEBUG] mod_sofia.c:140
>> sofia/internal/sip:61 at 192.168.1.148:15716 SOFIA ROUTING
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_ivr_originate.c:66
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State Change CS_ROUTING ->
>> CS_CONSUME_MEDIA
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:61 at 192.168.1.148:15716 [BREAK]
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:341
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State ROUTING going to sleep
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/sip:61 at 192.168.1.148:15716) Running State Change
>> CS_CONSUME_MEDIA
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:360
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State CONSUME_MEDIA
>> 2010-10-26 16:12:35.750000 [DEBUG] switch_core_state_machine.c:360
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State CONSUME_MEDIA going to
>> sleep
>> 2010-10-26 16:12:35.796875 [DEBUG] switch_rtp.c:2066 Correct ip/port
>> confirmed.
>> 2010-10-26 16:12:35.890625 [INFO] sofia.c:662 Update Callee ID to "61" <61>
>> 2010-10-26 16:12:35.906250 [DEBUG] sofia.c:4153 Channel
>> sofia/internal/sip:61 at 192.168.1.148:15716 entering state [proceeding][180]
>> 2010-10-26 16:12:35.906250 [NOTICE] sofia.c:4223 Ring-Ready
>> sofia/internal/sip:61 at 192.168.1.148:15716!
>> 2010-10-26 16:12:36.046875 [INFO] sofia.c:662 Update Callee ID to "60" <60>
>> 2010-10-26 16:12:36.062500 [DEBUG] sofia.c:4153 Channel
>> sofia/internal/sip:60 at 192.168.1.101:10488 entering state [proceeding][180]
>> 2010-10-26 16:12:36.062500 [NOTICE] sofia.c:4223 Ring-Ready
>> sofia/internal/sip:60 at 192.168.1.101:10488!
>> 2010-10-26 16:12:52.859375 [DEBUG] sofia.c:4153 Channel
>> sofia/internal/sip:61 at 192.168.1.148:15716 entering state [completing][200]
>> 2010-10-26 16:12:52.859375 [DEBUG] sofia.c:4164 Remote SDP:
>> v=0
>> o=- 5 2 IN IP4 192.168.1.148
>> s=CounterPath eyeBeam 1.5
>> c=IN IP4 192.168.1.148
>> t=0 0
>> m=audio 32706 RTP/AVP 8 101
>> a=rtpmap:101 telephone-event/8000
>> a=fmtp:101 0-15
>> a=x-rtp-session-id:7FD79621EBE842499E1736EE7C1A6E58
>>
>> 2010-10-26 16:12:52.859375 [DEBUG] sofia.c:4153 Channel
>> sofia/internal/sip:61 at 192.168.1.148:15716 entering state [ready][200]
>> 2010-10-26 16:12:52.859375 [DEBUG] sofia_glue.c:3585 Audio Codec Compare
>> [PCMA:8:8000:20]/[PCMA:8:8000:20]
>> 2010-10-26 16:12:52.859375 [DEBUG] sofia_glue.c:2354 Set Codec
>> sofia/internal/sip:61 at 192.168.1.148:15716 PCMA/8000 20 ms 160 samples
>> 2010-10-26 16:12:52.859375 [DEBUG] sofia_glue.c:3518 Set 2833 dtmf send
>> payload to 101
>> 2010-10-26 16:12:52.859375 [DEBUG] sofia_glue.c:2594 AUDIO RTP
>> [sofia/internal/sip:61 at 192.168.1.148:15716] 192.168.1.114 port 17306 ->
>> 192.168.1.148 port 32706 codec: 8 ms: 20
>> 2010-10-26 16:12:52.859375 [DEBUG] switch_rtp.c:1182 Starting timer
>> [soft] 160 bytes per 20ms
>> 2010-10-26 16:12:52.859375 [DEBUG] sofia_glue.c:2774 Set 2833 dtmf send
>> payload to 101
>> 2010-10-26 16:12:52.859375 [DEBUG] sofia_glue.c:2779 Set 2833 dtmf
>> receive payload to 101
>> 2010-10-26 16:12:52.875000 [NOTICE] switch_ivr_originate.c:3079 Hangup
>> sofia/internal/sip:60 at 192.168.1.101:10488 [CS_CONSUME_MEDIA] [LOSE_RACE]
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_channel.c:2324 Send signal
>> sofia/internal/50 at 192.168.1.114 [BREAK]
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_channel.c:2102 Send signal
>> sofia/internal/sip:60 at 192.168.1.101:10488 [KILL]
>> 2010-10-26 16:12:52.875000 [NOTICE] sofia.c:4733 Channel
>> [sofia/internal/sip:61 at 192.168.1.148:15716] has been answered
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:60 at 192.168.1.101:10488 [BREAK]
>> 2010-10-26 16:12:52.875000 [NOTICE] switch_channel.c:2424 Hangup
>> sofia/internal/50 at 192.168.1.114 [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/sip:60 at 192.168.1.101:10488) Running State Change CS_HANGUP
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_channel.c:2102 Send signal
>> sofia/internal/50 at 192.168.1.114 [KILL]
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/50 at 192.168.1.114 [BREAK]
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_ivr_originate.c:3111
>> sofia/internal/50 at 192.168.1.114 Media Establishment Failed.
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/sip:61 at 192.168.1.148:15716) Running State Change CS_HANGUP
>> 2010-10-26 16:12:52.875000 [INFO] mod_ebv.cpp:1264 leavin
>> ebv_process_call_extern
>> 2010-10-26 16:12:52.875000 [NOTICE] switch_ivr_originate.c:3113 Hangup
>> sofia/internal/sip:61 at 192.168.1.148:15716 [CS_CONSUME_MEDIA]
>> [INCOMPATIBLE_DESTINATION]
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_core_state_machine.c:348
>> (sofia/internal/50 at 192.168.1.114) State EXECUTE going to sleep
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/50 at 192.168.1.114) Running State Change CS_HANGUP
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_core_state_machine.c:499
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State HANGUP
>> 2010-10-26 16:12:52.875000 [DEBUG] mod_sofia.c:414 Channel
>> sofia/internal/sip:60 at 192.168.1.101:10488 hanging up, cause: LOSE_RACE
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_channel.c:2102 Send signal
>> sofia/internal/sip:61 at 192.168.1.148:15716 [KILL]
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:61 at 192.168.1.148:15716 [BREAK]
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_ivr_originate.c:3228 Originate
>> Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
>> 2010-10-26 16:12:52.875000 [INFO] mod_ebv.cpp:2219 Originate Failed.
>> Cause: INCOMPATIBLE_DESTINATION
>> 2010-10-26 16:12:52.875000 [INFO] mod_ebv.cpp:154 .... Bridge-Call
>> Thread beendet!!
>> 2010-10-26 16:12:52.875000 [DEBUG] switch_core_state_machine.c:499
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State HANGUP
>> 2010-10-26 16:12:52.875000 [DEBUG] mod_sofia.c:414 Channel
>> sofia/internal/sip:61 at 192.168.1.148:15716 hanging up, cause:
>> INCOMPATIBLE_DESTINATION
>> 2010-10-26 16:12:52.890625 [DEBUG] switch_core_state_machine.c:499
>> (sofia/internal/50 at 192.168.1.114) State HANGUP
>> 2010-10-26 16:12:52.890625 [DEBUG] mod_sofia.c:414 Channel
>> sofia/internal/50 at 192.168.1.114 hanging up, cause: INCOMPATIBLE_DESTINATION
>> 2010-10-26 16:12:52.906250 [DEBUG] mod_sofia.c:467 Sending CANCEL to
>> sofia/internal/sip:60 at 192.168.1.101:10488
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_state_machine.c:46
>> sofia/internal/sip:60 at 192.168.1.101:10488 Standard HANGUP, cause: LOSE_RACE
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_state_machine.c:499
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State HANGUP going to sleep
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_state_machine.c:333
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State Change CS_HANGUP ->
>> CS_REPORTING
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:60 at 192.168.1.101:10488 [BREAK]
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/sip:60 at 192.168.1.101:10488) Running State Change
>> CS_REPORTING
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_state_machine.c:590
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State REPORTING
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_state_machine.c:53
>> sofia/internal/sip:60 at 192.168.1.101:10488 Standard REPORTING, cause:
>> LOSE_RACE
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_state_machine.c:590
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State REPORTING going to sleep
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_state_machine.c:327
>> (sofia/internal/sip:60 at 192.168.1.101:10488) State Change CS_REPORTING ->
>> CS_DESTROY
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:60 at 192.168.1.101:10488 [BREAK]
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_session.c:1164 Session 62
>> (sofia/internal/sip:60 at 192.168.1.101:10488) Locked, Waiting on external
>> entities
>> 2010-10-26 16:12:52.906250 [DEBUG] mod_sofia.c:457 Sending BYE to
>> sofia/internal/sip:61 at 192.168.1.148:15716
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_state_machine.c:46
>> sofia/internal/sip:61 at 192.168.1.148:15716 Standard HANGUP, cause:
>> INCOMPATIBLE_DESTINATION
>> 2010-10-26 16:12:52.906250 [DEBUG] switch_core_state_machine.c:499
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State HANGUP going to sleep
>> 2010-10-26 16:12:52.921875 [DEBUG] switch_core_state_machine.c:333
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State Change CS_HANGUP ->
>> CS_REPORTING
>> 2010-10-26 16:12:52.921875 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:61 at 192.168.1.148:15716 [BREAK]
>> 2010-10-26 16:12:52.921875 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/sip:61 at 192.168.1.148:15716) Running State Change
>> CS_REPORTING
>> 2010-10-26 16:12:52.921875 [DEBUG] switch_core_state_machine.c:590
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State REPORTING
>> 2010-10-26 16:12:52.921875 [DEBUG] switch_core_state_machine.c:53
>> sofia/internal/sip:61 at 192.168.1.148:15716 Standard REPORTING, cause:
>> INCOMPATIBLE_DESTINATION
>> 2010-10-26 16:12:52.921875 [DEBUG] switch_core_state_machine.c:590
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State REPORTING going to sleep
>> 2010-10-26 16:12:52.921875 [DEBUG] switch_core_state_machine.c:327
>> (sofia/internal/sip:61 at 192.168.1.148:15716) State Change CS_REPORTING ->
>> CS_DESTROY
>> 2010-10-26 16:12:52.921875 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/sip:61 at 192.168.1.148:15716 [BREAK]
>> 2010-10-26 16:12:52.921875 [DEBUG] switch_core_session.c:1164 Session 63
>> (sofia/internal/sip:61 at 192.168.1.148:15716) Locked, Waiting on external
>> entities
>> 2010-10-26 16:12:52.921875 [DEBUG] mod_sofia.c:476 Responding to INVITE
>> with: 488
>> 2010-10-26 16:12:52.921875 [DEBUG] switch_core_state_machine.c:46
>> sofia/internal/50 at 192.168.1.114 Standard HANGUP, cause:
>> INCOMPATIBLE_DESTINATION
>> 2010-10-26 16:12:52.921875 [DEBUG] switch_core_state_machine.c:499
>> (sofia/internal/50 at 192.168.1.114) State HANGUP going to sleep
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_state_machine.c:333
>> (sofia/internal/50 at 192.168.1.114) State Change CS_HANGUP -> CS_REPORTING
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/50 at 192.168.1.114 [BREAK]
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/50 at 192.168.1.114) Running State Change CS_REPORTING
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_state_machine.c:590
>> (sofia/internal/50 at 192.168.1.114) State REPORTING
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_state_machine.c:53
>> sofia/internal/50 at 192.168.1.114 Standard REPORTING, cause:
>> INCOMPATIBLE_DESTINATION
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_state_machine.c:590
>> (sofia/internal/50 at 192.168.1.114) State REPORTING going to sleep
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_state_machine.c:327
>> (sofia/internal/50 at 192.168.1.114) State Change CS_REPORTING -> CS_DESTROY
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/50 at 192.168.1.114 [BREAK]
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_session.c:1164 Session 61
>> (sofia/internal/50 at 192.168.1.114) Locked, Waiting on external entities
>> 2010-10-26 16:12:52.937500 [NOTICE] switch_core_session.c:1182 Session
>> 61 (sofia/internal/50 at 192.168.1.114) Ended
>> 2010-10-26 16:12:52.937500 [NOTICE] switch_core_session.c:1184 Close
>> Channel sofia/internal/50 at 192.168.1.114 [CS_DESTROY]
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_state_machine.c:428
>> (sofia/internal/50 at 192.168.1.114) Running State Change CS_DESTROY
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_state_machine.c:439
>> (sofia/internal/50 at 192.168.1.114) State DESTROY
>> 2010-10-26 16:12:52.937500 [DEBUG] mod_sofia.c:341
>> sofia/internal/50 at 192.168.1.114 SOFIA DESTROY
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_state_machine.c:60
>> sofia/internal/50 at 192.168.1.114 Standard DESTROY
>> 2010-10-26 16:12:52.937500 [DEBUG] switch_core_state_machine.c:439
>> (sofia/internal/50 at 192.168.1.114) State DESTROY going to sleep
>> 2010-10-26 16:13:04.296875 [DEBUG] sofia.c:5847 IP 192.168.1.114
>> Rejected by acl "domains". Falling back to Digest auth.
>> 2010-10-26 16:13:04.296875 [DEBUG] sofia.c:5847 IP 192.168.1.114
>> Rejected by acl "domains". Falling back to Digest auth.
>> 2010-10-26 16:13:04.312500 [NOTICE] switch_channel.c:669 New Channel
>> sofia/internal/50 at 192.168.1.114 [164c4fd7-a814-4dc1-af21-585c313914c4]
>> 2010-10-26 16:13:04.312500 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/50 at 192.168.1.114) Running State Change CS_NEW
>> 2010-10-26 16:13:04.312500 [DEBUG] switch_core_state_machine.c:320
>> (sofia/internal/50 at 192.168.1.114) State NEW
>> 2010-10-26 16:13:04.312500 [DEBUG] sofia.c:4153 Channel
>> sofia/internal/50 at 192.168.1.114 entering state [received][100]
>> 2010-10-26 16:13:04.312500 [DEBUG] sofia.c:4164 Remote SDP:
>> v=0
>> o=- 6 2 IN IP4 192.168.1.114
>> s=CounterPath X-Lite 3.0
>> c=IN IP4 192.168.1.114
>> t=0 0
>> m=audio 35824 RTP/AVP 107 0 8 101
>> a=rtpmap:107 BV32/16000
>> a=rtpmap:101 telephone-event/8000
>> a=fmtp:101 0-15
>> a=alt:1 3 : KqvAzzQb 8KWQrMqE 192.168.1.114 35824
>> a=alt:2 2 : 7/J0Lq0c 31UYb9xD 192.168.197.1 35824
>> a=alt:3 1 : KVftFEHN Agl/D+Em 192.168.254.1 35824
>>
>> 2010-10-26 16:13:04.312500 [DEBUG] sofia_glue.c:3585 Audio Codec Compare
>> [BV32:107:16000:20]/[PCMA:8:8000:20]
>> 2010-10-26 16:13:04.312500 [DEBUG] sofia_glue.c:3585 Audio Codec Compare
>> [PCMU:0:8000:20]/[PCMA:8:8000:20]
>> 2010-10-26 16:13:04.312500 [DEBUG] sofia_glue.c:3585 Audio Codec Compare
>> [PCMA:8:8000:20]/[PCMA:8:8000:20]
>> 2010-10-26 16:13:04.312500 [DEBUG] sofia_glue.c:2354 Set Codec
>> sofia/internal/50 at 192.168.1.114 PCMA/8000 20 ms 160 samples
>> 2010-10-26 16:13:04.312500 [DEBUG] sofia_glue.c:3524 Set 2833 dtmf
>> send/recv payload to 101
>> 2010-10-26 16:13:04.312500 [DEBUG] sofia.c:4310
>> (sofia/internal/50 at 192.168.1.114) State Change CS_NEW -> CS_INIT
>> 2010-10-26 16:13:04.328125 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/50 at 192.168.1.114 [BREAK]
>> 2010-10-26 16:13:04.328125 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/50 at 192.168.1.114) Running State Change CS_INIT
>> 2010-10-26 16:13:04.328125 [DEBUG] switch_core_state_machine.c:338
>> (sofia/internal/50 at 192.168.1.114) State INIT
>> 2010-10-26 16:13:04.328125 [DEBUG] mod_sofia.c:83
>> sofia/internal/50 at 192.168.1.114 SOFIA INIT
>> 2010-10-26 16:13:04.328125 [DEBUG] mod_sofia.c:117
>> (sofia/internal/50 at 192.168.1.114) State Change CS_INIT -> CS_ROUTING
>> 2010-10-26 16:13:04.328125 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/50 at 192.168.1.114 [BREAK]
>> 2010-10-26 16:13:04.328125 [DEBUG] switch_core_state_machine.c:338
>> (sofia/internal/50 at 192.168.1.114) State INIT going to sleep
>> 2010-10-26 16:13:04.328125 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/50 at 192.168.1.114) Running State Change CS_ROUTING
>> 2010-10-26 16:13:04.328125 [DEBUG] switch_core_state_machine.c:341
>> (sofia/internal/50 at 192.168.1.114) State ROUTING
>> 2010-10-26 16:13:04.328125 [DEBUG] mod_sofia.c:140
>> sofia/internal/50 at 192.168.1.114 SOFIA ROUTING
>> 2010-10-26 16:13:04.328125 [DEBUG] switch_core_state_machine.c:77
>> sofia/internal/50 at 192.168.1.114 Standard ROUTING
>> 2010-10-26 16:13:04.328125 [INFO] mod_dialplan_xml.c:418 Processing
>> 50->70 in context default
>> Dialplan: sofia/internal/50 at 192.168.1.114 parsing
>> [default->EBV-Nebenstellen-extern] continue=false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (FAIL)
>> [EBV-Nebenstellen-extern] caller_id_number(50) =~ /^6[0-9]$/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 parsing
>> [default->EBV-Systemnummern10_11] continue=false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (FAIL)
>> [EBV-Systemnummern10_11] destination_number(70) =~ /^2[0-9]$/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 parsing
>> [default->EBV-Systemnummern12_13] continue=false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (FAIL)
>> [EBV-Systemnummern12_13] destination_number(70) =~ /^2[0-9]$/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 parsing
>> [default->EBV-Systemnummern14_15] continue=false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (FAIL)
>> [EBV-Systemnummern14_15] destination_number(70) =~ /^2[0-9]$/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 parsing
>> [default->EBV-Systemnummern16_17] continue=false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (FAIL)
>> [EBV-Systemnummern16_17] destination_number(70) =~ /^2[0-9]$/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 parsing
>> [default->EBV-Systemnummern18_19] continue=false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (FAIL)
>> [EBV-Systemnummern18_19] destination_number(70) =~ /^2[0-9]$/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 parsing
>> [default->EBV-Systemnummern] continue=false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (FAIL)
>> [EBV-Systemnummern] destination_number(70) =~ /^2[0-9]$/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 parsing
>> [default->EBV-Mailboxen] continue=false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (FAIL) [EBV-Mailboxen]
>> destination_number(70) =~ /^3[0-9]$/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 parsing [default->EBV-Tuerruf]
>> continue=false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (PASS) [EBV-Tuerruf]
>> destination_number(70) =~ /^7[0-9]$/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (FAIL) [EBV-Tuerruf]
>> caller_id_number(50) =~ /^4[0-9]$/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 parsing
>> [default->EBV-Amtsleitungen_50] continue=false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (PASS)
>> [EBV-Amtsleitungen_50] caller_id_number(50) =~ /^.*/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Regex (PASS)
>> [EBV-Amtsleitungen_50] destination_number(70) =~ /^70$/ break=on-false
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action log(CONSOLE
>> EBV-Amtsleitungen)
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action log(CONSOLE
>> ------------------------)
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action log(CONSOLE
>> caller_id_number   : ${caller_id_number})
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action log(CONSOLE
>> destination_number : ${destination_number})
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action log(CONSOLE user_name :
>> ${user_name})
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action info()
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action
>> set(ebv_nebenstellen=60,61) INLINE
>> EXECUTE sofia/internal/50 at 192.168.1.114 set(ebv_nebenstellen=60,61)
>> 2010-10-26 16:13:04.328125 [DEBUG] mod_dptools.c:816
>> sofia/internal/50 at 192.168.1.114 SET [ebv_nebenstellen]=[60,61]
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action
>> set(ebv_mailbox_record=30) INLINE
>> EXECUTE sofia/internal/50 at 192.168.1.114 set(ebv_mailbox_record=30)
>> 2010-10-26 16:13:04.328125 [DEBUG] mod_dptools.c:816
>> sofia/internal/50 at 192.168.1.114 SET [ebv_mailbox_record]=[30]
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action set(ebv_audiosync=10)
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action set(call_timeout=120)
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action
>> set(hangup_after_bridge=false)
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action
>> set(ignore_early_media=true)
>> Dialplan: sofia/internal/50 at 192.168.1.114 Action
>> easybyvoice(EProcessCallExtern,${user_name},${destination_number})
>> 2010-10-26 16:13:04.328125 [DEBUG] switch_core_state_machine.c:119
>> (sofia/internal/50 at 192.168.1.114) State Change CS_ROUTING -> CS_EXECUTE
>> 2010-10-26 16:13:04.328125 [DEBUG] switch_core_session.c:1021 Send
>> signal sofia/internal/50 at 192.168.1.114 [BREAK]
>>
>>
>>
>> regards
>>
>> Achim Stamm
>>
>> --
>> Achim Stamm, Dipl.-Inform. (FH)
>>
>>
>> Lyncker & Theis GmbH
>> Wilhelmstr. 16
>> 65185 Wiesbaden
>> Germany
>>
>> Fon +49 611/9006951
>> Fax +49 611/9406125
>>
>>
>> Handelsregister: HRB 23156 Amtsgericht Wiesbaden
>> Steuernummer: 04323897052
>> USt-IdNr.: DE255806399
>>
>> Geschäftsführer:
>> Filip Lyncker,
>> Armin Theis
>>
>>
>> _______________________________________________
>> FreeSWITCH-dev mailing list
>> FreeSWITCH-dev at lists.freeswitch.org
>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev
>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev
>> http://www.freeswitch.org
>>
>>     
>
>
>
>   
Thanks,

I can not reproduce INCOMPATIBLE_DESTINATION with latest Sourcen from 
GIT Head.
So it seams that all is working fine. I will continue testing.

Regards

Achim Stamm

-- 
Achim Stamm, Dipl.-Inform. (FH)

Lyncker & Theis GmbH
Wilhelmstr. 16
65185 Wiesbaden
Germany

Fon +49 611/9006951
Fax +49 611/9406125

Handelsregister: HRB 23156 Amtsgericht Wiesbaden
Steuernummer: 04323897052
USt-IdNr.: DE255806399

Geschäftsführer:
Filip Lyncker,
Armin Theis 