[Freeswitch-users] Sound issue on bridging one inbound gateway call to another gateway

Mon Oct 28 12:28:43 MSK 2013

Hy,

I see no answer so maybe you need some more informations : I put here the
logs...

<<<
2013-10-25 11:22:42.752485 [NOTICE] switch_channel.c:1034 New Channel
sofia/external/<nb_caller>@<my_provider>
[495c9dd1-1713-4dd1-b41d-cf1a53537e52]
2013-10-25 11:22:42.752485 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:42.752485 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:42.752485 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_caller>@<my_provider>) Running State Change CS_NEW
2013-10-25 11:22:42.752485 [DEBUG] switch_core_state_machine.c:436
(sofia/external/<nb_caller>@<my_provider>) State NEW
2013-10-25 11:22:42.772480 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_caller>@<my_provider> entering state [received][100]
2013-10-25 11:22:42.772480 [DEBUG] sofia.c:5844 Remote SDP:
v=0
o=cp10 138269296293 138269296293 IN IP4 10.7.1.129
s=SIP Call
c=IN IP4 91.121.128.144
t=0 0
m=audio 38132 RTP/AVP 18 4 0 8 125 111 101
b=AS:21
a=rtpmap:18 G729/8000/1
a=fmtp:18 annexb=no
a=rtpmap:4 G723/8000/1
a=fmtp:4 annexa=no
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:125 CLEARMODE/8000/1
a=rtpmap:111 iLBC/8000/1
a=fmtp:111 mode=30
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30

2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G729:18:8000:30:8000]/[G7221:115:32000:20:48000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G729:18:8000:30:8000]/[G7221:107:16000:20:32000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G729:18:8000:30:8000]/[G722:9:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G729:18:8000:30:8000]/[PCMU:0:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G729:18:8000:30:8000]/[PCMA:8:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G729:18:8000:30:8000]/[GSM:3:8000:20:13200]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G723:4:8000:30:6300]/[G7221:115:32000:20:48000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G723:4:8000:30:6300]/[G7221:107:16000:20:32000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G723:4:8000:30:6300]/[G722:9:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G723:4:8000:30:6300]/[PCMU:0:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G723:4:8000:30:6300]/[PCMA:8:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [G723:4:8000:30:6300]/[GSM:3:8000:20:13200]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMU:0:8000:30:64000]/[G7221:115:32000:20:48000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMU:0:8000:30:64000]/[G7221:107:16000:20:32000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMU:0:8000:30:64000]/[G722:9:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMU:0:8000:30:64000]/[PCMU:0:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMU:0:8000:30:64000]/[PCMA:8:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMU:0:8000:30:64000]/[GSM:3:8000:20:13200]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMA:8:8000:30:64000]/[G7221:115:32000:20:48000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMA:8:8000:30:64000]/[G7221:107:16000:20:32000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMA:8:8000:30:64000]/[G722:9:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMA:8:8000:30:64000]/[PCMU:0:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMA:8:8000:30:64000]/[PCMA:8:8000:20:64000]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:1913 Set Codec
sofia/external/<nb_caller>@<my_provider> PCMA/8000 20 ms 160 samples 64000
bits
2013-10-25 11:22:42.782468 [DEBUG] switch_core_codec.c:111
sofia/external/<nb_caller>@<my_provider> Original read codec set to PCMA:8
2013-10-25 11:22:42.782468 [DEBUG] switch_core_media.c:3051 Set 2833 dtmf
send/recv payload to 101
2013-10-25 11:22:42.782468 [DEBUG] sofia.c:6076
(sofia/external/<nb_caller>@<my_provider>) State Change CS_NEW -> CS_INIT
2013-10-25 11:22:42.782468 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_caller>@<my_provider>) Running State Change CS_INIT
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:457
(sofia/external/<nb_caller>@<my_provider>) State INIT
2013-10-25 11:22:42.782468 [DEBUG] mod_sofia.c:87
sofia/external/<nb_caller>@<my_provider> SOFIA INIT
2013-10-25 11:22:42.782468 [DEBUG] mod_sofia.c:114
(sofia/external/<nb_caller>@<my_provider>) State Change CS_INIT ->
CS_ROUTING
2013-10-25 11:22:42.782468 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:457
(sofia/external/<nb_caller>@<my_provider>) State INIT going to sleep
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_caller>@<my_provider>) Running State Change CS_ROUTING
2013-10-25 11:22:42.782468 [DEBUG] switch_channel.c:2120
(sofia/external/<nb_caller>@<my_provider>) Callstate Change DOWN -> RINGING
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:473
(sofia/external/<nb_caller>@<my_provider>) State ROUTING
2013-10-25 11:22:42.782468 [DEBUG] mod_sofia.c:137
sofia/external/<nb_caller>@<my_provider> SOFIA ROUTING
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:117
sofia/external/<nb_caller>@<my_provider> Standard ROUTING
2013-10-25 11:22:42.782468 [INFO] mod_dialplan_xml.c:558 Processing
<nb_caller> <<nb_caller>>-><inbound_gateway_number> in context public
Dialplan: sofia/external/<nb_caller>@<my_provider> parsing [public->unloop]
continue=false
Dialplan: sofia/external/<nb_caller>@<my_provider> Regex (PASS) [unloop]
${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/<nb_caller>@<my_provider> Regex (FAIL) [unloop]
${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/external/<nb_caller>@<my_provider> parsing
[public->outside_call] continue=true
Dialplan: sofia/external/<nb_caller>@<my_provider> Absolute Condition
[outside_call]
Dialplan: sofia/external/<nb_caller>@<my_provider> Action
set(outside_call=true) 
Dialplan: sofia/external/<nb_caller>@<my_provider> parsing
[public->call_debug] continue=true
Dialplan: sofia/external/<nb_caller>@<my_provider> Regex (FAIL) [call_debug]
${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/external/<nb_caller>@<my_provider> parsing
[public->public_extensions] continue=false
Dialplan: sofia/external/<nb_caller>@<my_provider> Regex (FAIL)
[public_extensions] destination_number(<inbound_gateway_number>) =~
/^(10[01][0-9])$/ break=on-false
Dialplan: sofia/external/<nb_caller>@<my_provider> parsing
[public->public_did] continue=false
Dialplan: sofia/external/<nb_caller>@<my_provider> Regex (FAIL) [public_did]
destination_number(<inbound_gateway_number>) =~ /^(5551212)$/ break=on-false
Dialplan: sofia/external/<nb_caller>@<my_provider> parsing
[public->public_openIP] continue=false
Dialplan: sofia/external/<nb_caller>@<my_provider> Regex (FAIL)
[public_openIP] destination_number(<inbound_gateway_number>) =~
/^(970710200)$/ break=on-false
Dialplan: sofia/external/<nb_caller>@<my_provider> parsing
[public->public_ovh] continue=false
Dialplan: sofia/external/<nb_caller>@<my_provider> Regex (FAIL) [public_ovh]
destination_number(<inbound_gateway_number>) =~
/^(<outbound_gateway_number>)$/ break=on-false
Dialplan: sofia/external/<nb_caller>@<my_provider> parsing
[public->transfert_08] continue=false
Dialplan: sofia/external/<nb_caller>@<my_provider> Regex (PASS)
[transfert_08] destination_number(<inbound_gateway_number>) =~
/^(<inbound_gateway_number>)$/ break=on-false
Dialplan: sofia/external/<nb_caller>@<my_provider> Action ring_ready() 
Dialplan: sofia/external/<nb_caller>@<my_provider> Action
bridge(sofia/gateway/outbound02_ovh/<nb_callee>) 
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:167
(sofia/external/<nb_caller>@<my_provider>) State Change CS_ROUTING ->
CS_EXECUTE
2013-10-25 11:22:42.782468 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:473
(sofia/external/<nb_caller>@<my_provider>) State ROUTING going to sleep
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_caller>@<my_provider>) Running State Change CS_EXECUTE
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:480
(sofia/external/<nb_caller>@<my_provider>) State EXECUTE
2013-10-25 11:22:42.782468 [DEBUG] mod_sofia.c:230
sofia/external/<nb_caller>@<my_provider> SOFIA EXECUTE
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:209
sofia/external/<nb_caller>@<my_provider> Standard EXECUTE
EXECUTE sofia/external/<nb_caller>@<my_provider> set(outside_call=true)
2013-10-25 11:22:42.782468 [DEBUG] mod_dptools.c:1395
sofia/external/<nb_caller>@<my_provider> SET [outside_call]=[true]
EXECUTE sofia/external/<nb_caller>@<my_provider> ring_ready()
2013-10-25 11:22:42.782468 [NOTICE] mod_sofia.c:2078 Ring-Ready
sofia/external/<nb_caller>@<my_provider>!
2013-10-25 11:22:42.782468 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:42.782468 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_caller>@<my_provider> entering state [early][180]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_session.c:865 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:42.782468 [NOTICE] mod_dptools.c:941 Ring Ready
sofia/external/<nb_caller>@<my_provider>!
EXECUTE sofia/external/<nb_caller>@<my_provider>
bridge(sofia/gateway/outbound02_ovh/<nb_callee>)
2013-10-25 11:22:42.782468 [DEBUG] switch_ivr_originate.c:2060 Parsing
global variables
2013-10-25 11:22:42.782468 [NOTICE] switch_channel.c:1034 New Channel
sofia/external/<nb_callee> [e564400f-6932-4d3b-b3cb-ba0c7fdac9df]
2013-10-25 11:22:42.782468 [DEBUG] mod_sofia.c:4480
(sofia/external/<nb_callee>) State Change CS_NEW -> CS_INIT
2013-10-25 11:22:42.782468 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_callee>) Running State Change CS_INIT
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:457
(sofia/external/<nb_callee>) State INIT
2013-10-25 11:22:42.782468 [DEBUG] mod_sofia.c:87 sofia/external/<nb_callee>
SOFIA INIT
2013-10-25 11:22:42.782468 [DEBUG] sofia_glue.c:1225 Local SDP:
v=0
o=FreeSWITCH 1382661558 1382661559 IN IP4 91.204.116.116
s=FreeSWITCH
c=IN IP4 91.204.116.116
t=0 0
m=audio 31404 RTP/AVP 8 0 3 101 13
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2013-10-25 11:22:42.782468 [DEBUG] mod_sofia.c:114
(sofia/external/<nb_callee>) State Change CS_INIT -> CS_ROUTING
2013-10-25 11:22:42.782468 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:457
(sofia/external/<nb_callee>) State INIT going to sleep
2013-10-25 11:22:42.782468 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_callee>) Running State Change CS_ROUTING
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:473
(sofia/external/<nb_callee>) State ROUTING
2013-10-25 11:22:42.782468 [DEBUG] mod_sofia.c:137
sofia/external/<nb_callee> SOFIA ROUTING
2013-10-25 11:22:42.782468 [DEBUG] switch_ivr_originate.c:67
(sofia/external/<nb_callee>) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2013-10-25 11:22:42.782468 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:473
(sofia/external/<nb_callee>) State ROUTING going to sleep
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_callee>) Running State Change CS_CONSUME_MEDIA
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:492
(sofia/external/<nb_callee>) State CONSUME_MEDIA
2013-10-25 11:22:42.782468 [DEBUG] switch_core_state_machine.c:492
(sofia/external/<nb_callee>) State CONSUME_MEDIA going to sleep
2013-10-25 11:22:42.782468 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_callee> entering state [calling][0]
2013-10-25 11:22:42.792471 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:42.792471 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:42.792471 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_callee> entering state [calling][0]
2013-10-25 11:22:47.812527 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:47.812527 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:47.812527 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_callee> entering state [proceeding][180]
2013-10-25 11:22:47.812527 [DEBUG] sofia.c:5844 Remote SDP:
v=0
o=cp10 138269296200 138269296201 IN IP4 10.7.1.133
s=SIP Call
c=IN IP4 91.121.128.146
t=0 0
m=audio 33910 RTP/AVP 0 8 101
b=AS:77
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30

2013-10-25 11:22:47.812527 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMU:0:8000:30:64000]/[PCMA:8:8000:20:64000]
2013-10-25 11:22:47.812527 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMU:0:8000:30:64000]/[PCMU:0:8000:20:64000]
2013-10-25 11:22:47.812527 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMU:0:8000:30:64000]/[GSM:3:8000:20:13200]
2013-10-25 11:22:47.812527 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMA:8:8000:30:64000]/[PCMA:8:8000:20:64000]
2013-10-25 11:22:47.812527 [DEBUG] switch_core_media.c:1913 Set Codec
sofia/external/<nb_callee> PCMA/8000 20 ms 160 samples 64000 bits
2013-10-25 11:22:47.812527 [DEBUG] switch_core_codec.c:111
sofia/external/<nb_callee> Original read codec set to PCMA:8
2013-10-25 11:22:47.812527 [DEBUG] switch_core_media.c:3042 Set 2833 dtmf
send payload to 101
2013-10-25 11:22:47.812527 [DEBUG] switch_core_media.c:4098 AUDIO RTP
[sofia/external/<nb_callee>] 91.204.116.116 port 31404 -> 91.121.128.146
port 33910 codec: 8 ms: 20
2013-10-25 11:22:47.812527 [DEBUG] switch_rtp.c:2881 Starting timer [soft]
160 bytes per 20ms
2013-10-25 11:22:47.812527 [DEBUG] switch_core_media.c:4440 Set 2833 dtmf
send payload to 101
2013-10-25 11:22:47.812527 [DEBUG] switch_core_media.c:4446 Set 2833 dtmf
receive payload to 101
2013-10-25 11:22:47.812527 [NOTICE] sofia_media.c:92 Pre-Answer
sofia/external/<nb_callee>!
2013-10-25 11:22:47.812527 [DEBUG] switch_channel.c:3328 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:47.812527 [DEBUG] switch_channel.c:3332
(sofia/external/<nb_callee>) Callstate Change DOWN -> EARLY
2013-10-25 11:22:47.822472 [INFO] switch_ivr_originate.c:3443 Sending early
media
2013-10-25 11:22:47.822472 [DEBUG] switch_core_media.c:4098 AUDIO RTP
[sofia/external/<nb_caller>@<my_provider>] 91.204.116.116 port 28082 ->
91.121.128.144 port 38132 codec: 8 ms: 20
2013-10-25 11:22:47.822472 [DEBUG] switch_rtp.c:2881 Starting timer [soft]
160 bytes per 20ms
2013-10-25 11:22:47.822472 [DEBUG] switch_core_media.c:4440 Set 2833 dtmf
send payload to 101
2013-10-25 11:22:47.822472 [DEBUG] switch_core_media.c:4446 Set 2833 dtmf
receive payload to 101
2013-10-25 11:22:47.822472 [DEBUG] mod_sofia.c:2143 Ring SDP:
v=0
o=FreeSWITCH 1382664885 1382664886 IN IP4 91.204.116.116
s=FreeSWITCH
c=IN IP4 91.204.116.116
t=0 0
m=audio 28082 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2013-10-25 11:22:47.822472 [NOTICE] mod_sofia.c:2146 Pre-Answer
sofia/external/<nb_caller>@<my_provider>!
2013-10-25 11:22:47.822472 [DEBUG] switch_channel.c:3332
(sofia/external/<nb_caller>@<my_provider>) Callstate Change RINGING -> EARLY
2013-10-25 11:22:47.822472 [DEBUG] switch_core_session.c:865 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:47.822472 [DEBUG] switch_ivr_originate.c:3494 Originate
Resulted in Success: [sofia/external/<nb_callee>]
2013-10-25 11:22:47.822472 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:47.822472 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_caller>@<my_provider> entering state [early][183]
2013-10-25 11:22:47.822472 [DEBUG] switch_core_session.c:865 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:47.822472 [DEBUG] switch_core_session.c:865 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:47.822472 [DEBUG] switch_ivr_bridge.c:1440
(sofia/external/<nb_callee>) State Change CS_CONSUME_MEDIA ->
CS_EXCHANGE_MEDIA
2013-10-25 11:22:47.822472 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:47.822472 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_callee>) Running State Change CS_EXCHANGE_MEDIA
2013-10-25 11:22:47.822472 [DEBUG] switch_core_state_machine.c:483
(sofia/external/<nb_callee>) State EXCHANGE_MEDIA
2013-10-25 11:22:47.822472 [DEBUG] mod_sofia.c:644 SOFIA EXCHANGE_MEDIA
2013-10-25 11:22:55.972482 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:55.972482 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:55.992491 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_callee> entering state [completing][200]
2013-10-25 11:22:55.992491 [DEBUG] sofia.c:5841 Duplicate SDP
v=0
o=cp10 138269296200 138269296201 IN IP4 10.7.1.133
s=SIP Call
c=IN IP4 91.121.128.146
t=0 0
m=audio 33910 RTP/AVP 0 8 101
b=AS:77
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30

2013-10-25 11:22:55.992491 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:55.992491 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:55.992491 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:55.992491 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:55.992491 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:56.012473 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_callee> entering state [ready][200]
2013-10-25 11:22:56.012473 [DEBUG] switch_channel.c:3567 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:56.012473 [NOTICE] sofia.c:6526 Channel
[sofia/external/<nb_callee>] has been answered
2013-10-25 11:22:56.012473 [DEBUG] switch_channel.c:3613
(sofia/external/<nb_callee>) Callstate Change EARLY -> ACTIVE
2013-10-25 11:22:56.012473 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_callee> entering state [ready][200]
2013-10-25 11:22:56.012473 [DEBUG] sofia.c:5844 Remote SDP:
v=0
o=cp10 138269296200 138269296202 IN IP4 10.7.1.133
s=SIP Call
c=IN IP4 91.121.128.146
t=0 0
m=audio 33910 RTP/AVP 0 8 101
b=AS:77
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30

2013-10-25 11:22:56.012473 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMU:0:8000:30:64000]/[PCMA:8:8000:20:64000]
2013-10-25 11:22:56.012473 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMA:8:8000:30:64000]/[PCMA:8:8000:20:64000]
2013-10-25 11:22:56.012473 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [telephone-event:101:8000:30:0]/[PCMA:8:8000:20:64000]
2013-10-25 11:22:56.012473 [DEBUG] switch_core_media.c:2961 Substituting
codec PCMA at 30i@8000h
2013-10-25 11:22:56.012473 [DEBUG] switch_core_media.c:1822 Changing Codec
from PCMA at 20ms@8000hz to PCMA at 30ms@8000hz
2013-10-25 11:22:56.032474 [DEBUG] mod_sofia.c:824 Local SDP
sofia/external/<nb_caller>@<my_provider>:
v=0
o=FreeSWITCH 1382664885 1382664887 IN IP4 91.204.116.116
s=FreeSWITCH
c=IN IP4 91.204.116.116
t=0 0
m=audio 28082 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2013-10-25 11:22:56.032474 [DEBUG] switch_core_session.c:865 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:56.032474 [NOTICE] switch_ivr_bridge.c:484 Channel
[sofia/external/<nb_caller>@<my_provider>] has been answered
2013-10-25 11:22:56.032474 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:56.032474 [DEBUG] switch_channel.c:3613
(sofia/external/<nb_caller>@<my_provider>) Callstate Change EARLY -> ACTIVE
2013-10-25 11:22:56.032474 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_caller>@<my_provider> entering state [completed][200]
2013-10-25 11:22:56.032474 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:56.032474 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:56.032474 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:56.052478 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_caller>@<my_provider> entering state [ready][200]
2013-10-25 11:22:56.052478 [DEBUG] switch_core_session.c:927 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:22:56.052478 [DEBUG] switch_core_session.c:927 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:56.052478 [DEBUG] switch_rtp.c:2764 RE-Starting timer
[soft] 240 bytes per 30ms
2013-10-25 11:22:56.052478 [DEBUG] switch_core_media.c:1913 Set Codec
sofia/external/<nb_callee> PCMA/8000 30 ms 240 samples 64000 bits
2013-10-25 11:22:56.052478 [DEBUG] switch_core_codec.c:123
sofia/external/<nb_callee> Original read codec replaced with PCMA:8
2013-10-25 11:22:56.052478 [DEBUG] switch_core_media.c:3042 Set 2833 dtmf
send payload to 101
2013-10-25 11:22:56.052478 [DEBUG] sofia.c:6429 Processing updated SDP
2013-10-25 11:22:56.052478 [DEBUG] switch_core_media.c:4068 Audio params are
unchanged for sofia/external/<nb_callee>.
2013-10-25 11:22:56.052478 [DEBUG] switch_core_media.c:4078
sofia/external/<nb_callee> Setting audio receive payload in Re-INVITE to 8
2013-10-25 11:22:56.052478 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:56.052478 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:56.052478 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:22:56.072495 [DEBUG] sofia.c:5834 Channel
sofia/external/<nb_caller>@<my_provider> entering state [ready][200]
2013-10-25 11:22:56.072495 [DEBUG] sofia.c:5844 Remote SDP:
v=0
o=cp10 138269296293 138269296295 IN IP4 10.7.1.129
s=SIP Call
c=IN IP4 91.121.128.144
t=0 0
m=audio 38132 RTP/AVP 8 101
b=AS:77
a=rtpmap:8 PCMA/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30

2013-10-25 11:22:56.072495 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [PCMA:8:8000:30:64000]/[PCMA:8:8000:20:64000]
2013-10-25 11:22:56.072495 [DEBUG] switch_core_media.c:2880 Audio Codec
Compare [telephone-event:101:8000:30:0]/[PCMA:8:8000:20:64000]
2013-10-25 11:22:56.072495 [DEBUG] switch_core_media.c:2961 Substituting
codec PCMA at 30i@8000h
2013-10-25 11:22:56.072495 [DEBUG] switch_core_media.c:1822 Changing Codec
from PCMA at 20ms@8000hz to PCMA at 30ms@8000hz
2013-10-25 11:22:56.112493 [DEBUG] switch_rtp.c:2764 RE-Starting timer
[soft] 240 bytes per 30ms
2013-10-25 11:22:56.112493 [DEBUG] switch_core_media.c:1913 Set Codec
sofia/external/<nb_caller>@<my_provider> PCMA/8000 30 ms 240 samples 64000
bits
2013-10-25 11:22:56.112493 [DEBUG] switch_core_codec.c:123
sofia/external/<nb_caller>@<my_provider> Original read codec replaced with
PCMA:8
2013-10-25 11:22:56.112493 [DEBUG] switch_core_media.c:3051 Set 2833 dtmf
send/recv payload to 101
2013-10-25 11:22:56.112493 [DEBUG] sofia.c:6429 Processing updated SDP
2013-10-25 11:22:56.112493 [DEBUG] switch_core_media.c:4068 Audio params are
unchanged for sofia/external/<nb_caller>@<my_provider>.
2013-10-25 11:22:56.112493 [DEBUG] switch_core_media.c:4078
sofia/external/<nb_caller>@<my_provider> Setting audio receive payload in
Re-INVITE to 8
2013-10-25 11:23:11.432467 [DEBUG] switch_core_session.c:1010 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:23:11.462472 [NOTICE] sofia.c:715 Hangup
sofia/external/<nb_caller>@<my_provider> [CS_EXECUTE] [NORMAL_CLEARING]
2013-10-25 11:23:11.462472 [DEBUG] switch_channel.c:3139 Send signal
sofia/external/<nb_caller>@<my_provider> [KILL]
2013-10-25 11:23:11.462472 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:23:11.462472 [DEBUG] switch_ivr_bridge.c:647 BRIDGE THREAD
DONE [sofia/external/<nb_caller>@<my_provider>]
2013-10-25 11:23:11.462472 [DEBUG] switch_ivr_bridge.c:672 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:23:11.492469 [DEBUG] switch_ivr_bridge.c:647 BRIDGE THREAD
DONE [sofia/external/<nb_callee>]
2013-10-25 11:23:11.492469 [DEBUG] switch_ivr_bridge.c:672 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:23:11.492469 [NOTICE] switch_ivr_bridge.c:735 Hangup
sofia/external/<nb_callee> [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2013-10-25 11:23:11.492469 [DEBUG] switch_channel.c:3139 Send signal
sofia/external/<nb_callee> [KILL]
2013-10-25 11:23:11.492469 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:483
(sofia/external/<nb_callee>) State EXCHANGE_MEDIA going to sleep
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_callee>) Running State Change CS_HANGUP
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:681
(sofia/external/<nb_callee>) State HANGUP
2013-10-25 11:23:11.492469 [DEBUG] mod_sofia.c:459
sofia/external/<nb_callee> Overriding SIP cause 480 with 200 from the other
leg
2013-10-25 11:23:11.492469 [DEBUG] mod_sofia.c:465 Channel
sofia/external/<nb_callee> hanging up, cause: NORMAL_CLEARING
2013-10-25 11:23:11.492469 [DEBUG] mod_sofia.c:517 Sending BYE to
sofia/external/<nb_callee>
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:48
sofia/external/<nb_callee> Standard HANGUP, cause: NORMAL_CLEARING
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:681
(sofia/external/<nb_callee>) State HANGUP going to sleep
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:694
(sofia/external/<nb_callee>) Callstate Change ACTIVE -> HANGUP
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:449
(sofia/external/<nb_callee>) State Change CS_HANGUP -> CS_REPORTING
2013-10-25 11:23:11.492469 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_callee>) Running State Change CS_REPORTING
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:766
(sofia/external/<nb_callee>) State REPORTING
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:92
sofia/external/<nb_callee> Standard REPORTING, cause: NORMAL_CLEARING
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:766
(sofia/external/<nb_callee>) State REPORTING going to sleep
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:443
(sofia/external/<nb_callee>) State Change CS_REPORTING -> CS_DESTROY
2013-10-25 11:23:11.492469 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_callee> [BREAK]
2013-10-25 11:23:11.492469 [DEBUG] switch_core_session.c:1553 Session 605
(sofia/external/<nb_callee>) Locked, Waiting on external entities
2013-10-25 11:23:11.492469 [DEBUG] switch_ivr_bridge.c:1541
sofia/external/<nb_caller>@<my_provider> skip receive message [UNBRIDGE]
(channel is hungup already)
2013-10-25 11:23:11.492469 [NOTICE] switch_core_session.c:1571 Session 605
(sofia/external/<nb_callee>) Ended
2013-10-25 11:23:11.492469 [NOTICE] switch_core_session.c:1575 Close Channel
sofia/external/<nb_callee> [CS_DESTROY]
2013-10-25 11:23:11.492469 [DEBUG] switch_core_session.c:2814
sofia/external/<nb_caller>@<my_provider> skip receive message
[APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:480
(sofia/external/<nb_caller>@<my_provider>) State EXECUTE going to sleep
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_caller>@<my_provider>) Running State Change CS_HANGUP
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:681
(sofia/external/<nb_caller>@<my_provider>) State HANGUP
2013-10-25 11:23:11.492469 [DEBUG] mod_sofia.c:465 Channel
sofia/external/<nb_caller>@<my_provider> hanging up, cause: NORMAL_CLEARING
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:48
sofia/external/<nb_caller>@<my_provider> Standard HANGUP, cause:
NORMAL_CLEARING
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:681
(sofia/external/<nb_caller>@<my_provider>) State HANGUP going to sleep
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:568
(sofia/external/<nb_callee>) Callstate Change HANGUP -> DOWN
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:694
(sofia/external/<nb_caller>@<my_provider>) Callstate Change ACTIVE -> HANGUP
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:449
(sofia/external/<nb_caller>@<my_provider>) State Change CS_HANGUP ->
CS_REPORTING
2013-10-25 11:23:11.492469 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:418
(sofia/external/<nb_caller>@<my_provider>) Running State Change CS_REPORTING
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:571
(sofia/external/<nb_callee>) Running State Change CS_DESTROY
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:766
(sofia/external/<nb_caller>@<my_provider>) State REPORTING
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:581
(sofia/external/<nb_callee>) State DESTROY
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:92
sofia/external/<nb_caller>@<my_provider> Standard REPORTING, cause:
NORMAL_CLEARING
2013-10-25 11:23:11.492469 [DEBUG] mod_sofia.c:375
sofia/external/<nb_callee> SOFIA DESTROY
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:766
(sofia/external/<nb_caller>@<my_provider>) State REPORTING going to sleep
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:99
sofia/external/<nb_callee> Standard DESTROY
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:581
(sofia/external/<nb_callee>) State DESTROY going to sleep
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:443
(sofia/external/<nb_caller>@<my_provider>) State Change CS_REPORTING ->
CS_DESTROY
2013-10-25 11:23:11.492469 [DEBUG] switch_core_session.c:1345 Send signal
sofia/external/<nb_caller>@<my_provider> [BREAK]
2013-10-25 11:23:11.492469 [DEBUG] switch_core_session.c:1553 Session 604
(sofia/external/<nb_caller>@<my_provider>) Locked, Waiting on external
entities
2013-10-25 11:23:11.492469 [NOTICE] switch_core_session.c:1571 Session 604
(sofia/external/<nb_caller>@<my_provider>) Ended
2013-10-25 11:23:11.492469 [NOTICE] switch_core_session.c:1575 Close Channel
sofia/external/<nb_caller>@<my_provider> [CS_DESTROY]
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:568
(sofia/external/<nb_caller>@<my_provider>) Callstate Change HANGUP -> DOWN
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:571
(sofia/external/<nb_caller>@<my_provider>) Running State Change CS_DESTROY
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:581
(sofia/external/<nb_caller>@<my_provider>) State DESTROY
2013-10-25 11:23:11.492469 [DEBUG] mod_sofia.c:375
sofia/external/<nb_caller>@<my_provider> SOFIA DESTROY
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:99
sofia/external/<nb_caller>@<my_provider> Standard DESTROY
2013-10-25 11:23:11.492469 [DEBUG] switch_core_state_machine.c:581
(sofia/external/<nb_caller>@<my_provider>) State DESTROY going to sleep

>>>

If someone understand what happens... Thanks in advance...

Obbyone

--
View this message in context: http://freeswitch-users.2379917.n2.nabble.com/Sound-issue-on-bridging-one-inbound-gateway-call-to-another-gateway-tp7595967p7595973.html
Sent from the freeswitch-users mailing list archive at Nabble.com.