[Freeswitch-users] Blind transfer dropped calls

Mon Feb 18 14:35:16 MSK 2013

Hi everyone. I'm not absolute begineer with freeswitch, but pretty new after 
long experience with asterisk.
I'm facing now with an installation (1.3.13b+git~20130213) of FS with an issue 
I can't solve (the same issue was present in 1.0head previous installation), 
so I invoke your help
The scenario is simple: Grandstream (updated firmware) Ip phones connected to 
FS, gateway to SIP VoIP provider via trunk.
Everything works great. Internal phones can talk each oters, calls can came in 
and go out to PSTN without problem, but ...
When a phone call came and an internal phone picks it up, it is impossible to 
blind tranfert that call to onother internal phone.
I mean that the phone i try to transfert the call rings, then it picks up the 
call and the call is dropped.
I suspect is a codec problem, but I can't figure why. All the phones are 
configured the same way.
Here below the log af the process, from external call incoming until the drop.
I can see two suspicious steps, where a "no read codec" error and an 
"incopatible_destination" message appears.
I think I made a trivial error, but I really can't understand where.
Can please anyone point me in the right direction to solve it?
Many thanks  
g

____ call log taken from the cli - some data is ### masked ### ___

2013-01-25 17:15:04.770166 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:04.770166 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:04.770166 [DEBUG] sofia.c:5370 Channel
sofia/sipinterface_1/201 at 192.168.35.80 entering state [received][100]
2013-01-25 17:15:04.770166 [DEBUG] sofia.c:5381 Remote SDP:
v=0
o=201 8000 8004 IN IP4 192.168.35.114
s=SIP Call
c=IN IP4 192.168.35.114
t=0 0
m=audio 5086 RTP/AVP 18 0 8 2 97 9 3 101
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=20
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11
a=sendonly
a=ptime:20

2013-01-25 17:15:04.770166 [DEBUG] sofia.c:5810 Passing SDP to other leg.
v=0
o=201 8000 8004 IN IP4 192.168.35.114
s=SIP Call
c=IN IP4 192.168.35.114
t=0 0
m=audio 5086 RTP/AVP 18 0 8 2 97 9 3 101
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=20
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11
a=sendonly
a=ptime:20

2013-01-25 17:15:04.770166 [DEBUG] switch_channel.c:1554
(sofia/sipinterface_1/201 at 192.168.35.80) Callstate Change ACTIVE -> HELD
2013-01-25 17:15:04.770166 [DEBUG] switch_core_session.c:790 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:04.770166 [DEBUG] mod_sofia.c:1812
sofia/sipinterface_3/347******** ### masked ### Sending media re-direct:
v=0
o=201 8000 8004 IN IP4 192.168.35.114
s=SIP Call
c=IN IP4 192.168.35.114
t=0 0
m=audio 5086 RTP/AVP 18 0 8 2 97 9 3 101
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=20
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11
a=sendonly
a=ptime:20

2013-01-25 17:15:04.770166 [DEBUG] switch_core_session.c:728 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:04.770166 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:04.770166 [DEBUG] sofia.c:5370 Channel
sofia/sipinterface_3/347******** ### masked ### entering state [calling][0]
2013-01-25 17:15:04.810163 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:04.810163 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:04.830180 [DEBUG] sofia.c:4953 Passing 200 OK to other leg
2013-01-25 17:15:04.830180 [DEBUG] switch_core_session.c:790 Send signal
sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:04.830180 [DEBUG] sofia.c:5370 Channel
sofia/sipinterface_3/347******** ### masked ### entering state [completing]
[200]
2013-01-25 17:15:04.830180 [DEBUG] sofia.c:5381 Remote SDP:
v=0
o=root 1558328831 1558328833 IN IP4 xxx.xxx.xxx.xxx
s=VOIP ### provider masked ###
c=IN IP4 xxx.xxx.xxx.xxx #### masked ###
t=0 0
m=audio 46518 RTP/AVP 18 3 97 8 0 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=recvonly
a=ptime:20

2013-01-25 17:15:04.830180 [DEBUG] mod_sofia.c:2363 Responding with 200 [OK]
2013-01-25 17:15:04.830180 [DEBUG] switch_core_session.c:728 Send signal
sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:04.830180 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:04.830180 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:04.830180 [DEBUG] sofia.c:5370 Channel
sofia/sipinterface_3/347******** ### masked ### entering state [ready][200]
2013-01-25 17:15:04.830180 [DEBUG] switch_core_session.c:790 Send signal
sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:04.830180 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:04.830180 [DEBUG] sofia.c:5370 Channel
sofia/sipinterface_1/201 at 192.168.35.80 entering state [completed][200]
2013-01-25 17:15:04.910172 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:04.910172 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:04.910172 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:04.910172 [DEBUG] sofia.c:5370 Channel
sofia/sipinterface_1/201 at 192.168.35.80 entering state [ready][200]
2013-01-25 17:15:09.270174 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:09.270174 [DEBUG] sofia.c:6356 Process REFER to
[203 at 192.168.35.80]
2013-01-25 17:15:09.270174 [DEBUG] switch_channel.c:2842
(sofia/sipinterface_1/201 at 192.168.35.80) Callstate Change HELD -> HANGUP
2013-01-25 17:15:09.270174 [NOTICE] switch_ivr.c:1696 Hangup
sofia/sipinterface_1/201 at 192.168.35.80 [CS_HIBERNATE] [BLIND_TRANSFER]
2013-01-25 17:15:09.270174 [DEBUG] switch_channel.c:2865 Send signal
sofia/sipinterface_1/201 at 192.168.35.80 [KILL]
2013-01-25 17:15:09.270174 [DEBUG] switch_ivr_bridge.c:929
sofia/sipinterface_1/201 at 192.168.35.80 skip receive message [UNBRIDGE]
(channel is hungup already)
2013-01-25 17:15:09.270174 [DEBUG] switch_core_session.c:1179 Send
signal sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:09.270174 [DEBUG] switch_ivr.c:1705
(sofia/sipinterface_3/347******** ### masked ###) State Change CS_HIBERNATE -> 
CS_ROUTING
2013-01-25 17:15:09.270174 [DEBUG] switch_core_session.c:728 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:09.270174 [DEBUG] switch_core_session.c:1179 Send
signal sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:09.270174 [DEBUG] switch_core_session.c:728 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:09.270174 [NOTICE] switch_ivr.c:1711 Transfer
sofia/sipinterface_3/347******** ### masked ### to XML[203 at context_1]
2013-01-25 17:15:09.270174 [DEBUG] switch_core_state_machine.c:362
(sofia/sipinterface_1/201 at 192.168.35.80) Running State Change CS_HANGUP
2013-01-25 17:15:09.270174 [DEBUG] switch_core_state_machine.c:362
(sofia/sipinterface_3/347******** ### masked ###) Running State Change 
CS_ROUTING
2013-01-25 17:15:09.270174 [DEBUG] switch_channel.c:1880
(sofia/sipinterface_3/347******** ### masked ###) Callstate Change ACTIVE -> 
RINGING
2013-01-25 17:15:09.270174 [DEBUG] switch_core_state_machine.c:410
(sofia/sipinterface_3/347******** ### masked ###) State ROUTING
2013-01-25 17:15:09.270174 [DEBUG] mod_sofia.c:148
sofia/sipinterface_3/347******** ### masked ### SOFIA ROUTING
2013-01-25 17:15:09.270174 [DEBUG] switch_core_state_machine.c:104
sofia/sipinterface_3/347******** ### masked ### Standard ROUTING
2013-01-25 17:15:09.270174 [DEBUG] switch_core_state_machine.c:602
(sofia/sipinterface_1/201 at 192.168.35.80) State HANGUP
2013-01-25 17:15:09.270174 [DEBUG] mod_sofia.c:465 Channel
sofia/sipinterface_1/201 at 192.168.35.80 hanging up, cause: BLIND_TRANSFER
2013-01-25 17:15:09.270174 [DEBUG] mod_sofia.c:509 Sending BYE to
sofia/sipinterface_1/201 at 192.168.35.80
2013-01-25 17:15:09.270174 [DEBUG] switch_core_state_machine.c:47
sofia/sipinterface_1/201 at 192.168.35.80 Standard HANGUP, cause:
BLIND_TRANSFER
2013-01-25 17:15:09.270174 [DEBUG] switch_core_state_machine.c:602
(sofia/sipinterface_1/201 at 192.168.35.80) State HANGUP going to sleep
2013-01-25 17:15:09.270174 [DEBUG] switch_core_state_machine.c:393
(sofia/sipinterface_1/201 at 192.168.35.80) State Change CS_HANGUP ->
CS_REPORTING
2013-01-25 17:15:09.270174 [DEBUG] switch_core_session.c:1179 Send
signal sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:09.270174 [DEBUG] switch_core_state_machine.c:362
(sofia/sipinterface_1/201 at 192.168.35.80) Running State Change CS_REPORTING
2013-01-25 17:15:09.270174 [DEBUG] switch_core_state_machine.c:662
(sofia/sipinterface_1/201 at 192.168.35.80) State REPORTING
2013-01-25 17:15:09.270174 [DEBUG] switch_nat.c:510 mapped public port
29868 protocol UDP to localport 29868
2013-01-25 17:15:09.290165 [DEBUG] switch_nat.c:510 mapped public port
29869 protocol UDP to localport 29869
2013-01-25 17:15:09.290165 [DEBUG] switch_core_session.c:728 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:09.290165 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:09.330130 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:09.330130 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:09.330130 [DEBUG] switch_core_session.c:874 Send signal
sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:09.730204 [DEBUG] switch_core_state_machine.c:79
sofia/sipinterface_1/201 at 192.168.35.80 Standard REPORTING, cause:
BLIND_TRANSFER
2013-01-25 17:15:09.730204 [DEBUG] switch_core_state_machine.c:662
(sofia/sipinterface_1/201 at 192.168.35.80) State REPORTING going to sleep
2013-01-25 17:15:09.730204 [DEBUG] switch_core_state_machine.c:387
(sofia/sipinterface_1/201 at 192.168.35.80) State Change CS_REPORTING ->
CS_DESTROY
2013-01-25 17:15:09.730204 [DEBUG] switch_core_session.c:1179 Send
signal sofia/sipinterface_1/201 at 192.168.35.80 [BREAK]
2013-01-25 17:15:09.730204 [DEBUG] switch_core_session.c:1379 Session 77
(sofia/sipinterface_1/201 at 192.168.35.80) Locked, Waiting on external
entities
2013-01-25 17:15:09.730204 [NOTICE] switch_core_session.c:1397 Session
77 (sofia/sipinterface_1/201 at 192.168.35.80) Ended
2013-01-25 17:15:09.730204 [NOTICE] switch_core_session.c:1399 Close
Channel sofia/sipinterface_1/201 at 192.168.35.80 [CS_DESTROY]
2013-01-25 17:15:09.730204 [DEBUG] switch_core_state_machine.c:491
(sofia/sipinterface_1/201 at 192.168.35.80) Callstate Change HANGUP -> DOWN
2013-01-25 17:15:09.730204 [DEBUG] switch_core_state_machine.c:494
(sofia/sipinterface_1/201 at 192.168.35.80) Running State Change CS_DESTROY
2013-01-25 17:15:09.730204 [DEBUG] switch_core_state_machine.c:504
(sofia/sipinterface_1/201 at 192.168.35.80) State DESTROY
2013-01-25 17:15:09.730204 [DEBUG] mod_sofia.c:370
sofia/sipinterface_1/201 at 192.168.35.80 SOFIA DESTROY
2013-01-25 17:15:09.730204 [DEBUG] switch_core_state_machine.c:86
sofia/sipinterface_1/201 at 192.168.35.80 Standard DESTROY
2013-01-25 17:15:09.730204 [DEBUG] switch_core_state_machine.c:504
(sofia/sipinterface_1/201 at 192.168.35.80) State DESTROY going to sleep
2013-01-25 17:15:41.650174 [ERR] switch_core_io.c:131
sofia/sipinterface_3/347******** ### masked ### has no read codec.
2013-01-25 17:15:41.650174 [DEBUG] switch_channel.c:2842
(sofia/sipinterface_3/347******** ### masked ###) Callstate Change RINGING -> 
HANGUP
2013-01-25 17:15:41.650174 [NOTICE] switch_core_io.c:132 Hangup
sofia/sipinterface_3/347******** ### masked ### [CS_ROUTING] 
[INCOMPATIBLE_DESTINATION]
2013-01-25 17:15:41.650174 [DEBUG] switch_channel.c:2865 Send signal
sofia/sipinterface_3/347******** ### masked ### [KILL]
2013-01-25 17:15:41.650174 [DEBUG] switch_core_session.c:1179 Send
signal sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:41.650174 [INFO] mod_dialplan_xml.c:481 Processing
Gianbattista Gallus <0707966538>->203 in context context_1
Dialplan: sofia/sipinterface_3/347******** ### masked ### parsing
[context_1->conditioning_callerid] continue=true
Dialplan: sofia/sipinterface_3/347******** ### masked ### Regex (FAIL)
[conditioning_callerid] ${internal_caller_id_number}() =~ /^.+$/
break=on-false
Dialplan: sofia/sipinterface_3/347******** ### masked ### parsing
[context_1->postroute_global] continue=true
Dialplan: sofia/sipinterface_3/347******** ### masked ### Absolute Condition
[postroute_global]
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action
hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action
hash(insert/${domain_name}-
last_dial/${caller_id_number}/${destination_number})

Dialplan: sofia/sipinterface_3/347******** ### masked ### Action
hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action
set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/sipinterface_3/347******** ### masked ### parsing
[context_1->main_trunk_1_pattern_5] continue=true
Dialplan: sofia/sipinterface_3/347******** ### masked ### Regex (FAIL)
[main_trunk_1_pattern_5] destination_number(203) =~ /^0(\d+)$/
break=on-false
Dialplan: sofia/sipinterface_3/347******** ### masked ### parsing
[context_1->main_number_2] continue=true
Dialplan: sofia/sipinterface_3/347******** ### masked ### Regex (FAIL) 
[main_number_2]
destination_number(203) =~ /^999$/ break=on-false
Dialplan: sofia/sipinterface_3/347******** ### masked ### parsing
[context_1->main_number_4] continue=true
Dialplan: sofia/sipinterface_3/347******** ### masked ### Regex (FAIL) 
[main_number_4]
destination_number(203) =~ /^201$/ break=on-false
Dialplan: sofia/sipinterface_3/347******** ### masked ### parsing
[context_1->main_number_5] continue=true
Dialplan: sofia/sipinterface_3/347******** ### masked ### Regex (FAIL) 
[main_number_5]
destination_number(203) =~ /^202$/ break=on-false
Dialplan: sofia/sipinterface_3/347******** ### masked ### parsing
[context_1->main_number_6] continue=true
Dialplan: sofia/sipinterface_3/347******** ### masked ### Regex (PASS) 
[main_number_6]
destination_number(203) =~ /^203$/ break=on-false
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action
set(hangup_after_bridge=true)
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action 
set(continue_on_fail=true)
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action 
set(vm_auto_play=false)
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action 
set(call_timeout=20)
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action 
set(ringback=${us-ring})
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action
set(transfer_ringback=${us-ring})
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action
export(sip_callee_id_name=Vanna Cuccu)
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action
export(sip_callee_id_number=203)
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action
bridge(user/203 at 192.168.35.80)
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action answer()
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action 
voicemail(default
voicemail_1 203)
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action hangup()
Dialplan: sofia/sipinterface_3/347******** ### masked ### parsing
[context_1->main_number_7] continue=true
Dialplan: sofia/sipinterface_3/347******** ### masked ### Regex (FAIL) 
[main_number_7]
destination_number(203) =~ /^204$/ break=on-false
Dialplan: sofia/sipinterface_3/347******** ### masked ### parsing
[context_1->main_number_8] continue=true
Dialplan: sofia/sipinterface_3/347******** ### masked ### Regex (FAIL) 
[main_number_8]
destination_number(203) =~ /^200$/ break=on-false
Dialplan: sofia/sipinterface_3/347******** ### masked ### parsing [context_1-
>catchall]
continue=false
Dialplan: sofia/sipinterface_3/347******** ### masked ### Regex (PASS) 
[catchall]
destination_number(203) =~ /^(\d+)$/ break=on-false
Dialplan: sofia/sipinterface_3/347******** ### masked ### Action
playback(misc/invalid_extension.wav)
2013-01-25 17:15:41.670347 [INFO] switch_channel.c:2704
sofia/sipinterface_3/347******** ### masked ### Flipping CID from "Gianbattista 
Gallus"
<0707966538> to "Outbound Call" <347******** ### masked ###>
2013-01-25 17:15:41.670347 [DEBUG] switch_core_state_machine.c:410
(sofia/sipinterface_3/347******** ### masked ###) State ROUTING going to sleep
2013-01-25 17:15:41.670347 [DEBUG] switch_core_state_machine.c:362
(sofia/sipinterface_3/347******** ### masked ###) Running State Change 
CS_HANGUP
2013-01-25 17:15:41.670347 [DEBUG] switch_core_state_machine.c:602
(sofia/sipinterface_3/347******** ### masked ###) State HANGUP
2013-01-25 17:15:41.670347 [DEBUG] mod_sofia.c:465 Channel
sofia/sipinterface_3/347******** ### masked ### hanging up, cause: 
INCOMPATIBLE_DESTINATION
2013-01-25 17:15:41.670347 [DEBUG] mod_sofia.c:509 Sending BYE to
sofia/sipinterface_3/347******** ### masked ###
2013-01-25 17:15:41.670347 [DEBUG] switch_core_state_machine.c:47
sofia/sipinterface_3/347******** ### masked ### Standard HANGUP, cause:
INCOMPATIBLE_DESTINATION
2013-01-25 17:15:41.670347 [DEBUG] switch_core_state_machine.c:602
(sofia/sipinterface_3/347******** ### masked ###) State HANGUP going to sleep
2013-01-25 17:15:41.670347 [DEBUG] switch_core_state_machine.c:393
(sofia/sipinterface_3/347******** ### masked ###) State Change CS_HANGUP -> 
CS_REPORTING
2013-01-25 17:15:41.670347 [DEBUG] switch_core_session.c:1179 Send
signal sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:41.670347 [DEBUG] switch_core_state_machine.c:362
(sofia/sipinterface_3/347******** ### masked ###) Running State Change 
CS_REPORTING
2013-01-25 17:15:41.670347 [DEBUG] switch_core_state_machine.c:662
(sofia/sipinterface_3/347******** ### masked ###) State REPORTING
2013-01-25 17:15:42.090203 [DEBUG] switch_core_state_machine.c:79
sofia/sipinterface_3/347******** ### masked ### Standard REPORTING, cause:
INCOMPATIBLE_DESTINATION
2013-01-25 17:15:42.090203 [DEBUG] switch_core_state_machine.c:662
(sofia/sipinterface_3/347******** ### masked ###) State REPORTING going to 
sleep
2013-01-25 17:15:42.090203 [DEBUG] switch_core_state_machine.c:387
(sofia/sipinterface_3/347******** ### masked ###) State Change CS_REPORTING -> 
CS_DESTROY
2013-01-25 17:15:42.090203 [DEBUG] switch_core_session.c:1179 Send
signal sofia/sipinterface_3/347******** ### masked ### [BREAK]
2013-01-25 17:15:42.090203 [DEBUG] switch_core_session.c:1379 Session 78
(sofia/sipinterface_3/347******** ### masked ###) Locked, Waiting on external 
entities
2013-01-25 17:15:42.090203 [NOTICE] switch_core_session.c:1397 Session
78 (sofia/sipinterface_3/347******** ### masked ###) Ended
2013-01-25 17:15:42.090203 [NOTICE] switch_core_session.c:1399 Close
Channel sofia/sipinterface_3/347******** ### masked ### [CS_DESTROY]
2013-01-25 17:15:42.090203 [DEBUG] switch_core_state_machine.c:491
(sofia/sipinterface_3/347******** ### masked ###) Callstate Change HANGUP -> 
DOWN
2013-01-25 17:15:42.090203 [DEBUG] switch_core_state_machine.c:494
(sofia/sipinterface_3/347******** ### masked ###) Running State Change 
CS_DESTROY
2013-01-25 17:15:42.090203 [DEBUG] switch_core_state_machine.c:504
(sofia/sipinterface_3/347******** ### masked ###) State DESTROY
2013-01-25 17:15:42.090203 [DEBUG] mod_sofia.c:370
sofia/sipinterface_3/347******** ### masked ### SOFIA DESTROY
2013-01-25 17:15:42.110145 [DEBUG] switch_nat.c:570 unmapped public port
29868 protocol UDP to localport 29868
2013-01-25 17:15:42.130212 [DEBUG] switch_nat.c:570 unmapped public port
29869 protocol UDP to localport 29869
2013-01-25 17:15:42.130212 [DEBUG] switch_core_state_machine.c:86
sofia/sipinterface_3/347******** ### masked ### Standard DESTROY
2013-01-25 17:15:42.130212 [DEBUG] switch_core_state_machine.c:504
(sofia/sipinterface_3/347******** ### masked ###) State DESTROY going to sleep