log snippets are not useful.

as always, we need full log from beginning to end of the complete call
with the inline sip trace enabled.



On Fri, Apr 30, 2010 at 6:23 AM, Peter P GMX <Prometheus001@gmx.net> wrote:
I just updated, with the same result:


Anthony Minessale schrieb:
> do you have lastest git HEAD ?
> can you update and try again?
>
>

Here's the log:

2010-04-30 13:17:21.819392 [DEBUG] sofia.c:4185 Remote SDP:
v=0
o=root 929923105 929923106 IN IP4 192.168.178.125
s=call
c=IN IP4 192.168.178.125
t=0 0
m=audio 60566 RTP/AVP 18 8 0 99 3 101
a=rtpmap:18 g729/8000
a=rtpmap:8 pcma/8000
a=rtpmap:0 pcmu/8000
a=rtpmap:99 g726-32/8000
a=rtpmap:3 gsm/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

2010-04-30 13:17:21.819392 [DEBUG] sofia.c:4174 Channel
sofia/internal/sip:211@192.168.178.125:2048 entering state [ready][200]
2010-04-30 13:17:21.819392 [DEBUG] sofia_glue.c:3662 Audio Codec Compare
[g729:18:8000:20]/[G729:18:8000:20]
2010-04-30 13:17:21.819392 [DEBUG] sofia_glue.c:2372 Set Codec
sofia/internal/sip:211@192.168.178.125:2048 G729/8000 20 ms 160 samples
2010-04-30 13:17:21.819392 [DEBUG] sofia_glue.c:3595 Set 2833 dtmf send
payload to 101
2010-04-30 13:17:21.819392 [DEBUG] sofia_glue.c:2612 AUDIO RTP
[sofia/internal/sip:211@192.168.178.125:2048] 192.168.178.220 port 12046
-> 192.168.178.125 port 60566 codec: 18 ms: 20
2010-04-30 13:17:21.819392 [DEBUG] switch_rtp.c:1343 Starting timer
[soft] 160 bytes per 20ms
2010-04-30 13:17:21.823983 [DEBUG] sofia_glue.c:2818 Set 2833 dtmf send
payload to 101
2010-04-30 13:17:21.823983 [DEBUG] sofia_glue.c:2823 Set 2833 dtmf
receive payload to 101
2010-04-30 13:17:21.823983 [DEBUG] switch_channel.c:2347 Send signal
sofia/internal/200@my.domain [BREAK]
2010-04-30 13:17:21.823983 [NOTICE] sofia.c:4754 Channel
[sofia/internal/sip:211@192.168.178.125:2048] has been answered
2010-04-30 13:17:21.830046 [DEBUG] sofia_glue.c:2612 AUDIO RTP
[sofia/internal/200@my.domain] 192.168.178.220 port 12006 ->
192.168.178.50 port 12770 codec: 18 ms: 20
2010-04-30 13:17:21.830046 [DEBUG] switch_rtp.c:1343 Starting timer
[soft] 160 bytes per 20ms
2010-04-30 13:17:21.832815 [DEBUG] sofia_glue.c:2818 Set 2833 dtmf send
payload to 101
2010-04-30 13:17:21.832815 [DEBUG] sofia_glue.c:2823 Set 2833 dtmf
receive payload to 101
2010-04-30 13:17:21.832815 [INFO] switch_rtp.c:1173 Activating Secure
RTP SEND
2010-04-30 13:17:21.832815 [DEBUG] switch_core_sqldb.c:1110 Secure Type:
srtp:AES_CM_128_HMAC_SHA1_32
2010-04-30 13:17:21.832815 [INFO] switch_rtp.c:1153 Activating Secure
RTP RECV
2010-04-30 13:17:21.832815 [DEBUG] switch_core_sqldb.c:1110 Secure Type:
srtp:AES_CM_128_HMAC_SHA1_32
2010-04-30 13:17:21.832815 [DEBUG] mod_sofia.c:663 Local SDP
sofia/internal/200@my.domain:
v=0
o=FreeSWITCH 1272614235 1272614236 IN IP4 192.168.178.220
s=FreeSWITCH
c=IN IP4 192.168.178.220
t=0 0
m=audio 12006 RTP/SAVP 18 101
a=rtpmap:18 g729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
a=crypto:1 AES_CM_128_HMAC_SHA1_32
inline:OoR/ZSNzik9jFHdcbzXyGXGSO5E3mGT6tvcpvsqK

2010-04-30 13:17:21.834022 [DEBUG] switch_core_session.c:703 Send signal
sofia/internal/sip:211@192.168.178.125:2048 [BREAK]
2010-04-30 13:17:21.834022 [DEBUG] sofia.c:4174 Channel
sofia/internal/200@my.domain entering state [completed][200]
2010-04-30 13:17:21.834022 [DEBUG] switch_core_session.c:642 Send signal
sofia/internal/200@my.domain [BREAK]
2010-04-30 13:17:21.835065 [NOTICE] switch_ivr_originate.c:3174 Channel
[sofia/internal/200@my.domain] has been answered
2010-04-30 13:17:21.835065 [DEBUG] switch_ivr_originate.c:3219 Originate
Resulted in Success: [sofia/internal/sip:211@192.168.178.125:2048]
2010-04-30 13:17:21.838531 [DEBUG] switch_ivr_originate.c:3219 Originate
Resulted in Success: [sofia/internal/sip:211@192.168.178.125:2048]
2010-04-30 13:17:21.838531 [DEBUG] switch_core_session.c:642 Send signal
sofia/internal/sip:211@192.168.178.125:2048 [BREAK]
2010-04-30 13:17:21.839601 [DEBUG] switch_core_session.c:642 Send signal
sofia/internal/200@my.domain [BREAK]
2010-04-30 13:17:21.839601 [DEBUG] switch_ivr_bridge.c:1182
(sofia/internal/sip:211@192.168.178.125:2048) State Change
CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2010-04-30 13:17:21.839601 [DEBUG] switch_core_session.c:1022 Send
signal sofia/internal/sip:211@192.168.178.125:2048 [BREAK]
2010-04-30 13:17:21.839601 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/sip:211@192.168.178.125:2048) Running State Change
CS_EXCHANGE_MEDIA
2010-04-30 13:17:21.839601 [DEBUG] switch_core_state_machine.c:351
(sofia/internal/sip:211@192.168.178.125:2048) State EXCHANGE_MEDIA
2010-04-30 13:17:21.839601 [DEBUG] mod_sofia.c:534 SOFIA EXCHANGE_MEDIA
2010-04-30 13:17:21.861445 [DEBUG] switch_core_media_bug.c:360 Attaching
BUG to sofia/internal/sip:211@192.168.178.125:2048
2010-04-30 13:17:21.891110 [DEBUG] switch_rtp.c:2443 Correct ip/port
confirmed.
2010-04-30 13:17:21.951915 [DEBUG] switch_rtp.c:2443 Correct ip/port
confirmed.
2010-04-30 13:17:21.951915 [INFO] mod_com_g729.c:146 DECODER CREATE -
0x904e070 0x8fb39c0
2010-04-30 13:17:22.031586 [INFO] mod_com_g729.c:146 DECODER CREATE -
0x8fcd7e8 0x8e448a0
2010-04-30 13:17:22.247792 [DEBUG] switch_core_media_bug.c:360 Attaching
BUG to sofia/internal/200@my.domain
2010-04-30 13:17:22.253759 [ERR] mod_com_g729.c:142 DECODER CREATE
FAILED - 0x90990a0 (nil)
2010-04-30 13:17:22.253759 [ERR] switch_core_io.c:327 Codec G.729
decoder error!
2010-04-30 13:17:22.253759 [DEBUG] switch_ivr_bridge.c:478
sofia/internal/200@my.domain ending bridge by request from read function



--
Anthony Minessale II

FreeSWITCH http://www.freeswitch.org/
ClueCon http://www.cluecon.com/
Twitter: http://twitter.com/FreeSWITCH_wire

AIM: anthm
MSN:anthony_minessale@hotmail.com
GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com
IRC: irc.freenode.net #freeswitch

FreeSWITCH Developer Conference
sip:888@conference.freeswitch.org
googletalk:conf+888@conference.freeswitch.org
pstn:+19193869900