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

Tue Oct 26 08:51:04 PDT 2010

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