Re: [Freeswitch-users] Problem of FreeSwitch and T.38 Test

8 Jul 2010


      This is the log:
        !!!---------------------------------log
start-------------------------------------!!!


recv 906 bytes from udp/[192.168.21.76]:5060 at 07:59:05.856966:
   ------------------------------------------------------------------------
   INVITE sip:9179@192.168.26.39:25060;transport=UDP SIP/2.0
   Via: SIP/2.0/UDP 192.168.21.76:5060
;branch=z9hG4bK-d8754z-bc46e07b00e494a3-1---d8754z-
   Max-Forwards: 70
   Contact: <sip:1001@192.168.21.76:5060;transport=UDP>
   To: <sip:9179@192.168.26.39:25060;transport=UDP>
   From: "1001"<sip:1001@192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 1 INVITE
   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO,
SUBSCRIBE
   Content-Type: application/sdp
   User-Agent: Zoiper rev.6751
   Content-Length: 329

   v=0
   o=Zoiper_user 0 0 IN IP4 192.168.21.76
   s=Zoiper_session
   c=IN IP4 192.168.21.76
   t=0 0
   m=audio 8000 RTP/AVP 3 0 8 110 98 101
   a=rtpmap:3 GSM/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:110 speex/8000
   a=rtpmap:98 iLBC/8000
   a=fmtp:98 mode=30
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-15
   a=sendrecv
   ------------------------------------------------------------------------
send 369 bytes to udp/[192.168.21.76]:5060 at 07:59:05.857299:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 192.168.21.76:5060
;branch=z9hG4bK-d8754z-bc46e07b00e494a3-1---d8754z-
   From: "1001"<sip:1001@192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   To: <sip:9179@192.168.26.39:25060;transport=UDP>
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 1 INVITE
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Content-Length: 0

   ------------------------------------------------------------------------
2010-07-08 15:59:05.857080 [DEBUG] sofia.c:5979 IP 192.168.21.76 Rejected by
acl "192.168.26.0/24". Falling back to Digest auth.
send 857 bytes to udp/[192.168.21.76]:5060 at 07:59:05.912630:
   ------------------------------------------------------------------------
   SIP/2.0 407 Proxy Authentication Required
   Via: SIP/2.0/UDP 192.168.21.76:5060
;branch=z9hG4bK-d8754z-bc46e07b00e494a3-1---d8754z-
   From: "1001"<sip:1001@192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   To: <sip:9179@192.168.26.39:25060;transport=UDP>;tag=SKU88e5Sa1gSS
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 1 INVITE
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla,
include-session-description, presence.winfo, message-summary, refer
   Proxy-Authenticate: Digest realm="192.168.26.39",
nonce="fba8e3e1-c3d7-4faa-b367-7ba18ad5c276", algorithm=MD5, qop="auth"
   Content-Length: 0

   ------------------------------------------------------------------------
recv 390 bytes from udp/[192.168.21.76]:5060 at 07:59:05.931667:
   ------------------------------------------------------------------------
   ACK sip:9179@192.168.26.39:25060;transport=UDP SIP/2.0
   Via: SIP/2.0/UDP 192.168.21.76:5060
;branch=z9hG4bK-d8754z-bc46e07b00e494a3-1---d8754z-
   Max-Forwards: 70
   To: <sip:9179@192.168.26.39:25060;transport=UDP>;tag=SKU88e5Sa1gSS
   From: "1001"<sip:1001@192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 1 ACK
   Content-Length: 0

   ------------------------------------------------------------------------
recv 1188 bytes from udp/[192.168.21.76]:5060 at 07:59:05.932411:
   ------------------------------------------------------------------------
   INVITE sip:9179@192.168.26.39:25060;transport=UDP SIP/2.0
   Via: SIP/2.0/UDP 192.168.21.76:5060
;branch=z9hG4bK-d8754z-f0f812882a888ca8-1---d8754z-
   Max-Forwards: 70
   Contact: <sip:1001@192.168.21.76:5060;transport=UDP>
   To: <sip:9179@192.168.26.39:25060;transport=UDP>
   From: "1001"<sip:1001@192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 2 INVITE
   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO,
SUBSCRIBE
   Content-Type: application/sdp
   Proxy-Authorization: Digest
username="1001",realm="192.168.26.39",nonce="fba8e3e1-c3d7-4faa-b367-7ba18ad5c276",uri="sip:9179@192.168.26.39:25060
;transport=UDP",response="02f8fac6c1ea37df7e41b1be478f46d2",cnonce="78fc6a89edc259bb930a8b2e2da67a14",nc=00000001,qop=auth,algorithm=MD5
   User-Agent: Zoiper rev.6751
   Content-Length: 329

   v=0
   o=Zoiper_user 0 0 IN IP4 192.168.21.76
   s=Zoiper_session
   c=IN IP4 192.168.21.76
   t=0 0
   m=audio 8000 RTP/AVP 3 0 8 110 98 101
   a=rtpmap:3 GSM/8000
   a=rtpmap:0 PCMU/8000
   a=rtpmap:8 PCMA/8000
   a=rtpmap:110 speex/8000
   a=rtpmap:98 iLBC/8000
   a=fmtp:98 mode=30
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-15
   a=sendrecv
   ------------------------------------------------------------------------
send 369 bytes to udp/[192.168.21.76]:5060 at 07:59:05.932598:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 192.168.21.76:5060
;branch=z9hG4bK-d8754z-f0f812882a888ca8-1---d8754z-
   From: "1001"<sip:1001@192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   To: <sip:9179@192.168.26.39:25060;transport=UDP>
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 2 INVITE
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Content-Length: 0

   ------------------------------------------------------------------------
2010-07-08 15:59:05.932082 [DEBUG] sofia.c:5979 IP 192.168.21.76 Rejected by
acl "192.168.26.0/24". Falling back to Digest auth.
2010-07-08 15:59:05.971086 [NOTICE] switch_channel.c:776 New Channel
sofia/internal/1001@192.168.26.39:25060[d072ed1a-b182-4340-bbc8-e16923b4bed6]
2010-07-08 15:59:05.971086 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1001@192.168.26.39:25060) Running State Change CS_NEW
2010-07-08 15:59:05.971086 [DEBUG] switch_core_state_machine.c:320
(sofia/internal/1001@192.168.26.39:25060) State NEW
2010-07-08 15:59:06.037086 [DEBUG] sofia.c:6802 Setting NAT mode based on
nat.auto
2010-07-08 15:59:06.037086 [DEBUG] sofia.c:4297 Channel sofia/internal/
1001@192.168.26.39:25060 entering state [received][100]
2010-07-08 15:59:06.037086 [DEBUG] sofia.c:4308 Remote SDP:
v=0

o=Zoiper_user 0 0 IN IP4 192.168.21.76

s=Zoiper_session

c=IN IP4 192.168.21.76

t=0 0

m=audio 8000 RTP/AVP 3 0 8 110 98 101

a=rtpmap:3 GSM/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:110 speex/8000

a=rtpmap:98 iLBC/8000

a=fmtp:98 mode=30

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15


2010-07-08 15:59:06.037086 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[GSM:3:8000:20]/[G7221:115:32000:20]
2010-07-08 15:59:06.037086 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[GSM:3:8000:20]/[G7221:107:16000:20]
2010-07-08 15:59:06.037086 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[GSM:3:8000:20]/[G722:9:8000:20]
2010-07-08 15:59:06.037086 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[GSM:3:8000:20]/[PCMU:0:8000:20]
2010-07-08 15:59:06.037086 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[GSM:3:8000:20]/[PCMA:8:8000:20]
2010-07-08 15:59:06.037086 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[GSM:3:8000:20]/[GSM:3:8000:20]
2010-07-08 15:59:06.037086 [DEBUG] sofia_glue.c:2442 Set Codec
sofia/internal/1001@192.168.26.39:25060 GSM/8000 20 ms 160 samples
2010-07-08 15:59:06.037086 [DEBUG] sofia_glue.c:3798 Set 2833 dtmf send/recv
payload to 101
2010-07-08 15:59:06.037086 [DEBUG] sofia.c:4455 (sofia/internal/
1001@192.168.26.39:25060) State Change CS_NEW -> CS_INIT
2010-07-08 15:59:06.037086 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1001@192.168.26.39:25060 [BREAK]
2010-07-08 15:59:06.037086 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1001@192.168.26.39:25060) Running State Change CS_INIT
2010-07-08 15:59:06.037086 [DEBUG] switch_core_state_machine.c:338
(sofia/internal/1001@192.168.26.39:25060) State INIT
2010-07-08 15:59:06.037086 [DEBUG] mod_sofia.c:83 sofia/internal/
1001@192.168.26.39:25060 SOFIA INIT
2010-07-08 15:59:06.037086 [DEBUG] mod_sofia.c:119 (sofia/internal/
1001@192.168.26.39:25060) State Change CS_INIT -> CS_ROUTING
2010-07-08 15:59:06.037086 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1001@192.168.26.39:25060 [BREAK]
2010-07-08 15:59:06.037086 [DEBUG] switch_core_state_machine.c:338
(sofia/internal/1001@192.168.26.39:25060) State INIT going to sleep
2010-07-08 15:59:06.037086 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1001@192.168.26.39:25060) Running State Change CS_ROUTING
2010-07-08 15:59:06.037086 [DEBUG] switch_channel.c:1471 (sofia/internal/
1001@192.168.26.39:25060) Callstate Change DOWN -> RINGING
2010-07-08 15:59:06.037086 [DEBUG] switch_core_state_machine.c:341
(sofia/internal/1001@192.168.26.39:25060) State ROUTING
2010-07-08 15:59:06.037086 [DEBUG] mod_sofia.c:142 sofia/internal/
1001@192.168.26.39:25060 SOFIA ROUTING
2010-07-08 15:59:06.037086 [DEBUG] switch_core_state_machine.c:77
sofia/internal/1001@192.168.26.39:25060 Standard ROUTING
[32m2010-07-08 15:59:06.037086 [INFO] mod_dialplan_xml.c:331 Processing
1001->9179 in context default
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->unloop]
continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (PASS) [unloop]
${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [unloop]
${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->tod_example] continue=true
Dialplan: sofia/internal/1001@192.168.26.39:25060 Date/Time Match (PASS)
[tod_example] break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Action set(open=true)
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->holiday_example] continue=true
Dialplan: sofia/internal/1001@192.168.26.39:25060 Date/Time Match (FAIL)
[holiday_example] break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->global-intercept] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[global-intercept] destination_number(9179) =~ /^886$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->group-intercept] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[group-intercept] destination_number(9179) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->intercept-ext] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[intercept-ext] destination_number(9179) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->redial]
continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [redial]
destination_number(9179) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->global]
continue=true
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [global]
${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [global]
${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/
break=never
Dialplan: sofia/internal/1001@192.168.26.39:25060 Absolute Condition
[global]
Dialplan: sofia/internal/1001@192.168.26.39:25060 Action
hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/internal/1001@192.168.26.39:25060 Action
hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1001@192.168.26.39:25060 Action
hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->snom-demo-2] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [snom-demo-2]
destination_number(9179) =~ /^9001$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->snom-demo-1] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [snom-demo-1]
destination_number(9179) =~ /^9000$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->eavesdrop] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [eavesdrop]
destination_number(9179) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->eavesdrop] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [eavesdrop]
destination_number(9179) =~ /^779$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->call_return] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [call_return]
destination_number(9179) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->del-group] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [del-group]
destination_number(9179) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->add-group] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [add-group]
destination_number(9179) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->call-group-simo] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[call-group-simo] destination_number(9179) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->call-group-order] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[call-group-order] destination_number(9179) =~ /^83(\d{2})$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->extension-intercom] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[extension-intercom] destination_number(9179) =~ /^8(10[01][0-9])$/
break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->Local_Extension] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[Local_Extension] destination_number(9179) =~ /^(10[01][0-9])$/
break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->Local_Extension_Skinny] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[Local_Extension_Skinny] destination_number(9179) =~ /^(20[01][0-9])$/
break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->group_dial_sales] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[group_dial_sales] destination_number(9179) =~ /^2000$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->group_dial_support] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[group_dial_support] destination_number(9179) =~ /^2001$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->group_dial_billing] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[group_dial_billing] destination_number(9179) =~ /^2002$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->operator] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [operator]
destination_number(9179) =~ /^(operator|0)$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->vmain]
continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [vmain]
destination_number(9179) =~ /^vmain$|^4000$|^\*98$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->sip_uri]
continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [sip_uri]
destination_number(9179) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->nb_conferences] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[nb_conferences] destination_number(9179) =~ /^(30\d{2})$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->wb_conferences] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[wb_conferences] destination_number(9179) =~ /^(31\d{2})$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->uwb_conferences] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[uwb_conferences] destination_number(9179) =~ /^(32\d{2})$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->cdquality_conferences] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[cdquality_conferences] destination_number(9179) =~ /^(33\d{2})$/
break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->freeswitch_public_conf_via_sip] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[freeswitch_public_conf_via_sip] destination_number(9179) =~
/^9(888|8888|1616|3232)$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[mad_boss_intercom] destination_number(9179) =~ /^0911$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[mad_boss_intercom] destination_number(9179) =~ /^0912$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->mad_boss] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [mad_boss]
destination_number(9179) =~ /^0913$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->ivr_demo] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [ivr_demo]
destination_number(9179) =~ /^5000$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->dynamic_conference] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[dynamic_conference] destination_number(9179) =~ /^5001$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->rtp_multicast_page] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL)
[rtp_multicast_page] destination_number(9179) =~ /^pagegroup$|^7243$/
break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->park]
continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [park]
destination_number(9179) =~ /^5900$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [unpark]
destination_number(9179) =~ /^5901$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->valet_park] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [valet_park]
destination_number(9179) =~ /^(6000)$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->valet_park] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [valet_park]
destination_number(9179) =~ /^(60\d[1-9])$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->park]
continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (PASS) [park]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [park]
destination_number(9179) =~ /park\+(\d+)/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (PASS) [unpark]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [unpark]
destination_number(9179) =~ /^parking$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->park]
continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (PASS) [park]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [park]
destination_number(9179) =~ /callpark/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (PASS) [unpark]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [unpark]
destination_number(9179) =~ /pickup/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing [default->wait]
continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [wait]
destination_number(9179) =~ /^wait$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->fax_receive] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (FAIL) [fax_receive]
destination_number(9179) =~ /^9178$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 parsing
[default->fax_transmit] continue=false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Regex (PASS)
[fax_transmit] destination_number(9179) =~ /^9179$/ break=on-false
Dialplan: sofia/internal/1001@192.168.26.39:25060 Action
txfax(/tmp/999.tif)
Dialplan: sofia/internal/1001@192.168.26.39:25060 Action hangup()
2010-07-08 15:59:06.040087 [DEBUG] switch_core_state_machine.c:119
(sofia/internal/1001@192.168.26.39:25060) State Change CS_ROUTING ->
CS_EXECUTE
2010-07-08 15:59:06.040087 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1001@192.168.26.39:25060 [BREAK]
2010-07-08 15:59:06.040087 [DEBUG] switch_core_state_machine.c:341
(sofia/internal/1001@192.168.26.39:25060) State ROUTING going to sleep
2010-07-08 15:59:06.040087 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1001@192.168.26.39:25060) Running State Change CS_EXECUTE
2010-07-08 15:59:06.040087 [DEBUG] switch_core_state_machine.c:348
(sofia/internal/1001@192.168.26.39:25060) State EXECUTE
2010-07-08 15:59:06.040087 [DEBUG] mod_sofia.c:235 sofia/internal/
1001@192.168.26.39:25060 SOFIA EXECUTE
2010-07-08 15:59:06.040087 [DEBUG] switch_core_state_machine.c:157
sofia/internal/1001@192.168.26.39:25060 Standard EXECUTE
EXECUTE sofia/internal/1001@192.168.26.39:25060 set(open=true)
2010-07-08 15:59:06.040087 [DEBUG] mod_dptools.c:843 sofia/internal/
1001@192.168.26.39:25060 SET [open]=[true]
EXECUTE sofia/internal/1001@192.168.26.39:25060hash(insert/192.168.26.39-spymap/1001/d072ed1a-b182-4340-bbc8-e16923b4bed6)
EXECUTE sofia/internal/1001@192.168.26.39:25060hash(insert/192.168.26.39-last_dial/1001/9179)
EXECUTE sofia/internal/1001@192.168.26.39:25060hash(insert/192.168.26.39-last_dial/global/d072ed1a-b182-4340-bbc8-e16923b4bed6)
EXECUTE sofia/internal/1001@192.168.26.39:25060 txfax(/tmp/999.tif)
[31m2010-07-08 15:59:06.043087 [ERR] mod_spandsp.c:56 This is for fax test:
transfer fax
2010-07-08 15:59:06.088091 [DEBUG] sofia_glue.c:2682 AUDIO RTP
[sofia/internal/1001@192.168.26.39:25060] 192.168.26.39 port 31920 ->
192.168.21.76 port 8000 codec: 3 ms: 20
2010-07-08 15:59:06.088091 [DEBUG] switch_rtp.c:1413 Starting timer [soft]
160 bytes per 20ms
2010-07-08 15:59:06.091095 [DEBUG] sofia_glue.c:2892 Set 2833 dtmf send
payload to 101
2010-07-08 15:59:06.091095 [DEBUG] sofia_glue.c:2897 Set 2833 dtmf receive
payload to 101
2010-07-08 15:59:06.091095 [DEBUG] mod_sofia.c:669 Local SDP sofia/internal/
1001@192.168.26.39:25060:
v=0
o=FreeSWITCH 1278544026 1278544027 IN IP4 192.168.26.39
s=FreeSWITCH
c=IN IP4 192.168.26.39
t=0 0
m=audio 31920 RTP/AVP 3 101
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

2010-07-08 15:59:06.091095 [DEBUG] switch_core_session.c:647 Send signal
sofia/internal/1001@192.168.26.39:25060 [BREAK]
2010-07-08 15:59:06.091095 [DEBUG] switch_channel.c:2494 (sofia/internal/
1001@192.168.26.39:25060) Callstate Change RINGING -> ACTIVE
2010-07-08 15:59:06.091095 [NOTICE] mod_spandsp_fax.c:873 Channel
[sofia/internal/1001@192.168.26.39:25060] has been answered
send 1209 bytes to udp/[192.168.21.76]:5060 at 07:59:06.093433:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.21.76:5060
;branch=z9hG4bK-d8754z-f0f812882a888ca8-1---d8754z-
   From: "1001"<sip:1001@192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   To: <sip:9179@192.168.26.39:25060;transport=UDP>;tag=tvm1aapX796BN
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 2 INVITE
   Contact: <sip:9179@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla,
include-session-description, presence.winfo, message-summary, refer
   Session-Expires: 1800;refresher=uas
   Min-SE: 120
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 248
   Remote-Party-ID: "9179" <sip:9179@192.168.26.39<sip%3A9179@192.168.26.39>
...
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1278544026 1278544027 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=audio 31920 RTP/AVP 3 101
   a=rtpmap:3 GSM/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=silenceSupp:off - - - -
   a=ptime:20
   ------------------------------------------------------------------------
[31m2010-07-08 15:59:06.091095 [ERR] mod_spandsp_fax.c:445 trans mode = 1
2010-07-08 15:59:06.091095 [DEBUG] sofia.c:4297 Channel sofia/internal/
1001@192.168.26.39:25060 entering state [completed][200]
[31m2010-07-08 15:59:06.091095 [ERR] mod_spandsp_fax.c:591 This is for fax
test: prag go to here!!!
2010-07-08 15:59:06.091095 [DEBUG] mod_spandsp_fax.c:1064 Raw read codec
activation Success L16 20000
2010-07-08 15:59:06.091095 [DEBUG] switch_core_codec.c:122 sofia/internal/
1001@192.168.26.39:25060 Push codec L16:10
2010-07-08 15:59:06.091095 [DEBUG] mod_spandsp_fax.c:1080 Raw write codec
activation Success L16
recv 755 bytes from udp/[192.168.21.76]:5060 at 07:59:06.111565:
   ------------------------------------------------------------------------
   ACK sip:9179@192.168.26.39:25060;transport=udp SIP/2.0
   Via: SIP/2.0/UDP 192.168.21.76:5060
;branch=z9hG4bK-d8754z-d8c37a91bfb28de8-1---d8754z-
   Max-Forwards: 70
   Contact: <sip:1001@192.168.21.76:5060;transport=UDP>
   To: <sip:9179@192.168.26.39:25060;transport=UDP>;tag=tvm1aapX796BN
   From: "1001"<sip:1001@192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 2 ACK
   Proxy-Authorization: Digest
username="1001",realm="192.168.26.39",nonce="fba8e3e1-c3d7-4faa-b367-7ba18ad5c276",uri="sip:9179@192.168.26.39:25060
;transport=UDP",response="02f8fac6c1ea37df7e41b1be478f46d2",cnonce="78fc6a89edc259bb930a8b2e2da67a14",nc=00000001,qop=auth,algorithm=MD5
   User-Agent: Zoiper rev.6751
   Content-Length: 0

   ------------------------------------------------------------------------
2010-07-08 15:59:06.109092 [DEBUG] sofia.c:4297 Channel sofia/internal/
1001@192.168.26.39:25060 entering state [ready][200]
2010-07-08 15:59:06.163089 [DEBUG] switch_rtp.c:2517 Correct ip/port
confirmed.
[31m2010-07-08 16:00:07.342919 [ERR] mod_spandsp_fax.c:1119 pvt->t38_mode =
0
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:302 result = 2
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:304
==============================================================================
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:316 Fax processing not
successful - result (2) Timed out waiting for initial communication.
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:321 Remote station id:
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:322 Local station id:
 SpanDSP Fax Ident
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:323 Pages transferred:
0
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:325 Total fax pages:
0
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:326 Image resolution:
 0x0
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:327 Transfer Rate:
14400
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:329 ECM status
off
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:330 remote country:
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:331 remote vendor:
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:332 remote model:
2010-07-08 16:00:07.342919 [DEBUG] mod_spandsp_fax.c:334
==============================================================================
2010-07-08 16:00:07.362919 [DEBUG] switch_core_codec.c:146 sofia/internal/
1001@192.168.26.39:25060 Restore previous codec GSM:3.
EXECUTE sofia/internal/1001@192.168.26.39:25060 hangup()
2010-07-08 16:00:07.362919 [DEBUG] switch_channel.c:2261 (sofia/internal/
1001@192.168.26.39:25060) Callstate Change ACTIVE -> HANGUP
2010-07-08 16:00:07.362919 [NOTICE] mod_dptools.c:732 Hangup sofia/internal/
1001@192.168.26.39:25060 [CS_EXECUTE] [NORMAL_CLEARING]
2010-07-08 16:00:07.362919 [DEBUG] switch_channel.c:2277 Send signal
sofia/internal/1001@192.168.26.39:25060 [KILL]
2010-07-08 16:00:07.362919 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1001@192.168.26.39:25060 [BREAK]
2010-07-08 16:00:07.362919 [DEBUG] switch_core_state_machine.c:348
(sofia/internal/1001@192.168.26.39:25060) State EXECUTE going to sleep
2010-07-08 16:00:07.362919 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1001@192.168.26.39:25060) Running State Change CS_HANGUP
2010-07-08 16:00:07.362919 [DEBUG] switch_core_state_machine.c:500
(sofia/internal/1001@192.168.26.39:25060) State HANGUP
2010-07-08 16:00:07.362919 [DEBUG] mod_sofia.c:447 Channel sofia/internal/
1001@192.168.26.39:25060 hanging up, cause: NORMAL_CLEARING
2010-07-08 16:00:07.420917 [DEBUG] mod_sofia.c:490 Sending BYE to
sofia/internal/1001@192.168.26.39:25060
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:46
sofia/internal/1001@192.168.26.39:25060 Standard HANGUP, cause:
NORMAL_CLEARING
send 690 bytes to udp/[192.168.21.76]:5060 at 08:00:07.423127:
   ------------------------------------------------------------------------
   BYE sip:1001@192.168.21.76:5060;transport=UDP SIP/2.0
   Via: SIP/2.0/UDP 192.168.26.39:25060;rport;branch=z9hG4bKN7S88805r15eF
   Max-Forwards: 70
   From: <sip:9179@192.168.26.39:25060;transport=UDP>;tag=tvm1aapX796BN
   To: "1001" <sip:1001@192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 133169667 BYE
   Contact: <sip:9179@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:500
(sofia/internal/1001@192.168.26.39:25060) State HANGUP going to sleep
   Supported: timer, precondition, path, replaces
   Reason: Q.850;cause=16;text="NORMAL_CLEARING"
   Content-Length: 0

   ------------------------------------------------------------------------
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:333
(sofia/internal/1001@192.168.26.39:25060) State Change CS_HANGUP ->
CS_REPORTING
2010-07-08 16:00:07.422926 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1001@192.168.26.39:25060 [BREAK]
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1001@192.168.26.39:25060) Running State Change CS_REPORTING
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:591
(sofia/internal/1001@192.168.26.39:25060) State REPORTING
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:53
sofia/internal/1001@192.168.26.39:25060 Standard REPORTING, cause:
NORMAL_CLEARING
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:591
(sofia/internal/1001@192.168.26.39:25060) State REPORTING going to sleep
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:327
(sofia/internal/1001@192.168.26.39:25060) State Change CS_REPORTING ->
CS_DESTROY
2010-07-08 16:00:07.422926 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1001@192.168.26.39:25060 [BREAK]
2010-07-08 16:00:07.422926 [DEBUG] switch_core_session.c:1175 Session 1
(sofia/internal/1001@192.168.26.39:25060) Locked, Waiting on external
entities
2010-07-08 16:00:07.422926 [NOTICE] switch_core_session.c:1193 Session 1
(sofia/internal/1001@192.168.26.39:25060) Ended
2010-07-08 16:00:07.422926 [NOTICE] switch_core_session.c:1195 Close Channel
sofia/internal/1001@192.168.26.39:25060 [CS_DESTROY]
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:427
(sofia/internal/1001@192.168.26.39:25060) Callstate Change HANGUP -> DOWN
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:430
(sofia/internal/1001@192.168.26.39:25060) Running State Change CS_DESTROY
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:440
(sofia/internal/1001@192.168.26.39:25060) State DESTROY
2010-07-08 16:00:07.422926 [DEBUG] mod_sofia.c:352 sofia/internal/
1001@192.168.26.39:25060 SOFIA DESTROY
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:60
sofia/internal/1001@192.168.26.39:25060 Standard DESTROY
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:440
(sofia/internal/1001@192.168.26.39:25060) State DESTROY going to sleep
recv 413 bytes from udp/[192.168.21.76]:5060 at 08:00:07.628924:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060
;rport=25060;branch=z9hG4bKN7S88805r15eF
   Contact: <sip:1001@192.168.21.76:5060;transport=UDP>
   To: "1001"<sip:1001@192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   From: <sip:9179@192.168.26.39:25060;transport=UDP>;tag=tvm1aapX796BN
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 133169667 BYE
   User-Agent: Zoiper rev.6751
   Content-Length: 0

 !!!---------------------------------log
end-------------------------------------!!!


2010/7/8 chi zhang <sanms.zhang@gmail.com>
...
Now, i have finished T.38 fax receive test with Zoiper, it works well.
But, transfer a fax is still  Not successful.
    Previous, i do simulate fax with sipP, but FS return 48(Disconnected
after permitted retries).
    Accidentally, i found softphone: Zoiper has fax function, so retry fax
with it, and in diaplan file: default.xml,  9178 was the receive fax number.
So i call 9178 with Zoiper(register as 1000), fax receiving is perfect done.
    Then i test transfer fax function: call to 9179(configured by TX fax in
default.xml), but FS return 2 (Timed out waiting for initial communication)
, i have no idea about it, reason ?
regards
sammy
2010/7/2 chi zhang <sanms.zhang@gmail.com>
I got it.
...
-------------------log start--------------------------
recv 576 bytes from udp/[192.168.26.39]:15060 at 01:41:26.500127:
------------------------------------------------------------------------
   INVITE sip:666666@192.168.26.39:25060 SIP/2.0
   Via: SIP/2.0/UDP 192.168.26.39:15060;branch=z9hG4bK-16005-1-0
   From: 1000 <sip:1000@192.168.26.39:15060>;tag=1
   To: 666666 <sip:666666@192.168.26.39:25060>
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: sip:1000@192.168.26.39:15060
   Max-Forwards: 70
   Subject: Performance Test
   Content-Type: application/sdp
   Content-Length:   184
v=0
   o=user1 3748 3748 IN IP4 192.168.26.39
   s=-
   c=IN IP4 192.168.26.39
   t=0 0
   m=audio 6000 RTP/AVP 8 101
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-11,16
------------------------------------------------------------------------
send 294 bytes to udp/[192.168.26.39]:15060 at 01:41:26.500477:
------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 192.168.26.39:15060;branch=z9hG4bK-16005-1-0
   From: 1000 <sip:1000@192.168.26.39:15060>;tag=1
   To: 666666 <sip:666666@192.168.26.39:25060>
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Content-Length: 0
------------------------------------------------------------------------
2010-07-02 09:41:26.498946 [DEBUG] sofia.c:5928 IP 192.168.26.39 Approved
by acl "192.168.26.0/24[] <http://192.168.26.0/24%5B%5D>". Access
Granted.
 [36m2010-07-02 09:41:26.498946 [NOTICE] switch_channel.c:776 New Channel
sofia/internal/1000@192.168.26.39:15060[f588c66c-48c8-4220-a944-8de287adb3ab]
2010-07-02 09:41:26.500953 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000@192.168.26.39:15060) Running State Change CS_NEW
2010-07-02 09:41:26.500953 [DEBUG] switch_core_state_machine.c:320
(sofia/internal/1000@192.168.26.39:15060) State NEW
2010-07-02 09:41:26.542951 [DEBUG] sofia.c:4297 Channel sofia/internal/
1000@192.168.26.39:15060 entering state [received][100]
2010-07-02 09:41:26.542951 [DEBUG] sofia.c:4308 Remote SDP:
v=0
o=user1 3748 3748 IN IP4 192.168.26.39
s=-
c=IN IP4 192.168.26.39
t=0 0
m=audio 6000 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-11,16
2010-07-02 09:41:26.542951 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[PCMA:8:8000:20]/[G7221:115:32000:20]
2010-07-02 09:41:26.542951 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[PCMA:8:8000:20]/[G7221:107:16000:20]
2010-07-02 09:41:26.542951 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[PCMA:8:8000:20]/[G722:9:8000:20]
2010-07-02 09:41:26.542951 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[PCMA:8:8000:20]/[PCMU:0:8000:20]
2010-07-02 09:41:26.542951 [DEBUG] sofia_glue.c:3859 Audio Codec Compare
[PCMA:8:8000:20]/[PCMA:8:8000:20]
2010-07-02 09:41:26.542951 [DEBUG] sofia_glue.c:2442 Set Codec
sofia/internal/1000@192.168.26.39:15060 PCMA/8000 20 ms 160 samples
2010-07-02 09:41:26.542951 [DEBUG] sofia_glue.c:3798 Set 2833 dtmf
send/recv payload to 101
2010-07-02 09:41:26.542951 [DEBUG] sofia.c:4455 (sofia/internal/
1000@192.168.26.39:15060) State Change CS_NEW -> CS_INIT
2010-07-02 09:41:26.542951 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1000@192.168.26.39:15060 [BREAK]
2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000@192.168.26.39:15060) Running State Change CS_INIT
2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:338
(sofia/internal/1000@192.168.26.39:15060) State INIT
2010-07-02 09:41:26.544953 [DEBUG] mod_sofia.c:83 sofia/internal/
1000@192.168.26.39:15060 SOFIA INIT
2010-07-02 09:41:26.544953 [DEBUG] mod_sofia.c:119 (sofia/internal/
1000@192.168.26.39:15060) State Change CS_INIT -> CS_ROUTING
2010-07-02 09:41:26.544953 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1000@192.168.26.39:15060 [BREAK]
2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:338
(sofia/internal/1000@192.168.26.39:15060) State INIT going to sleep
2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000@192.168.26.39:15060) Running State Change CS_ROUTING
2010-07-02 09:41:26.544953 [DEBUG] switch_channel.c:1471 (sofia/internal/
1000@192.168.26.39:15060) Callstate Change DOWN -> RINGING
2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:341
(sofia/internal/1000@192.168.26.39:15060) State ROUTING
2010-07-02 09:41:26.544953 [DEBUG] mod_sofia.c:142 sofia/internal/
1000@192.168.26.39:15060 SOFIA ROUTING
2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:77
sofia/internal/1000@192.168.26.39:15060 Standard ROUTING
 [32m2010-07-02 09:41:26.544953 [INFO] mod_dialplan_xml.c:331 Processing
1000->666666 in context public
Dialplan: sofia/internal/1000@192.168.26.39:15060 parsing [public->fax]
continue=false
Dialplan: sofia/internal/1000@192.168.26.39:15060 Regex (FAIL) [fax]
destination_number(666666) =~ /^fax$/ break=on-false
Dialplan: sofia/internal/1000@192.168.26.39:15060 parsing [public->4444]
continue=false
Dialplan: sofia/internal/1000@192.168.26.39:15060 Regex (FAIL) [4444]
destination_number(666666) =~ /^(4444)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.26.39:15060 parsing [public->fax]
continue=false
Dialplan: sofia/internal/1000@192.168.26.39:15060 Regex (FAIL) [fax]
destination_number(666666) =~ /^fax$/ break=on-false
Dialplan: sofia/internal/1000@192.168.26.39:15060 parsing
[public->666666] continue=false
Dialplan: sofia/internal/1000@192.168.26.39:15060 Regex (PASS) [666666]
destination_number(666666) =~ /^(666666)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.26.39:15060 Action answer()
Dialplan: sofia/internal/1000@192.168.26.39:15060 Action
playback(silence_stream://2000)
Dialplan: sofia/internal/1000@192.168.26.39:15060 Action
rxfax(/tmp/999.tiff)
Dialplan: sofia/internal/1000@192.168.26.39:15060 Action hangup()
2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:119
(sofia/internal/1000@192.168.26.39:15060) State Change CS_ROUTING ->
CS_EXECUTE
2010-07-02 09:41:26.544953 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1000@192.168.26.39:15060 [BREAK]
2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:341
(sofia/internal/1000@192.168.26.39:15060) State ROUTING going to sleep
2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000@192.168.26.39:15060) Running State Change CS_EXECUTE
2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:348
(sofia/internal/1000@192.168.26.39:15060) State EXECUTE
2010-07-02 09:41:26.544953 [DEBUG] mod_sofia.c:235 sofia/internal/
1000@192.168.26.39:15060 SOFIA EXECUTE
2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:157
sofia/internal/1000@192.168.26.39:15060 Standard EXECUTE
 EXECUTE sofia/internal/1000@192.168.26.39:15060 answer()
2010-07-02 09:41:26.580967 [DEBUG] sofia_glue.c:2682 AUDIO RTP
[sofia/internal/1000@192.168.26.39:15060] 192.168.26.39 port 22464 ->
192.168.26.39 port 6000 codec: 8 ms: 20
2010-07-02 09:41:26.580967 [DEBUG] switch_rtp.c:1413 Starting timer [soft]
160 bytes per 20ms
2010-07-02 09:41:26.582952 [DEBUG] sofia_glue.c:2892 Set 2833 dtmf send
payload to 101
2010-07-02 09:41:26.582952 [DEBUG] sofia_glue.c:2897 Set 2833 dtmf receive
payload to 101
2010-07-02 09:41:26.582952 [DEBUG] mod_sofia.c:669 Local SDP
sofia/internal/1000@192.168.26.39:15060:
v=0
o=FreeSWITCH 1278012422 1278012423 IN IP4 192.168.26.39
 s=FreeSWITCH
c=IN IP4 192.168.26.39
t=0 0
m=audio 22464 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
send 1091 bytes to udp/[192.168.26.39]:15060 at 01:41:26.584093:
------------------------------------------------------------------------
    SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:15060;branch=z9hG4bK-16005-1-0
   From: 1000 <sip:1000@192.168.26.39:15060>;tag=1
   To: 666666 <sip:666666@192.168.26.39:25060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla,
include-session-description, presence.winfo, message-summary, refer
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 249
   Remote-Party-ID: "666666" <sip:666666@192.168.26.39<sip%3A666666@192.168.26.39>
...
;party=calling;privacy=off;screen=no
v=0
   o=FreeSWITCH 1278012422 1278012423 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=audio 22464 RTP/AVP 8 101
   a=rtpmap:8 PCMA/8000
   a=rtpmap:101 telephone-event/8000
   a=fmtp:101 0-16
   a=silenceSupp:off - - - -
   a=ptime:20
------------------------------------------------------------------------
recv 369 bytes from udp/[192.168.26.39]:15060 at 01:41:26.584205:
------------------------------------------------------------------------
   ACK sip:666666@192.168.26.39:25060 SIP/2.0
   Via: SIP/2.0/UDP 192.168.26.39:15060;branch=z9hG4bK-16005-1-4
   From: 1000 <sip:1000@192.168.26.39:15060>;tag=1
   To: 666666 <sip:666666@192.168.26.39:25060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 ACK
   Contact: sip:1000@192.168.26.39:15060
   Max-Forwards: 70
   Subject: Performance Test
   Content-Length: 0
------------------------------------------------------------------------
2010-07-02 09:41:26.582952 [DEBUG] sofia.c:4297 Channel sofia/internal/
1000@192.168.26.39:15060 entering state [completed][200]
2010-07-02 09:41:26.582952 [DEBUG] sofia.c:4297 Channel sofia/internal/
1000@192.168.26.39:15060 entering state [ready][200]
2010-07-02 09:41:26.582952 [DEBUG] switch_core_session.c:647 Send signal
sofia/internal/1000@192.168.26.39:15060 [BREAK]
2010-07-02 09:41:26.582952 [DEBUG] switch_channel.c:2494 (sofia/internal/
1000@192.168.26.39:15060) Callstate Change RINGING -> ACTIVE
[36m2010-07-02 09:41:26.582952 [NOTICE] mod_dptools.c:746 Channel
[sofia/internal/1000@192.168.26.39:15060] has been answered
 EXECUTE sofia/internal/1000@192.168.26.39:15060playback(silence_stream://2000)
2010-07-02 09:41:26.584953 [DEBUG] switch_ivr_play_say.c:1161 Codec
Activated L16@8000hz 1 channels 20ms
2010-07-02 09:41:28.578954 [DEBUG] switch_ivr_play_say.c:1468 done playing
file
EXECUTE sofia/internal/1000@192.168.26.39:15060 rxfax(/tmp/999.tiff)
2010-07-02 09:41:28.578954 [ERR] mod_spandsp.c:64 This is for fax test:
receive fax
2010-07-02 09:41:28.578954 [ERR] mod_spandsp_fax.c:445 trans mode = 1
2010-07-02 09:41:28.578954 [ERR] mod_spandsp_fax.c:591 This is for fax
test: prag go to here!!!
2010-07-02 09:41:28.578954 [DEBUG] mod_spandsp_fax.c:1064 Raw read codec
activation Success L16 20000
2010-07-02 09:41:28.578954 [DEBUG] switch_core_codec.c:122 sofia/internal/
1000@192.168.26.39:15060 Push codec L16:10
2010-07-02 09:41:28.578954 [DEBUG] mod_spandsp_fax.c:1080 Raw write codec
activation Success L16
2010-07-02 09:41:28.857958 [ERR] mod_spandsp_fax.c:1119 pvt->t38_mode = 0
send 956 bytes to udp/[192.168.21.76]:5060 at 01:41:29.754477:
------------------------------------------------------------------------
   NOTIFY sip:1001@192.168.21.76:5060 SIP/2.0
   Via: SIP/2.0/UDP 192.168.26.39:25060;rport;branch=z9hG4bKS989SFgmXvmNF
   Max-Forwards: 70
    From: "1001" <sip:1001@192.168.26.39:25060
;transport=UDP>;tag=vr6p3K1XK247r
   To: "1001" <sip:1001@192.168.26.39:25060;transport=UDP>;tag=26647676
   Call-ID: ZTljY2QxZGQ2NGEwOWQwNzRmMDMzYWM2ZDA4NzczNTY.
   CSeq: 132897310 NOTIFY
   Contact: <sip:1001@192.168.26.39:25060>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Event: message-summary
   Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla,
include-session-description, presence.winfo, message-summary, refer
   Subscription-State: terminated;reason=timeout
   Content-Type: application/simple-message-summary
   Content-Length: 65
Messages-Waiting: no
   Message-Account: sip:1001@192.168.26.39 <sip%3A1001@192.168.26.39>
------------------------------------------------------------------------
2010-07-02 09:41:29.757979 [ERR] mod_spandsp_fax.c:1119 pvt->t38_mode = 0
recv 389 bytes from udp/[192.168.21.76]:5060 at 01:41:29.758362:
------------------------------------------------------------------------
   SIP/2.0 481 Call/Transaction Does Not Exist
   Via: SIP/2.0/UDP 192.168.26.39:25060
;rport=25060;branch=z9hG4bKS989SFgmXvmNF
   To: "1001"<sip:1001@192.168.26.39:25060;transport=UDP>;tag=26647676
   From: "1001"<sip:1001@192.168.26.39:25060
;transport=UDP>;tag=vr6p3K1XK247r
   Call-ID: ZTljY2QxZGQ2NGEwOWQwNzRmMDMzYWM2ZDA4NzczNTY.
   CSeq: 132897310 NOTIFY
   Accept-Language: en
   Content-Length: 0
------------------------------------------------------------------------
2010-07-02 09:41:36.557090 [ERR] mod_spandsp_fax.c:1119 pvt->t38_mode = 0
recv 737 bytes from udp/[192.168.26.39]:15060 at 01:41:36.587253:
------------------------------------------------------------------------
   INVITE sip:1000@192.168.26.39:15060 SIP/2.0
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: sip:666666@192.168.26.39:25060
    Max-Forwards: 70
   Subject: Performance Test
   Content-Type: application/sdp
   Content-Length:   345
v=0
   o=root 0 0 IN IP4 192.168.26.39
    s=Session SDP
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 49172 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:9600
   a=T38FaxFillBitRemoval:0
   a=T38FaxTranscodingMMR:0
   a=T38FaxTranscodingJBIG:0
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:200
   a=T38FaxMaxDatagram:72
   a=T38FaxUdpEC:t38UDPRedundancy
------------------------------------------------------------------------
send 312 bytes to udp/[192.168.26.39]:25060 at 01:41:36.587546:
------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Content-Length: 0
------------------------------------------------------------------------
recv 312 bytes from udp/[192.168.26.39]:25060 at 01:41:36.587641:
------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE2010-07-02 09:41:36.587092 [DEBUG] sofia.c:4297 Channel
sofia/internal/1000@192.168.26.39:15060 entering state [received][100]
User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Content-Length: 0
------------------------------------------------------------------------
2010-07-02 09:41:36.587092 [DEBUG] sofia.c:4308 Remote SDP:
 v=0
o=root 0 0 IN IP4 192.168.26.39
s=Session SDP
c=IN IP4 192.168.26.39
t=0 0
m=image 49172 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:9600
a=T38FaxFillBitRemoval:0
a=T38FaxTranscodingMMR:0
a=T38FaxTranscodingJBIG:0
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:200
a=T38FaxMaxDatagram:72
a=T38FaxUdpEC:t38UDPRedundancy
2010-07-02 09:41:36.597094 [ERR] mod_spandsp_fax.c:1119 pvt->t38_mode = 0
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:744 T38FaxVersion = 0
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:745 T38MaxBitRate =
9600
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:746
T38FaxFillBitRemoval = 1
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:747
T38FaxTranscodingMMR = 1
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:748
T38FaxTranscodingJBIG = 1
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:749
T38FaxRateManagement = 'transferredTCF'
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:750 T38FaxMaxBuffer =
200
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:751 T38FaxMaxDatagram
= 72
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:752 T38FaxUdpEC =
't38UDPRedundancy'
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:753 T38VendorInfo =
''
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:754 ip =
'192.168.26.39'
2010-07-02 09:41:36.597094 [DEBUG] mod_spandsp_fax.c:756 port = 49172
2010-07-02 09:41:36.597094 [DEBUG] mod_sofia.c:1232 IMAGE UDPTL CHANGING
DEST TO: [192.168.26.39:49172]
2010-07-02 09:41:36.597094 [DEBUG] sofia_glue.c:122 sofia/internal/
1000@192.168.26.39:15060 image media sdp:
v=0
o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
 s=FreeSWITCH
c=IN IP4 192.168.26.39
t=0 0
m=image 22464 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxFillBitRemoval
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:2000
a=T38FaxMaxDatagram:400
a=T38FaxUdpEC:t38UDPRedundancy
a=T38VendorInfo:0 0 0
2010-07-02 09:41:36.597094 [ERR] mod_spandsp_fax.c:445 trans mode = 0
send 952 bytes to udp/[192.168.26.39]:25060 at 01:41:36.597525:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
2010-07-02 09:41:36.597094 [DEBUG] sofia.c:4297 Channel sofia/internal/
1000@192.168.26.39:15060 entering state [completed][200]
recv 952 bytes from udp/[192.168.26.39]:25060 at 01:41:36.597657:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
    s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
    a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
2010-07-02 09:41:36.607095 [ERR] mod_spandsp_fax.c:1119 pvt->t38_mode = 1
recv 737 bytes from udp/[192.168.26.39]:15060 at 01:41:37.089156:
------------------------------------------------------------------------
   INVITE sip:1000@192.168.26.39:15060 SIP/2.0
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: sip:666666@192.168.26.39:25060
   Max-Forwards: 70
   Subject: Performance Test
   Content-Type: application/sdp
    Content-Length:   345
v=0
   o=root 0 0 IN IP4 192.168.26.39
   s=Session SDP
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 49172 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:9600
   a=T38FaxFillBitRemoval:0
   a=T38FaxTranscodingMMR:0
   a=T38FaxTranscodingJBIG:0
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:200
   a=T38FaxMaxDatagram:72
   a=T38FaxUdpEC:t38UDPRedundancy
------------------------------------------------------------------------
send 952 bytes to udp/[192.168.26.39]:25060 at 01:41:37.089302:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
    s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
    a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
recv 952 bytes from udp/[192.168.26.39]:25060 at 01:41:37.089395:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
send 952 bytes to udp/[192.168.26.39]:25060 at 01:41:37.098259:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
recv 952 bytes from udp/[192.168.26.39]:25060 at 01:41:37.098339:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
recv 737 bytes from udp/[192.168.26.39]:15060 at 01:41:38.091157:
------------------------------------------------------------------------
   INVITE sip:1000@192.168.26.39:15060 SIP/2.0
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: sip:666666@192.168.26.39:25060
    Max-Forwards: 70
   Subject: Performance Test
   Content-Type: application/sdp
   Content-Length:   345
v=0
   o=root 0 0 IN IP4 192.168.26.39
    s=Session SDP
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 49172 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:9600
   a=T38FaxFillBitRemoval:0
   a=T38FaxTranscodingMMR:0
   a=T38FaxTranscodingJBIG:0
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:200
   a=T38FaxMaxDatagram:72
   a=T38FaxUdpEC:t38UDPRedundancy
------------------------------------------------------------------------
send 952 bytes to udp/[192.168.26.39]:25060 at 01:41:38.091305:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
    s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
    a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
recv 952 bytes from udp/[192.168.26.39]:25060 at 01:41:38.091406:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
send 952 bytes to udp/[192.168.26.39]:25060 at 01:41:38.098260:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
recv 952 bytes from udp/[192.168.26.39]:25060 at 01:41:38.098344:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
This is for fax test: dis 5This is for fax test: cause disconnect 4
recv 737 bytes from udp/[192.168.26.39]:15060 at 01:41:40.093267:
------------------------------------------------------------------------
   INVITE sip:1000@192.168.26.39:15060 SIP/2.0
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: sip:666666@192.168.26.39:25060
   Max-Forwards: 70
   Subject: Performance Test
   Content-Type: application/sdp
    Content-Length:   345
v=0
   o=root 0 0 IN IP4 192.168.26.39
   s=Session SDP
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 49172 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:9600
   a=T38FaxFillBitRemoval:0
   a=T38FaxTranscodingMMR:0
   a=T38FaxTranscodingJBIG:0
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:200
   a=T38FaxMaxDatagram:72
   a=T38FaxUdpEC:t38UDPRedundancy
------------------------------------------------------------------------
send 952 bytes to udp/[192.168.26.39]:25060 at 01:41:40.093421:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
    s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
    a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
recv 952 bytes from udp/[192.168.26.39]:25060 at 01:41:40.093541:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
send 952 bytes to udp/[192.168.26.39]:25060 at 01:41:40.098263:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
recv 952 bytes from udp/[192.168.26.39]:25060 at 01:41:40.098418:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
2010-07-02 09:41:41.606147 [ERR] mod_spandsp_fax.c:1119 pvt->t38_mode = 1
recv 737 bytes from udp/[192.168.26.39]:15060 at 01:41:44.095284:
------------------------------------------------------------------------
   INVITE sip:1000@192.168.26.39:15060 SIP/2.0
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: sip:666666@192.168.26.39:25060
   Max-Forwards: 70
   Subject: Performance Test
   Content-Type: application/sdp
    Content-Length:   345
v=0
   o=root 0 0 IN IP4 192.168.26.39
   s=Session SDP
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 49172 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:9600
   a=T38FaxFillBitRemoval:0
   a=T38FaxTranscodingMMR:0
   a=T38FaxTranscodingJBIG:0
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:200
   a=T38FaxMaxDatagram:72
   a=T38FaxUdpEC:t38UDPRedundancy
------------------------------------------------------------------------
send 952 bytes to udp/[192.168.26.39]:25060 at 01:41:44.095457:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
    s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
    a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
recv 952 bytes from udp/[192.168.26.39]:25060 at 01:41:44.095556:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
send 952 bytes to udp/[192.168.26.39]:25060 at 01:41:44.098263:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
recv 952 bytes from udp/[192.168.26.39]:25060 at 01:41:44.098399:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
   s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
------------------------------------------------------------------------
2010-07-02 09:41:46.607257 [ERR] mod_spandsp_fax.c:1119 pvt->t38_mode = 1
send 952 bytes to udp/[192.168.26.39]:25060 at 01:41:48.098287:
------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/UDP 192.168.26.39:25060;branch=z9hG4bK-16005-1-7
   From: 666666 <sip:666666@192.168.26.39:25060>;tag=1
   To: 1000 <sip:1000@192.168.26.39:15060>;tag=85ySHt6rmaSyp
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   Contact: <sip:666666@192.168.26.39:25060;transport=udp>
   User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, precondition, path, replaces
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 341
v=0
   o=FreeSWITCH 1278012422 1278012424 IN IP4 192.168.26.39
    s=FreeSWITCH
   c=IN IP4 192.168.26.39
   t=0 0
   m=image 22464 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval
    a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38VendorInfo:0 0 0
-----------------------------log end----------------------------------
2010/7/2 Brian West <brian@freeswitch.org>
turn sip on
...
sofia profile xxx siptrace on
/b
On Jul 1, 2010, at 8:23 PM, chi zhang wrote:
...
hi,brian
_______________________________________________
FreeSWITCH-users mailing list
FreeSWITCH-users@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org