From: Josip Djuricic <[EMAIL PROTECTED]>
Reply-To: <[email protected]>
Date: Fri, 11 Apr 2008 15:11:04 +0200
To: <[email protected]>
Subject: [Freeswitch-users] Incoming fax problems
Hi there
I wanted to ask for advice on my incoming fax receive problem.
When I send the fax to a Patton smartnode FXS gateway behind FS (and the
call goes PSTN < -DSS1- > AS5300 <-h323> Yate <-sip-> FS <-sip-> PATTON).
I needed to add yate to act as h323 to sip converter because my AS5300
had problems with sip (not only to FS, but to almost every device, seams
like a sw issue).
So now my fax behind FS answers, but the call fails after a while.
What I've noticed on FS output, though not sure if it's related is:
[DEBUG] switch_ivr_bridge.c:275 audio_bridge_thread() read:
sofia/default/38514001702 Bad Frame.... Bubye!
I'm including entire FS debug output of that call.
I need to also notice that outgoing fax sending works just perfect.
Thank you all,
Josip
#irc jdjurici
2008-04-11 13:46:46 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel
sofia/default/38514001702 entering state [ready]
2008-04-11 13:46:46 [DEBUG] sofia.c:1599 sofia_handle_sip_i_state() Remote
SDP:
v=0
o=MxSIP 0 81 IN IP4 89.18.37.100
s=SIP Call
c=IN IP4 89.18.37.100
t=0 0
m=audio 5024 RTP/AVP 8
a=rtpmap:8 PCMA/8000
2008-04-11 13:46:46 [DEBUG] sofia_glue.c:2011 sofia_glue_negotiate_sdp() Audio
Codec Compare [PCMA:8:8000]/[PCMA:8:8000]
2008-04-11 13:46:46 [DEBUG] sofia_glue.c:1333 sofia_glue_tech_set_codec() Set
Codec sofia/default/38514001702 PCMA/8000 20 ms 160 samples
2008-04-11 13:46:46 [DEBUG] sofia_glue.c:1523 sofia_glue_activate_rtp() AUDIO
RTP [sofia/default/38514001702] 89.18.32.19:25440->89.18.37.100:5024 codec: 8
ms: 20
2008-04-11 13:46:46 [DEBUG] switch_rtp.c:716 switch_rtp_create() Starting
timer [soft] 160 bytes per 20000ms
2008-04-11 13:46:46 [DEBUG] switch_channel.c:1506
switch_channel_perform_mark_answered() Kill
sofia/outbound/[EMAIL PROTECTED] [BREAK]
2008-04-11 13:46:46 [NOTICE] sofia.c:1945 sofia_handle_sip_i_state() Channel
[sofia/default/38514001702] has been answered
2008-04-11 13:46:46 [DEBUG] sofia_glue.c:1523 sofia_glue_activate_rtp() AUDIO
RTP [sofia/outbound/[EMAIL PROTECTED]
89.18.32.19:29046->10.0.3.220:17396 codec: 8 ms: 20
2008-04-11 13:46:46 [DEBUG] switch_rtp.c:716 switch_rtp_create() Starting
timer [soft] 160 bytes per 20000ms
2008-04-11 13:46:46 [DEBUG] mod_sofia.c:376 sofia_answer_channel() Local SDP
sofia/outbound/[EMAIL PROTECTED]:
v=0
o=FreeSWITCH 1207885360 1207885361 IN IP4 89.18.32.19
s=FreeSWITCH
c=IN IP4 89.18.32.19
t=0 0
a=sendrecv
m=audio 29046 RTP/AVP 8 101 13
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
2008-04-11 13:46:46 [DEBUG] switch_core_session.c:383
switch_core_session_receive_message() Kill
sofia/outbound/[EMAIL PROTECTED] [BREAK]
2008-04-11 13:46:46 [NOTICE] switch_ivr_originate.c:1171
switch_ivr_originate() Channel [sofia/outbound/[EMAIL PROTECTED] has
been answered
2008-04-11 13:46:46 [DEBUG] switch_ivr_originate.c:1205 switch_ivr_originate()
Originate Resulted in Success: [sofia/default/38514001702]
2008-04-11 13:46:46 [DEBUG] switch_core_session.c:383
switch_core_session_receive_message() Kill sofia/default/38514001702 [BREAK]
2008-04-11 13:46:46 [DEBUG] switch_core_session.c:383
switch_core_session_receive_message() Kill
sofia/outbound/[EMAIL PROTECTED] [BREAK]
2008-04-11 13:46:46 [DEBUG] switch_ivr_bridge.c:756
switch_ivr_multi_threaded_bridge() sofia/default/38514001702 State Change
CS_HOLD -> CS_LOOPBACK
2008-04-11 13:46:46 [DEBUG] switch_core_session.c:670
switch_core_session_signal_state_change() Kill sofia/default/38514001702
[BREAK]
send 897 bytes to udp/[10.0.3.220]:5090 at 11:46:46.446911:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.3.220:5090;rport=5090;branch=z9hG4bK293463607
From: "10.0.3.10" <sip:[EMAIL PROTECTED]>;tag=1005463975
To: <sip:[EMAIL PROTECTED]:5080>;tag=2Sgt80aptejjg
Call-ID: [EMAIL PROTECTED]
CSeq: 19 INVITE
Contact: <sip:[EMAIL PROTECTED]:5080;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.0.pre4-8058
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE,
NOTIFY, REFER, UPDATE, REGISTER, INFO
Supported: 100rel, precondition, timer
Allow-Events: talk
Min-SE: 120
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 272
v=0
o=FreeSWITCH 6013977101500251822 7042482777030961138 IN IP4 89.18.32.19
s=FreeSWITCH
c=IN IP4 89.18.32.19
t=0 0
a=sendrecv
m=audio 29046 RTP/AVP 8 101 13
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
------------------------------------------------------------------------
2008-04-11 13:46:46 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel
sofia/outbound/[EMAIL PROTECTED] entering state [completed]
recv 404 bytes from udp/[10.0.3.220]:5090 at 11:46:46.459555:
------------------------------------------------------------------------
ACK sip:[EMAIL PROTECTED]:5080;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.0.3.220:5090;rport;branch=z9hG4bK1807442927
From: "10.0.3.10" <sip:[EMAIL PROTECTED]>;tag=1005463975
To: <sip:[EMAIL PROTECTED]:5080>;tag=2Sgt80aptejjg
Call-ID: [EMAIL PROTECTED]
CSeq: 19 ACK
Max-Forwards: 20
Contact: <sip:[EMAIL PROTECTED]:5090>
User-Agent: sip-to-h323/0.9.0
Content-Length: 0
------------------------------------------------------------------------
2008-04-11 13:46:46 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel
sofia/outbound/[EMAIL PROTECTED] entering state [ready]
2008-04-11 13:46:46 [DEBUG] switch_core_state_machine.c:440
switch_core_session_run() (sofia/default/38514001702) State HOLD going to
sleep
2008-04-11 13:46:46 [DEBUG] switch_core_state_machine.c:364
switch_core_session_run() sofia/default/38514001702 Running State Change
CS_LOOPBACK
2008-04-11 13:46:46 [DEBUG] switch_core_state_machine.c:431
switch_core_session_run() (sofia/default/38514001702) State LOOPBACK
2008-04-11 13:46:46 [DEBUG] mod_sofia.c:314 sofia_on_loopback() SOFIA LOOPBACK
[EMAIL PROTECTED]>
[EMAIL PROTECTED]> recv 440 bytes from udp/[89.18.37.100]:5062 at
11:47:25.493537:
------------------------------------------------------------------------
BYE sip:[EMAIL PROTECTED]:5060 SIP/2.0
Via: SIP/2.0/UDP 89.18.37.100:5062;branch=z9hG4bKc22903e25
Max-Forwards: 70
Content-Length: 0
To: "10.0.3.10" <sip:[EMAIL PROTECTED]>;tag=KtFgg4Z384S5j
From: <sip:[EMAIL PROTECTED]:5062>;tag=01ec8a090df17a1
Call-ID: cc981044-825f-122b-1ca9-001e0b83659c
CSeq: 802101929 BYE
Supported: timer
Supported: replaces
User-Agent: Patton SN4112 JS EUI MxSF v3.2.8.45 00A0BA02B814
------------------------------------------------------------------------
send 498 bytes to udp/[89.18.37.100]:5062 at 11:47:25.494066:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 89.18.37.100:5062;branch=z9hG4bKc22903e25
From: <sip:[EMAIL PROTECTED]:5062>;tag=01ec8a090df17a1
To: "10.0.3.10" <sip:[EMAIL PROTECTED]>;tag=KtFgg4Z384S5j
Call-ID: cc981044-825f-122b-1ca9-001e0b83659c
CSeq: 802101929 BYE
User-Agent: FreeSWITCH-mod_sofia/1.0.pre4-8058
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE,
NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH
Supported: 100rel, precondition, timer
Content-Length: 0
------------------------------------------------------------------------
2008-04-11 13:47:25 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel
sofia/default/38514001702 entering state [terminated]
2008-04-11 13:47:25 [NOTICE] sofia.c:1992 sofia_handle_sip_i_state() Hangup
sofia/default/38514001702 [CS_LOOPBACK] [NORMAL_CLEARING]
2008-04-11 13:47:25 [DEBUG] switch_channel.c:1355
switch_channel_perform_hangup() Kill sofia/default/38514001702 [KILL]
2008-04-11 13:47:25 [DEBUG] switch_core_session.c:670
switch_core_session_signal_state_change() Kill sofia/default/38514001702
[BREAK]
2008-04-11 13:47:25 [DEBUG] switch_ivr_bridge.c:275 audio_bridge_thread()
read: sofia/default/38514001702 Bad Frame.... Bubye!
2008-04-11 13:47:25 [DEBUG] switch_ivr_bridge.c:344 audio_bridge_thread() Kill
sofia/outbound/[EMAIL PROTECTED] [BREAK]
2008-04-11 13:47:25 [DEBUG] switch_ivr_bridge.c:347 audio_bridge_thread()
BRIDGE THREAD DONE [sofia/default/38514001702]
2008-04-11 13:47:25 [DEBUG] switch_core_state_machine.c:431
switch_core_session_run() (sofia/default/38514001702) State LOOPBACK going to
sleep
2008-04-11 13:47:25 [DEBUG] switch_core_state_machine.c:364
switch_core_session_run() sofia/default/38514001702 Running State Change
CS_HANGUP
2008-04-11 13:47:25 [DEBUG] switch_core_state_machine.c:392
switch_core_session_run() (sofia/default/38514001702) State HANGUP
2008-04-11 13:47:25 [DEBUG] mod_sofia.c:255 sofia_on_hangup() Channel
sofia/default/38514001702 hanging up, cause: NORMAL_CLEARING
2008-04-11 13:47:25 [DEBUG] switch_core_state_machine.c:46
switch_core_standard_on_hangup() Standard HANGUP sofia/default/38514001702,
cause: NORMAL_CLEARING
2008-04-11 13:47:25 [DEBUG] switch_core_state_machine.c:392
switch_core_session_run() (sofia/default/38514001702) State HANGUP going to
sleep
2008-04-11 13:47:25 [DEBUG] switch_core_session.c:730
switch_core_session_thread() Session 145 (sofia/default/38514001702) Locked,
Waiting on external entities
2008-04-11 13:47:25 [DEBUG] switch_core_session.c:383
switch_core_session_receive_message() Kill
sofia/outbound/[EMAIL PROTECTED] [BREAK]
2008-04-11 13:47:25 [DEBUG] switch_ivr_bridge.c:344 audio_bridge_thread() Kill
sofia/default/38514001702 [BREAK]
2008-04-11 13:47:25 [DEBUG] switch_ivr_bridge.c:347 audio_bridge_thread()
BRIDGE THREAD DONE [sofia/outbound/[EMAIL PROTECTED]
2008-04-11 13:47:25 [NOTICE] switch_core_state_machine.c:156
switch_core_standard_on_execute() Hangup sofia/outbound/[EMAIL PROTECTED]
[CS_EXECUTE] [NORMAL_CLEARING]
2008-04-11 13:47:25 [DEBUG] switch_channel.c:1355
switch_channel_perform_hangup() Kill sofia/outbound/[EMAIL PROTECTED]
[KILL]
2008-04-11 13:47:25 [DEBUG] switch_core_session.c:670
switch_core_session_signal_state_change() Kill
sofia/outbound/[EMAIL PROTECTED] [BREAK]
2008-04-11 13:47:25 [DEBUG] switch_core_state_machine.c:428
switch_core_session_run() (sofia/outbound/[EMAIL PROTECTED]) State
EXECUTE going to sleep
2008-04-11 13:47:25 [DEBUG] switch_core_state_machine.c:364
switch_core_session_run() sofia/outbound/[EMAIL PROTECTED] Running State
Change CS_HANGUP
2008-04-11 13:47:25 [DEBUG] switch_core_state_machine.c:392
switch_core_session_run() (sofia/outbound/[EMAIL PROTECTED]) State HANGUP
send 626 bytes to udp/[10.0.3.220]:5090 at 11:47:25.500778:
2008-04-11 13:47:25 [DEBUG] mod_sofia.c:255 sofia_on_hangup() Channel
sofia/outbound/[EMAIL PROTECTED] hanging up, cause: NORMAL_CLEARING
BYE sip:[EMAIL PROTECTED]:5090 SIP/2.0
Via: SIP/2.0/UDP 89.18.32.19:5080;rport;branch=z9hG4bKK5Zr11t0jaS2m
Max-Forwards: 70
From: <sip:[EMAIL PROTECTED]:5080>;tag=2Sgt80aptejjg
To: "10.0.3.10" <sip:[EMAIL PROTECTED]>;tag=1005463975
Call-ID: [EMAIL PROTECTED]
CSeq: 97838886 BYE
Contact: <sip:[EMAIL PROTECTED]:5080;transport=udp>2008-04-11 13:47:25
[DEBUG] mod_sofia.c:287 sofia_on_hangup() Sending BYE to
sofia/outbound/[EMAIL PROTECTED]
2008-04-11 13:47:25 [DEBUG] switch_core_state_machine.c:46
switch_core_standard_on_hangup() Standard HANGUP
sofia/outbound/[EMAIL PROTECTED], cause: NORMAL_CLEARING
User-Agent: FreeSWITCH-mod_sofia/1.0.pre4-8058
2008-04-11 13:47:25 [DEBUG] switch_core_state_machine.c:392
switch_core_session_run() (sofia/outbound/[EMAIL PROTECTED]) State HANGUP
going to sleep
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE,
NOTIFY, REFER, UPDATE, REGISTER, INFO
2008-04-11 13:47:25 [DEBUG] switch_core_session.c:730
switch_core_session_thread() Session 144
(sofia/outbound/[EMAIL PROTECTED]) Locked, Waiting on external entities
Supported: 100rel, precondition, timer
Reason: Q.850;cause=16;text="NORMAL_CLEARING"
Content-Length: 0
------------------------------------------------------------------------
2008-04-11 13:47:25 [NOTICE] switch_core_session.c:748
switch_core_session_thread() Session 144
(sofia/outbound/[EMAIL PROTECTED]) Ended
2008-04-11 13:47:25 [NOTICE] switch_core_session.c:750
switch_core_session_thread() Close Channel
sofia/outbound/[EMAIL PROTECTED] [CS_HANGUP]
2008-04-11 13:47:25 [NOTICE] switch_core_session.c:748
switch_core_session_thread() Session 145 (sofia/default/38514001702) Ended
2008-04-11 13:47:25 [NOTICE] switch_core_session.c:750
switch_core_session_thread() Close Channel sofia/default/38514001702
[CS_HANGUP]
recv 336 bytes from udp/[10.0.3.220]:5090 at 11:47:25.511122:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
89.18.32.19:5080;rport=5080;branch=z9hG4bKK5Zr11t0jaS2m;received=89.18.32.19
From: <sip:[EMAIL PROTECTED]:5080>;tag=2Sgt80aptejjg
To: "10.0.3.10" <sip:[EMAIL PROTECTED]>;tag=1005463975
Call-ID: [EMAIL PROTECTED]
CSeq: 97838886 BYE
Server: sip-to-h323/0.9.0
Content-Length: 0
------------------------------------------------------------------------
recv 390 bytes from udp/[10.0.3.220]:5090 at 11:47:25.530548:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP
89.18.32.19:5080;rport=5080;branch=z9hG4bKK5Zr11t0jaS2m;received=89.18.32.19
Call-ID: [EMAIL PROTECTED]
CSeq: 97838886 BYE
Server: sip-to-h323/0.9.0
Allow: ACK, INVITE, BYE, CANCEL, REGISTER, OPTIONS, INFO
Content-Length: 0
------------------------------------------------------------------------
_______________________________________________
Freeswitch-users mailing list
[email protected]
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org
