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

Thu Jul 8 01:05:33 PDT 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 at 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 at 192.168.21.76:5060;transport=UDP>
   To: <sip:9179 at 192.168.26.39:25060;transport=UDP>
   From: "1001"<sip:1001 at 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 at 192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   To: <sip:9179 at 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 at 192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   To: <sip:9179 at 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 at 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 at 192.168.26.39:25060;transport=UDP>;tag=SKU88e5Sa1gSS
   From: "1001"<sip:1001 at 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 at 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 at 192.168.21.76:5060;transport=UDP>
   To: <sip:9179 at 192.168.26.39:25060;transport=UDP>
   From: "1001"<sip:1001 at 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 at 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 at 192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   To: <sip:9179 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 192.168.26.39:25060 [BREAK]
2010-07-08 15:59:06.037086 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1001 at 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 at 192.168.26.39:25060) State INIT
2010-07-08 15:59:06.037086 [DEBUG] mod_sofia.c:83 sofia/internal/
1001 at 192.168.26.39:25060 SOFIA INIT
2010-07-08 15:59:06.037086 [DEBUG] mod_sofia.c:119 (sofia/internal/
1001 at 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 at 192.168.26.39:25060 [BREAK]
2010-07-08 15:59:06.037086 [DEBUG] switch_core_state_machine.c:338
(sofia/internal/1001 at 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 at 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 at 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 at 192.168.26.39:25060) State ROUTING
2010-07-08 15:59:06.037086 [DEBUG] mod_sofia.c:142 sofia/internal/
1001 at 192.168.26.39:25060 SOFIA ROUTING
2010-07-08 15:59:06.037086 [DEBUG] switch_core_state_machine.c:77
sofia/internal/1001 at 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 at 192.168.26.39:25060 parsing [default->unloop]
continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (PASS) [unloop]
${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [unloop]
${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->tod_example] continue=true
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Date/Time Match (PASS)
[tod_example] break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Action set(open=true)
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->holiday_example] continue=true
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Date/Time Match (FAIL)
[holiday_example] break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->global-intercept] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[global-intercept] destination_number(9179) =~ /^886$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->group-intercept] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[group-intercept] destination_number(9179) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->intercept-ext] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[intercept-ext] destination_number(9179) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing [default->redial]
continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [redial]
destination_number(9179) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing [default->global]
continue=true
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [global]
${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1001 at 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 at 192.168.26.39:25060 Absolute Condition
[global]
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Action
hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Action
hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Action
hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->snom-demo-2] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [snom-demo-2]
destination_number(9179) =~ /^9001$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->snom-demo-1] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [snom-demo-1]
destination_number(9179) =~ /^9000$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->eavesdrop] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [eavesdrop]
destination_number(9179) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->eavesdrop] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [eavesdrop]
destination_number(9179) =~ /^779$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->call_return] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [call_return]
destination_number(9179) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->del-group] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [del-group]
destination_number(9179) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->add-group] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [add-group]
destination_number(9179) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->call-group-simo] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[call-group-simo] destination_number(9179) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->call-group-order] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[call-group-order] destination_number(9179) =~ /^83(\d{2})$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->extension-intercom] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[extension-intercom] destination_number(9179) =~ /^8(10[01][0-9])$/
break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->Local_Extension] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[Local_Extension] destination_number(9179) =~ /^(10[01][0-9])$/
break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->Local_Extension_Skinny] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[Local_Extension_Skinny] destination_number(9179) =~ /^(20[01][0-9])$/
break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->group_dial_sales] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[group_dial_sales] destination_number(9179) =~ /^2000$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->group_dial_support] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[group_dial_support] destination_number(9179) =~ /^2001$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->group_dial_billing] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[group_dial_billing] destination_number(9179) =~ /^2002$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->operator] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [operator]
destination_number(9179) =~ /^(operator|0)$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing [default->vmain]
continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [vmain]
destination_number(9179) =~ /^vmain$|^4000$|^\*98$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing [default->sip_uri]
continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [sip_uri]
destination_number(9179) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->nb_conferences] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[nb_conferences] destination_number(9179) =~ /^(30\d{2})$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->wb_conferences] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[wb_conferences] destination_number(9179) =~ /^(31\d{2})$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->uwb_conferences] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[uwb_conferences] destination_number(9179) =~ /^(32\d{2})$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->cdquality_conferences] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[cdquality_conferences] destination_number(9179) =~ /^(33\d{2})$/
break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->freeswitch_public_conf_via_sip] continue=false
Dialplan: sofia/internal/1001 at 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 at 192.168.26.39:25060 parsing
[default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[mad_boss_intercom] destination_number(9179) =~ /^0911$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[mad_boss_intercom] destination_number(9179) =~ /^0912$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->mad_boss] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [mad_boss]
destination_number(9179) =~ /^0913$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->ivr_demo] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [ivr_demo]
destination_number(9179) =~ /^5000$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->dynamic_conference] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[dynamic_conference] destination_number(9179) =~ /^5001$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->rtp_multicast_page] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL)
[rtp_multicast_page] destination_number(9179) =~ /^pagegroup$|^7243$/
break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing [default->park]
continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [park]
destination_number(9179) =~ /^5900$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [unpark]
destination_number(9179) =~ /^5901$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->valet_park] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [valet_park]
destination_number(9179) =~ /^(6000)$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->valet_park] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [valet_park]
destination_number(9179) =~ /^(60\d[1-9])$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing [default->park]
continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (PASS) [park]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [park]
destination_number(9179) =~ /park\+(\d+)/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (PASS) [unpark]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [unpark]
destination_number(9179) =~ /^parking$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing [default->park]
continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (PASS) [park]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [park]
destination_number(9179) =~ /callpark/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing [default->unpark]
continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (PASS) [unpark]
source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [unpark]
destination_number(9179) =~ /pickup/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing [default->wait]
continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [wait]
destination_number(9179) =~ /^wait$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->fax_receive] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (FAIL) [fax_receive]
destination_number(9179) =~ /^9178$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 parsing
[default->fax_transmit] continue=false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Regex (PASS)
[fax_transmit] destination_number(9179) =~ /^9179$/ break=on-false
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Action
txfax(/tmp/999.tif)
Dialplan: sofia/internal/1001 at 192.168.26.39:25060 Action hangup()
2010-07-08 15:59:06.040087 [DEBUG] switch_core_state_machine.c:119
(sofia/internal/1001 at 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 at 192.168.26.39:25060 [BREAK]
2010-07-08 15:59:06.040087 [DEBUG] switch_core_state_machine.c:341
(sofia/internal/1001 at 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 at 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 at 192.168.26.39:25060) State EXECUTE
2010-07-08 15:59:06.040087 [DEBUG] mod_sofia.c:235 sofia/internal/
1001 at 192.168.26.39:25060 SOFIA EXECUTE
2010-07-08 15:59:06.040087 [DEBUG] switch_core_state_machine.c:157
sofia/internal/1001 at 192.168.26.39:25060 Standard EXECUTE
EXECUTE sofia/internal/1001 at 192.168.26.39:25060 set(open=true)
2010-07-08 15:59:06.040087 [DEBUG] mod_dptools.c:843 sofia/internal/
1001 at 192.168.26.39:25060 SET [open]=[true]
EXECUTE sofia/internal/1001 at 192.168.26.39:25060hash(insert/192.168.26.39-spymap/1001/d072ed1a-b182-4340-bbc8-e16923b4bed6)
EXECUTE sofia/internal/1001 at 192.168.26.39:25060hash(insert/192.168.26.39-last_dial/1001/9179)
EXECUTE sofia/internal/1001 at 192.168.26.39:25060hash(insert/192.168.26.39-last_dial/global/d072ed1a-b182-4340-bbc8-e16923b4bed6)
EXECUTE sofia/internal/1001 at 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 at 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 at 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 at 192.168.26.39:25060 [BREAK]
2010-07-08 15:59:06.091095 [DEBUG] switch_channel.c:2494 (sofia/internal/
1001 at 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 at 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 at 192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   To: <sip:9179 at 192.168.26.39:25060;transport=UDP>;tag=tvm1aapX796BN
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 2 INVITE
   Contact: <sip:9179 at 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 at 192.168.26.39<sip%3A9179 at 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 at 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 at 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 at 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 at 192.168.21.76:5060;transport=UDP>
   To: <sip:9179 at 192.168.26.39:25060;transport=UDP>;tag=tvm1aapX796BN
   From: "1001"<sip:1001 at 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 at 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 at 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 at 192.168.26.39:25060 Restore previous codec GSM:3.
EXECUTE sofia/internal/1001 at 192.168.26.39:25060 hangup()
2010-07-08 16:00:07.362919 [DEBUG] switch_channel.c:2261 (sofia/internal/
1001 at 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 at 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 at 192.168.26.39:25060 [KILL]
2010-07-08 16:00:07.362919 [DEBUG] switch_core_session.c:1027 Send signal
sofia/internal/1001 at 192.168.26.39:25060 [BREAK]
2010-07-08 16:00:07.362919 [DEBUG] switch_core_state_machine.c:348
(sofia/internal/1001 at 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 at 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 at 192.168.26.39:25060) State HANGUP
2010-07-08 16:00:07.362919 [DEBUG] mod_sofia.c:447 Channel sofia/internal/
1001 at 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 at 192.168.26.39:25060
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:46
sofia/internal/1001 at 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 at 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 at 192.168.26.39:25060;transport=UDP>;tag=tvm1aapX796BN
   To: "1001" <sip:1001 at 192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   Call-ID: YTIxZDI4ZWNmZGViZjFjNmI5ZTEyYWJmNGE1ZjExZWI.
   CSeq: 133169667 BYE
   Contact: <sip:9179 at 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 at 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 at 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 at 192.168.26.39:25060 [BREAK]
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1001 at 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 at 192.168.26.39:25060) State REPORTING
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:53
sofia/internal/1001 at 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 at 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 at 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 at 192.168.26.39:25060 [BREAK]
2010-07-08 16:00:07.422926 [DEBUG] switch_core_session.c:1175 Session 1
(sofia/internal/1001 at 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 at 192.168.26.39:25060) Ended
2010-07-08 16:00:07.422926 [NOTICE] switch_core_session.c:1195 Close Channel
sofia/internal/1001 at 192.168.26.39:25060 [CS_DESTROY]
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:427
(sofia/internal/1001 at 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 at 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 at 192.168.26.39:25060) State DESTROY
2010-07-08 16:00:07.422926 [DEBUG] mod_sofia.c:352 sofia/internal/
1001 at 192.168.26.39:25060 SOFIA DESTROY
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:60
sofia/internal/1001 at 192.168.26.39:25060 Standard DESTROY
2010-07-08 16:00:07.422926 [DEBUG] switch_core_state_machine.c:440
(sofia/internal/1001 at 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 at 192.168.21.76:5060;transport=UDP>
   To: "1001"<sip:1001 at 192.168.26.39:25060;transport=UDP>;tag=9b6ed37d
   From: <sip:9179 at 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 at 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 at 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 at 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 at 192.168.26.39:15060>;tag=1
>>    To: 666666 <sip:666666 at 192.168.26.39:25060>
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: sip:1000 at 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 at 192.168.26.39:15060>;tag=1
>>    To: 666666 <sip:666666 at 192.168.26.39:25060>
>>    Call-ID: 1-16005 at 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 at 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 at 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 at 192.168.26.39:15060) State NEW
>> 2010-07-02 09:41:26.542951 [DEBUG] sofia.c:4297 Channel sofia/internal/
>> 1000 at 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 at 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 at 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 at 192.168.26.39:15060 [BREAK]
>> 2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:314
>> (sofia/internal/1000 at 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 at 192.168.26.39:15060) State INIT
>> 2010-07-02 09:41:26.544953 [DEBUG] mod_sofia.c:83 sofia/internal/
>> 1000 at 192.168.26.39:15060 SOFIA INIT
>> 2010-07-02 09:41:26.544953 [DEBUG] mod_sofia.c:119 (sofia/internal/
>> 1000 at 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 at 192.168.26.39:15060 [BREAK]
>> 2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:338
>> (sofia/internal/1000 at 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 at 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 at 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 at 192.168.26.39:15060) State ROUTING
>> 2010-07-02 09:41:26.544953 [DEBUG] mod_sofia.c:142 sofia/internal/
>> 1000 at 192.168.26.39:15060 SOFIA ROUTING
>> 2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:77
>> sofia/internal/1000 at 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 at 192.168.26.39:15060 parsing [public->fax]
>> continue=false
>> Dialplan: sofia/internal/1000 at 192.168.26.39:15060 Regex (FAIL) [fax]
>> destination_number(666666) =~ /^fax$/ break=on-false
>> Dialplan: sofia/internal/1000 at 192.168.26.39:15060 parsing [public->4444]
>> continue=false
>> Dialplan: sofia/internal/1000 at 192.168.26.39:15060 Regex (FAIL) [4444]
>> destination_number(666666) =~ /^(4444)$/ break=on-false
>> Dialplan: sofia/internal/1000 at 192.168.26.39:15060 parsing [public->fax]
>> continue=false
>> Dialplan: sofia/internal/1000 at 192.168.26.39:15060 Regex (FAIL) [fax]
>> destination_number(666666) =~ /^fax$/ break=on-false
>> Dialplan: sofia/internal/1000 at 192.168.26.39:15060 parsing
>> [public->666666] continue=false
>> Dialplan: sofia/internal/1000 at 192.168.26.39:15060 Regex (PASS) [666666]
>> destination_number(666666) =~ /^(666666)$/ break=on-false
>> Dialplan: sofia/internal/1000 at 192.168.26.39:15060 Action answer()
>> Dialplan: sofia/internal/1000 at 192.168.26.39:15060 Action
>> playback(silence_stream://2000)
>> Dialplan: sofia/internal/1000 at 192.168.26.39:15060 Action
>> rxfax(/tmp/999.tiff)
>> Dialplan: sofia/internal/1000 at 192.168.26.39:15060 Action hangup()
>> 2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:119
>> (sofia/internal/1000 at 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 at 192.168.26.39:15060 [BREAK]
>> 2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:341
>> (sofia/internal/1000 at 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 at 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 at 192.168.26.39:15060) State EXECUTE
>> 2010-07-02 09:41:26.544953 [DEBUG] mod_sofia.c:235 sofia/internal/
>> 1000 at 192.168.26.39:15060 SOFIA EXECUTE
>> 2010-07-02 09:41:26.544953 [DEBUG] switch_core_state_machine.c:157
>> sofia/internal/1000 at 192.168.26.39:15060 Standard EXECUTE
>>  EXECUTE sofia/internal/1000 at 192.168.26.39:15060 answer()
>> 2010-07-02 09:41:26.580967 [DEBUG] sofia_glue.c:2682 AUDIO RTP
>> [sofia/internal/1000 at 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 at 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 at 192.168.26.39:15060>;tag=1
>>    To: 666666 <sip:666666 at 192.168.26.39:25060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39<sip%3A666666 at 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 at 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 at 192.168.26.39:15060>;tag=1
>>    To: 666666 <sip:666666 at 192.168.26.39:25060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 ACK
>>    Contact: sip:1000 at 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 at 192.168.26.39:15060 entering state [completed][200]
>> 2010-07-02 09:41:26.582952 [DEBUG] sofia.c:4297 Channel sofia/internal/
>> 1000 at 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 at 192.168.26.39:15060 [BREAK]
>> 2010-07-02 09:41:26.582952 [DEBUG] switch_channel.c:2494 (sofia/internal/
>> 1000 at 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 at 192.168.26.39:15060] has been answered
>>  EXECUTE sofia/internal/1000 at 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 at 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 at 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 at 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 at 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 at 192.168.26.39:25060
>> ;transport=UDP>;tag=vr6p3K1XK247r
>>    To: "1001" <sip:1001 at 192.168.26.39:25060;transport=UDP>;tag=26647676
>>    Call-ID: ZTljY2QxZGQ2NGEwOWQwNzRmMDMzYWM2ZDA4NzczNTY.
>>    CSeq: 132897310 NOTIFY
>>    Contact: <sip:1001 at 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 at 192.168.26.39 <sip%3A1001 at 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 at 192.168.26.39:25060;transport=UDP>;tag=26647676
>>    From: "1001"<sip:1001 at 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 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE2010-07-02 09:41:36.587092 [DEBUG] sofia.c:4297 Channel
>> sofia/internal/1000 at 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 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 192.168.26.39:25060>;tag=1
>>    To: 1000 <sip:1000 at 192.168.26.39:15060>;tag=85ySHt6rmaSyp
>>    Call-ID: 1-16005 at 192.168.26.39
>>    CSeq: 2 INVITE
>>    Contact: <sip:666666 at 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 at 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 at lists.freeswitch.org
>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>>> http://www.freeswitch.org
>>>
>>
>>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20100708/94ac68c1/attachment-0001.html