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
2010-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:25060 hash(insert/192.168.26.39-spymap/1001/d072ed1a-b182-4340-bbc8-e16923b4bed6)
EXECUTE sofia/internal/1001@192.168.26.39:25060 hash(insert/192.168.26.39-last_dial/1001/9179)
EXECUTE sofia/internal/1001@192.168.26.39:25060 hash(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)
2010-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>;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
   ------------------------------------------------------------------------
2010-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]
2010-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.
2010-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
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   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
   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[]". 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>;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:
   ------------------------------------------------------------------------
   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
   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:15060 playback(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
   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
   
   ------------------------------------------------------------------------
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
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   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
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   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
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   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
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   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
   Call-ID: 1-16005@192.168.26.39
   CSeq: 2 INVITE
   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