[Freeswitch-users] No Audio

Sat Apr 28 00:29:17 MSD 2012

I'm experiencing No Audio when i call pstn from an extension mapped to
X-lite. internal sip endpoints i'm able to establish 2 way audio
but not when i call out. i'm able to establish connection without the audio
though.

below is my debug log
2012-04-27 12:57:13.533748 [DEBUG] sofia_glue.c:4918 Audio Codec Compare
[PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
2012-04-27 12:57:13.533748 [DEBUG] sofia_glue.c:3017 Set Codec
sofia/external/18178455922 PCMU/8000 20 ms 160 samples 64000 bits
2012-04-27 12:57:13.533748 [DEBUG] switch_core_codec.c:111
sofia/external/18178455922 Original read codec set to PCMU:0
2012-04-27 12:57:13.533748 [DEBUG] sofia_glue.c:5032 Set 2833 dtmf send
payload to 101
2012-04-27 12:57:13.533748 [DEBUG] sofia_glue.c:3269 AUDIO RTP
[sofia/external/18178455922] XX.XXX.XX.149 port 26586 -> 67.215.226.150
port 19474 codec: 0 ms: 20
2012-04-27 12:57:13.533748 [DEBUG] switch_rtp.c:1661 Starting timer [soft]
160 bytes per 20ms
2012-04-27 12:57:13.533748 [DEBUG] sofia_glue.c:3533 Set 2833 dtmf send
payload to 101
2012-04-27 12:57:13.533748 [DEBUG] sofia_glue.c:3539 Set 2833 dtmf receive
payload to 101
2012-04-27 12:57:13.533748 [NOTICE] sofia_glue.c:4063 Pre-Answer
sofia/external/18178455922!
2012-04-27 12:57:13.533748 [DEBUG] switch_channel.c:2986
(sofia/external/18178455922) Callstate Change RINGING -> EARLY
2012-04-27 12:57:13.533748 [INFO] switch_ivr_originate.c:3215 Sending early
media
2012-04-27 12:57:13.533748 [DEBUG] sofia_glue.c:3269 AUDIO RTP
[sofia/internal/1000 at XX.XXX.XX.149:5080] XX.XXX.XX.149 port 22578 ->
76.187.91.204 port 40032 codec: 0 ms: 20
2012-04-27 12:57:13.533748 [DEBUG] switch_rtp.c:1661 Starting timer [soft]
160 bytes per 20ms
2012-04-27 12:57:13.553797 [DEBUG] sofia_glue.c:3533 Set 2833 dtmf send
payload to 101
2012-04-27 12:57:13.553797 [DEBUG] sofia_glue.c:3539 Set 2833 dtmf receive
payload to 101
2012-04-27 12:57:13.553797 [DEBUG] mod_sofia.c:2582 Ring SDP:
v=0
o=FreeSWITCH 1335523255 1335523256 IN IP4 XX.XXX.XX.149
s=FreeSWITCH
c=IN IP4 XX.XXX.XX.149
t=0 0
m=audio 22578 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

o=root 2889 2889 IN IP4 67.215.226.150
s=session
c=IN IP4 67.215.226.150
t=0 0
m=audio 12116 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

2012-04-27 12:57:13.553797 [NOTICE] mod_sofia.c:2585 Pre-Answer
sofia/internal/1000 at XX.XXX.XX.149:5080!
2012-04-27 12:57:13.553797 [DEBUG] switch_channel.c:2986
(sofia/internal/1000 at XX.XXX.XX.149:5080) Callstate Change RINGING -> EARLY
2012-04-27 12:57:13.553797 [DEBUG] switch_channel.c:3028 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:13.553797 [DEBUG] switch_core_session.c:877 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:13.553797 [DEBUG] sofia.c:5554 Channel
sofia/internal/1000 at XX.XXX.XX.149:5080 skipping state [early][183]
2012-04-27 12:57:13.553797 [DEBUG] switch_core_session.c:731 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:13.553797 [DEBUG] switch_ivr_originate.c:3266 Originate
Resulted in Success: [sofia/external/18178455922]
2012-04-27 12:57:13.573784 [DEBUG] switch_core_session.c:731 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:13.573784 [DEBUG] switch_core_session.c:731 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:13.573784 [DEBUG] switch_ivr_bridge.c:1331
(sofia/external/18178455922) State Change CS_CONSUME_MEDIA ->
CS_EXCHANGE_MEDIA
2012-04-27 12:57:13.573784 [DEBUG] switch_core_state_machine.c:362
(sofia/external/18178455922) Running State Change CS_EXCHANGE_MEDIA
2012-04-27 12:57:13.573784 [DEBUG] switch_core_session.c:1182 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:13.573784 [DEBUG] switch_core_state_machine.c:420
(sofia/external/18178455922) State EXCHANGE_MEDIA
2012-04-27 12:57:13.573784 [DEBUG] mod_sofia.c:582 SOFIA EXCHANGE_MEDIA
2012-04-27 12:57:17.493751 [DEBUG] switch_core_session.c:877 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:17.493751 [DEBUG] switch_core_session.c:877 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:17.513750 [DEBUG] sofia.c:5561 Channel
sofia/external/18178455922 entering state [completing][200]
2012-04-27 12:57:17.513750 [DEBUG] sofia.c:5572 Remote SDP:
v=0
o=root 2889 2890 IN IP4 67.215.226.150
s=session
c=IN IP4 67.215.226.150
t=0 0
m=audio 19474 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

2012-04-27 12:57:17.513750 [DEBUG] switch_core_session.c:877 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:17.513750 [DEBUG] switch_core_session.c:877 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:17.513750 [DEBUG] sofia.c:5561 Channel
sofia/external/18178455922 entering state [ready][200]
2012-04-27 12:57:17.513750 [DEBUG] switch_channel.c:3245
(sofia/external/18178455922) Callstate Change EARLY -> ACTIVE
2012-04-27 12:57:17.513750 [DEBUG] switch_channel.c:3257 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:17.513750 [NOTICE] sofia.c:6214 Channel
[sofia/external/18178455922] has been answered
2012-04-27 12:57:17.533751 [DEBUG] mod_sofia.c:754 Local SDP
sofia/internal/1000 at XX.XXX.XX.149:5080:
v=0
o=FreeSWITCH 1335523255 1335523257 IN IP4 XX.XXX.XX.149
s=FreeSWITCH
c=IN IP4 XX.XXX.XX.149
t=0 0
m=audio 22578 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

2012-04-27 12:57:17.533751 [DEBUG] switch_core_session.c:877 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:17.533751 [DEBUG] sofia.c:5561 Channel
sofia/internal/1000 at XX.XXX.XX.149:5080 entering state [completed][200]
2012-04-27 12:57:17.533751 [DEBUG] switch_core_session.c:731 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:17.533751 [DEBUG] switch_channel.c:3245
(sofia/internal/1000 at XX.XXX.XX.149:5080) Callstate Change EARLY -> ACTIVE
2012-04-27 12:57:17.533751 [NOTICE] switch_ivr_bridge.c:417 Channel
[sofia/internal/1000 at XX.XXX.XX.149:5080] has been answered
2012-04-27 12:57:17.693753 [DEBUG] switch_core_session.c:877 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:17.693753 [DEBUG] switch_core_session.c:877 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:17.693753 [DEBUG] switch_core_session.c:877 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:17.713754 [DEBUG] sofia.c:5561 Channel
sofia/internal/1000 at XX.XXX.XX.149:5080 entering state [ready][200]
2012-04-27 12:57:17.713754 [DEBUG] switch_core_session.c:793 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:17.713754 [DEBUG] switch_core_session.c:793 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:24.913748 [DEBUG] switch_core_session.c:877 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:24.933750 [DEBUG] switch_channel.c:2849
(sofia/external/18178455922) Callstate Change ACTIVE -> HANGUP
2012-04-27 12:57:24.933750 [NOTICE] sofia.c:636 Hangup
sofia/external/18178455922 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2012-04-27 12:57:24.933750 [DEBUG] switch_channel.c:2872 Send signal
sofia/external/18178455922 [KILL]
2012-04-27 12:57:24.933750 [DEBUG] switch_core_session.c:1182 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:24.933750 [DEBUG] switch_ivr_bridge.c:586 BRIDGE THREAD
DONE [sofia/external/18178455922]
2012-04-27 12:57:24.933750 [DEBUG] switch_ivr_bridge.c:611 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:24.933750 [DEBUG] switch_core_state_machine.c:420
(sofia/external/18178455922) State EXCHANGE_MEDIA going to sleep
2012-04-27 12:57:24.933750 [DEBUG] switch_core_state_machine.c:362
(sofia/external/18178455922) Running State Change CS_HANGUP
2012-04-27 12:57:24.933750 [DEBUG] switch_core_state_machine.c:602
(sofia/external/18178455922) State HANGUP
2012-04-27 12:57:24.933750 [DEBUG] mod_sofia.c:469 Channel
sofia/external/18178455922 hanging up, cause: NORMAL_CLEARING
2012-04-27 12:57:24.933750 [DEBUG] switch_core_state_machine.c:47
sofia/external/18178455922 Standard HANGUP, cause: NORMAL_CLEARING
2012-04-27 12:57:24.933750 [DEBUG] switch_core_state_machine.c:602
(sofia/external/18178455922) State HANGUP going to sleep
2012-04-27 12:57:24.933750 [DEBUG] switch_core_state_machine.c:393
(sofia/external/18178455922) State Change CS_HANGUP -> CS_REPORTING
2012-04-27 12:57:24.933750 [DEBUG] switch_core_session.c:1182 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:24.933750 [DEBUG] switch_core_state_machine.c:362
(sofia/external/18178455922) Running State Change CS_REPORTING
2012-04-27 12:57:24.933750 [DEBUG] switch_core_state_machine.c:662
(sofia/external/18178455922) State REPORTING
2012-04-27 12:57:24.933750 [DEBUG] switch_core_state_machine.c:79
sofia/external/18178455922 Standard REPORTING, cause: NORMAL_CLEARING
2012-04-27 12:57:24.933750 [DEBUG] switch_core_state_machine.c:662
(sofia/external/18178455922) State REPORTING going to sleep
2012-04-27 12:57:24.933750 [DEBUG] switch_core_state_machine.c:387
(sofia/external/18178455922) State Change CS_REPORTING -> CS_DESTROY
2012-04-27 12:57:24.933750 [DEBUG] switch_core_session.c:1182 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:24.933750 [DEBUG] switch_core_session.c:1382 Session 205
(sofia/external/18178455922) Locked, Waiting on external entities
2012-04-27 12:57:24.954657 [DEBUG] switch_ivr_bridge.c:586 BRIDGE THREAD
DONE [sofia/internal/1000 at XX.XXX.XX.149:5080]
2012-04-27 12:57:24.954657 [DEBUG] switch_ivr_bridge.c:611 Send signal
sofia/external/18178455922 [BREAK]
2012-04-27 12:57:24.954657 [DEBUG] switch_core_session.c:731 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:24.954657 [NOTICE] switch_core_state_machine.c:226
sofia/internal/1000 at XX.XXX.XX.149:5080 has executed the last dialplan
instruction, hanging up.
2012-04-27 12:57:24.954657 [DEBUG] switch_channel.c:2849
(sofia/internal/1000 at XX.XXX.XX.149:5080) Callstate Change ACTIVE -> HANGUP
2012-04-27 12:57:24.954657 [NOTICE] switch_core_state_machine.c:228 Hangup
sofia/internal/1000 at XX.XXX.XX.149:5080 [CS_EXECUTE] [NORMAL_CLEARING]
2012-04-27 12:57:24.954657 [DEBUG] switch_channel.c:2872 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [KILL]
2012-04-27 12:57:24.954657 [DEBUG] switch_core_session.c:1182 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:24.954657 [DEBUG] switch_core_state_machine.c:417
(sofia/internal/1000 at XX.XXX.XX.149:5080) State EXECUTE going to sleep
2012-04-27 12:57:24.954657 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1000 at XX.XXX.XX.149:5080) Running State Change CS_HANGUP
2012-04-27 12:57:24.954657 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/1000 at XX.XXX.XX.149:5080) State HANGUP
2012-04-27 12:57:24.954657 [DEBUG] mod_sofia.c:463
sofia/internal/1000 at XX.XXX.XX.149:5080 Overriding SIP cause 480 with 200
from the other leg
2012-04-27 12:57:24.954657 [DEBUG] mod_sofia.c:469 Channel
sofia/internal/1000 at XX.XXX.XX.149:5080 hanging up, cause: NORMAL_CLEARING
2012-04-27 12:57:24.954657 [DEBUG] mod_sofia.c:517 Sending BYE to
sofia/internal/1000 at XX.XXX.XX.149:5080
2012-04-27 12:57:24.954657 [NOTICE] switch_core_session.c:1400 Session 205
(sofia/external/18178455922) Ended
2012-04-27 12:57:24.954657 [NOTICE] switch_core_session.c:1402 Close
Channel sofia/external/18178455922 [CS_DESTROY]
2012-04-27 12:57:24.954657 [DEBUG] switch_core_state_machine.c:491
(sofia/external/18178455922) Callstate Change HANGUP -> DOWN
2012-04-27 12:57:24.954657 [DEBUG] switch_core_state_machine.c:494
(sofia/external/18178455922) Running State Change CS_DESTROY
2012-04-27 12:57:24.954657 [DEBUG] switch_core_state_machine.c:504
(sofia/external/18178455922) State DESTROY
2012-04-27 12:57:24.954657 [DEBUG] mod_sofia.c:374
sofia/external/18178455922 SOFIA DESTROY
2012-04-27 12:57:24.954657 [DEBUG] switch_core_state_machine.c:86
sofia/external/18178455922 Standard DESTROY
2012-04-27 12:57:24.954657 [DEBUG] switch_core_state_machine.c:504
(sofia/external/18178455922) State DESTROY going to sleep
2012-04-27 12:57:24.973781 [DEBUG] switch_core_state_machine.c:47
sofia/internal/1000 at XX.XXX.XX.149:5080 Standard HANGUP, cause:
NORMAL_CLEARING
2012-04-27 12:57:24.973781 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/1000 at XX.XXX.XX.149:5080) State HANGUP going to sleep
2012-04-27 12:57:24.973781 [DEBUG] switch_core_state_machine.c:393
(sofia/internal/1000 at XX.XXX.XX.149:5080) State Change CS_HANGUP ->
CS_REPORTING
2012-04-27 12:57:24.973781 [DEBUG] switch_core_session.c:1182 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:24.973781 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/1000 at XX.XXX.XX.149:5080) Running State Change CS_REPORTING
2012-04-27 12:57:24.973781 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/1000 at XX.XXX.XX.149:5080) State REPORTING
2012-04-27 12:57:24.973781 [DEBUG] switch_core_state_machine.c:79
sofia/internal/1000 at XX.XXX.XX.149:5080 Standard REPORTING, cause:
NORMAL_CLEARING
2012-04-27 12:57:24.973781 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/1000 at XX.XXX.XX.149:5080) State REPORTING going to sleep
2012-04-27 12:57:24.973781 [DEBUG] switch_core_state_machine.c:387
(sofia/internal/1000 at XX.XXX.XX.149:5080) State Change CS_REPORTING ->
CS_DESTROY
2012-04-27 12:57:24.973781 [DEBUG] switch_core_session.c:1182 Send signal
sofia/internal/1000 at XX.XXX.XX.149:5080 [BREAK]
2012-04-27 12:57:24.973781 [DEBUG] switch_core_session.c:1382 Session 204
(sofia/internal/1000 at XX.XXX.XX.149:5080) Locked, Waiting on external
entities
2012-04-27 12:57:24.973781 [NOTICE] switch_core_session.c:1400 Session 204
(sofia/internal/1000 at XX.XXX.XX.149:5080) Ended
2012-04-27 12:57:24.995020 [NOTICE] switch_core_session.c:1402 Close
Channel sofia/internal/1000 at XX.XXX.XX.149:5080 [CS_DESTROY]
2012-04-27 12:57:24.995020 [DEBUG] switch_core_state_machine.c:491
(sofia/internal/1000 at XX.XXX.XX.149:5080) Callstate Change HANGUP -> DOWN
2012-04-27 12:57:24.995020 [DEBUG] switch_core_state_machine.c:494
(sofia/internal/1000 at XX.XXX.XX.149:5080) Running State Change CS_DESTROY
2012-04-27 12:57:24.995020 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/1000 at XX.XXX.XX.149:5080) State DESTROY
2012-04-27 12:57:24.995020 [DEBUG] mod_sofia.c:374
sofia/internal/1000 at XX.XXX.XX.149:5080 SOFIA DESTROY
2012-04-27 12:57:24.995020 [DEBUG] switch_core_state_machine.c:86
sofia/internal/1000 at XX.XXX.XX.149:5080 Standard DESTROY
2012-04-27 12:57:24.995020 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/1000 at XX.XXX.XX.149:5080) State DESTROY going to sleep

Alfred
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20120427/9b2d3600/attachment-0001.html 