[Freeswitch-users] T38 Configuration
Robert Craig
craig.robert at hotmail.com
Thu Jan 10 23:43:09 MSK 2013
Thanks for the quick response. Below is the capture from the log viewer. In this test, I am using a remote fax machine (line from Cox with physical machine) faxing into the "email server" where I created an extension for fax-to-email. Calling number is 1479-246-0547.
2013-01-10 13:39:53.162506 [DEBUG] switch_core_state_machine.c:569 (sofia/external/+14792460547 at flowroute.com) State DESTROY going to sleep 2013-01-10 13:39:53.162506 [DEBUG] switch_core_state_machine.c:99 sofia/external/+14792460547 at flowroute.com Standard DESTROY 2013-01-10 13:39:53.162506 [DEBUG] mod_sofia.c:396 sofia/external/+14792460547 at flowroute.com SOFIA DESTROY 2013-01-10 13:39:53.162506 [DEBUG] switch_core_state_machine.c:569 (sofia/external/+14792460547 at flowroute.com) State DESTROY 2013-01-10 13:39:53.162506 [DEBUG] switch_core_state_machine.c:559 (sofia/external/+14792460547 at flowroute.com) Running State Change CS_DESTROY 2013-01-10 13:39:53.162506 [DEBUG] switch_core_state_machine.c:556 (sofia/external/+14792460547 at flowroute.com) Callstate Change HANGUP -> DOWN 2013-01-10 13:39:53.162506 [NOTICE] switch_core_session.c:1510 Close Channel sofia/external/+14792460547 at flowroute.com [CS_DESTROY] 2013-01-10 13:39:53.162506 [NOTICE] switch_core_session.c:1506 Session 26 (sofia/external/+14792460547 at flowroute.com) Ended 2013-01-10 13:39:53.162506 [DEBUG] switch_core_session.c:1488 Session 26 (sofia/external/+14792460547 at flowroute.com) Locked, Waiting on external entities 2013-01-10 13:39:53.162506 [DEBUG] switch_core_session.c:1283 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:53.162506 [DEBUG] switch_core_state_machine.c:440 (sofia/external/+14792460547 at flowroute.com) State Change CS_REPORTING -> CS_DESTROY 2013-01-10 13:39:53.162506 [DEBUG] switch_core_state_machine.c:749 (sofia/external/+14792460547 at flowroute.com) State REPORTING going to sleep 2013-01-10 13:39:53.162506 [DEBUG] switch_core_state_machine.c:92 sofia/external/+14792460547 at flowroute.com Standard REPORTING, cause: NORMAL_CLEARING 2013-01-10 13:39:53.066135 [DEBUG] switch_core_state_machine.c:749 (sofia/external/+14792460547 at flowroute.com) State REPORTING 2013-01-10 13:39:53.066135 [DEBUG] switch_core_state_machine.c:415 (sofia/external/+14792460547 at flowroute.com) Running State Change CS_REPORTING 2013-01-10 13:39:53.066135 [DEBUG] switch_core_session.c:1283 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:53.066135 [DEBUG] switch_core_state_machine.c:446 (sofia/external/+14792460547 at flowroute.com) State Change CS_HANGUP -> CS_REPORTING 2013-01-10 13:39:53.066135 [DEBUG] switch_core_state_machine.c:599 Hangup Command with no Session system(/usr/bin/php /var/www/html/fusionpbx/secure/fax_to_email.php email=robert.l.craig at craignetwork.com extension=3150 name=+14792460547-2013-01-10-13-39-26 messages='result: Disconnected after permitted retries sender:ATW-4792460547 pages:0' domain=192.168.4.15 caller_id_name='' caller_id_number=): 2013-01-10 13:39:52.216151 [DEBUG] switch_core_state_machine.c:667 (sofia/external/+14792460547 at flowroute.com) State HANGUP going to sleep 2013-01-10 13:39:52.216151 [DEBUG] switch_core_state_machine.c:48 sofia/external/+14792460547 at flowroute.com Standard HANGUP, cause: NORMAL_CLEARING 2013-01-10 13:39:52.216151 [DEBUG] mod_sofia.c:552 Sending BYE to sofia/external/+14792460547 at flowroute.com 2013-01-10 13:39:52.216151 [DEBUG] mod_sofia.c:503 Channel sofia/external/+14792460547 at flowroute.com hanging up, cause: NORMAL_CLEARING 2013-01-10 13:39:52.216151 [DEBUG] switch_core_state_machine.c:667 (sofia/external/+14792460547 at flowroute.com) State HANGUP 2013-01-10 13:39:52.216151 [DEBUG] switch_core_state_machine.c:415 (sofia/external/+14792460547 at flowroute.com) Running State Change CS_HANGUP 2013-01-10 13:39:52.216151 [DEBUG] switch_core_state_machine.c:477 (sofia/external/+14792460547 at flowroute.com) State EXECUTE going to sleep 2013-01-10 13:39:52.216151 [DEBUG] switch_core_session.c:2687 sofia/external/+14792460547 at flowroute.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2013-01-10 13:39:52.216151 [DEBUG] switch_core_session.c:1283 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:52.216151 [DEBUG] switch_channel.c:3017 Send signal sofia/external/+14792460547 at flowroute.com [KILL] 2013-01-10 13:39:52.216151 [NOTICE] mod_dptools.c:1150 Hangup sofia/external/+14792460547 at flowroute.com [CS_EXECUTE] [NORMAL_CLEARING] 2013-01-10 13:39:52.216151 [DEBUG] switch_channel.c:2994 (sofia/external/+14792460547 at flowroute.com) Callstate Change ACTIVE -> HANGUP EXECUTE sofia/external/+14792460547 at flowroute.com hangup() 2013-01-10 13:39:52.216151 [DEBUG] switch_core_codec.c:244 sofia/external/+14792460547 at flowroute.com Restore previous codec PCMU:0. 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:518 ============================================================================== 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:516 remote model: 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:515 remote vendor: 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:514 remote country: 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:513 ECM status off 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:511 Transfer Rate: 14400 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:510 Image resolution: 0x0 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:509 Total fax pages: 0 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:507 Pages transferred: 0 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:506 Local station id: SpanDSP Fax Ident 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:505 Remote station id: ATW-4792460547 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:500 Fax processing not successful - result (48) Disconnected after permitted retries. 2013-01-10 13:39:52.216151 [DEBUG] mod_spandsp_fax.c:487 ============================================================================== 2013-01-10 13:39:38.766190 [DEBUG] switch_rtp.c:3624 Correct ip/port confirmed. 2013-01-10 13:39:29.576127 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] a=T38FaxUdpEC:t38UDPRedundancy a=T38FaxMaxDatagram:400 a=T38FaxMaxBuffer:2000 a=T38FaxRateManagement:transferredTCF a=T38FaxFillBitRemoval a=T38MaxBitRate:9600 a=T38FaxVersion:0 m=image 27588 udptl t38 t=0 0 c=IN IP4 72.201.112.131 s=FreeSWITCH o=FreeSWITCH 1357822778 1357822782 IN IP4 72.201.112.131 v=0 2013-01-10 13:39:29.576127 [DEBUG] sofia_glue.c:173 sofia/external/+14792460547 at flowroute.com image media sdp: 2013-01-10 13:39:29.576127 [DEBUG] mod_sofia.c:1487 Remote address:port [66.42.121.5:31632] has not changed. 2013-01-10 13:39:29.576127 [DEBUG] mod_sofia.c:1487 Remote address:port [66.42.121.5:31632] has not changed. 2013-01-10 13:39:29.576127 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] a=T38FaxUdpEC:t38UDPRedundancy a=T38FaxMaxDatagram:400 a=T38FaxMaxBuffer:2000 a=T38FaxRateManagement:transferredTCF a=T38FaxFillBitRemoval a=T38MaxBitRate:9600 a=T38FaxVersion:0 m=image 27588 udptl t38 t=0 0 c=IN IP4 72.201.112.131 s=FreeSWITCH o=FreeSWITCH 1357822778 1357822781 IN IP4 72.201.112.131 v=0 2013-01-10 13:39:29.576127 [DEBUG] sofia_glue.c:173 sofia/external/+14792460547 at flowroute.com image media sdp: 2013-01-10 13:39:29.576127 [DEBUG] mod_sofia.c:1487 Remote address:port [66.42.121.5:31632] has not changed. 2013-01-10 13:39:29.576127 [DEBUG] mod_sofia.c:1487 Remote address:port [66.42.121.5:31632] has not changed. 2013-01-10 13:39:29.576127 [DEBUG] sofia_glue.c:3645 sofia/external/+14792460547 at flowroute.com Set rtp dtmf delay to 40 2013-01-10 13:39:29.576127 [DEBUG] sofia_glue.c:3618 Set 2833 dtmf receive payload to 101 2013-01-10 13:39:29.576127 [DEBUG] sofia_glue.c:3612 Set 2833 dtmf send payload to 101 2013-01-10 13:39:29.576127 [ERR] sofia_glue.c:3549 Invalid Jitterbuffer spec [0] must be between 20 and 10000 2013-01-10 13:39:29.576127 [DEBUG] switch_rtp.c:1928 Starting timer [soft] 160 bytes per 20ms 2013-01-10 13:39:29.576127 [DEBUG] sofia_glue.c:3348 AUDIO RTP [sofia/external/+14792460547 at flowroute.com] 192.168.4.15 port 27588 -> 66.42.121.5 port 31632 codec: 0 ms: 20 2013-01-10 13:39:29.566140 [DEBUG] sofia.c:5603 Channel sofia/external/+14792460547 at flowroute.com entering state [ready][200] 2013-01-10 13:39:29.566140 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:29.566140 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] a=T38FaxRateManagement:transferredTCF m=image 31632 udptl t38 t=0 0 c=IN IP4 66.42.121.5 s=- o=- 275426305 1357849876 IN IP4 66.42.121.5 v=0 2013-01-10 13:39:29.566140 [DEBUG] sofia.c:5614 Remote SDP: 2013-01-10 13:39:29.566140 [DEBUG] sofia.c:5603 Channel sofia/external/+14792460547 at flowroute.com entering state [completing][200] 2013-01-10 13:39:29.566140 [INFO] sofia.c:931 sofia/external/+14792460547 at flowroute.com Update Callee ID to "+14792460547" <+14792460547> 2013-01-10 13:39:29.556130 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:29.556130 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:29.266195 [DEBUG] sofia.c:5603 Channel sofia/external/+14792460547 at flowroute.com entering state [calling][0] 2013-01-10 13:39:29.256196 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] a=T38FaxUdpEC:t38UDPRedundancy a=T38FaxMaxDatagram:400 a=T38FaxMaxBuffer:2000 a=T38FaxRateManagement:transferredTCF a=T38FaxFillBitRemoval a=T38MaxBitRate:9600 a=T38FaxVersion:0 m=image 27588 udptl t38 t=0 0 c=IN IP4 72.201.112.131 s=FreeSWITCH o=FreeSWITCH 1357822778 1357822780 IN IP4 72.201.112.131 v=0 2013-01-10 13:39:29.256196 [DEBUG] sofia_glue.c:2647 Local SDP: a=T38FaxUdpEC:t38UDPRedundancy a=T38FaxMaxDatagram:400 a=T38FaxMaxBuffer:2000 a=T38FaxRateManagement:transferredTCF a=T38FaxFillBitRemoval a=T38MaxBitRate:9600 a=T38FaxVersion:0 m=image 27588 udptl t38 t=0 0 c=IN IP4 72.201.112.131 s=FreeSWITCH o=FreeSWITCH 1357822778 1357822780 IN IP4 72.201.112.131 v=0 2013-01-10 13:39:29.256196 [DEBUG] sofia_glue.c:173 sofia/external/+14792460547 at flowroute.com image media sdp: 2013-01-10 13:39:27.996269 [DEBUG] mod_spandsp_fax.c:1379 Raw write codec activation Success L16 2013-01-10 13:39:27.996269 [DEBUG] switch_core_codec.c:219 sofia/external/+14792460547 at flowroute.com Push codec L16:70 2013-01-10 13:39:27.996269 [DEBUG] mod_spandsp_fax.c:1363 Raw read codec activation Success L16 20000 EXECUTE sofia/external/+14792460547 at flowroute.com rxfax(/usr/local/freeswitch/storage/fax/3150/inbox/+14792460547-2013-01-10-13-39-26.tif) 2013-01-10 13:39:27.996269 [DEBUG] switch_ivr_play_say.c:1682 done playing file silence_stream://2000 2013-01-10 13:39:26.256197 [DEBUG] switch_rtp.c:3624 Correct ip/port confirmed. 2013-01-10 13:39:26.116239 [DEBUG] sofia.c:5603 Channel sofia/external/+14792460547 at flowroute.com entering state [ready][200] 2013-01-10 13:39:26.096329 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:26.096329 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:26.096329 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:26.016177 [DEBUG] switch_ivr_play_say.c:1309 Codec Activated L16 at 8000hz 1 channels 20ms EXECUTE sofia/external/+14792460547 at flowroute.com playback(silence_stream://2000) 2013-01-10 13:39:26.016177 [DEBUG] mod_dptools.c:1344 sofia/external/+14792460547 at flowroute.com SET [last_fax]=[+14792460547-2013-01-10-13-39-26] EXECUTE sofia/external/+14792460547 at flowroute.com set(last_fax=+14792460547-2013-01-10-13-39-26) 2013-01-10 13:39:26.016177 [DEBUG] mod_dptools.c:1344 sofia/external/+14792460547 at flowroute.com SET [fax_enable_t38_request]=[true] EXECUTE sofia/external/+14792460547 at flowroute.com set(fax_enable_t38_request=true) 2013-01-10 13:39:26.006127 [DEBUG] mod_dptools.c:1344 sofia/external/+14792460547 at flowroute.com SET [fax_enable_t38]=[true] 2013-01-10 13:39:26.006127 [DEBUG] sofia.c:5603 Channel sofia/external/+14792460547 at flowroute.com entering state [completed][200] EXECUTE sofia/external/+14792460547 at flowroute.com set(fax_enable_t38=true) 2013-01-10 13:39:26.006127 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:26.006127 [NOTICE] mod_dptools.c:1176 Channel [sofia/external/+14792460547 at flowroute.com] has been answered 2013-01-10 13:39:26.006127 [DEBUG] switch_channel.c:3395 (sofia/external/+14792460547 at flowroute.com) Callstate Change RINGING -> ACTIVE 2013-01-10 13:39:26.006127 [DEBUG] switch_core_session.c:830 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] a=sendrecv a=ptime:20 a=silenceSupp:off - - - - a=fmtp:101 0-16 a=rtpmap:101 telephone-event/8000 a=rtpmap:0 PCMU/8000 m=audio 27588 RTP/AVP 0 101 t=0 0 c=IN IP4 72.201.112.131 s=FreeSWITCH o=FreeSWITCH 1357822778 1357822779 IN IP4 72.201.112.131 v=0 2013-01-10 13:39:26.006127 [DEBUG] mod_sofia.c:856 Local SDP sofia/external/+14792460547 at flowroute.com: 2013-01-10 13:39:26.006127 [DEBUG] sofia_glue.c:3645 sofia/external/+14792460547 at flowroute.com Set rtp dtmf delay to 40 2013-01-10 13:39:26.006127 [DEBUG] sofia_glue.c:3618 Set 2833 dtmf receive payload to 101 2013-01-10 13:39:26.006127 [DEBUG] sofia_glue.c:3612 Set 2833 dtmf send payload to 101 2013-01-10 13:39:26.006127 [DEBUG] switch_rtp.c:1928 Starting timer [soft] 160 bytes per 20ms 2013-01-10 13:39:26.006127 [DEBUG] sofia_glue.c:3348 AUDIO RTP [sofia/external/+14792460547 at flowroute.com] 192.168.4.15 port 27588 -> 66.42.121.5 port 31632 codec: 0 ms: 20 2013-01-10 13:39:26.006127 [DEBUG] sofia_glue.c:1046 STUN Success [72.201.112.131]:[27588] EXECUTE sofia/external/+14792460547 at flowroute.com answer() 2013-01-10 13:39:25.816280 [DEBUG] mod_dptools.c:1344 sofia/external/+14792460547 at flowroute.com SET [api_hangup_hook]=[system /usr/bin/php /var/www/html/fusionpbx/secure/fax_to_email.php email=robert.l.craig at craignetwork.com extension=3150 name=${last_fax} messages='result: ${fax_result_text} sender:${fax_remote_station_id} pages:${fax_document_total_pages}' domain=192.168.4.15 caller_id_name='${caller_id_name}' caller_id_number=${caller_id_number}] EXECUTE sofia/external/+14792460547 at flowroute.com set(api_hangup_hook=system /usr/bin/php /var/www/html/fusionpbx/secure/fax_to_email.php email=robert.l.craig at craignetwork.com extension=3150 name=\${last_fax} messages='result: \${fax_result_text} sender:\${fax_remote_station_id} pages:\${fax_document_total_pages}' domain=192.168.4.15 caller_id_name='\${caller_id_name}' caller_id_number=\${caller_id_number}) 2013-01-10 13:39:25.816280 [DEBUG] mod_dptools.c:1344 sofia/external/+14792460547 at flowroute.com SET [RFC2822_DATE]=[Thu, 10 Jan 2013 13:39:25 -0700] EXECUTE sofia/external/+14792460547 at flowroute.com set(RFC2822_DATE=Thu, 10 Jan 2013 13:39:25 -0700) EXECUTE sofia/external/+14792460547 at flowroute.com hash(insert/192.168.4.15-last_dial/global/d276f012-5b65-11e2-beae-8564ff827a25) EXECUTE sofia/external/+14792460547 at flowroute.com hash(insert/192.168.4.15-last_dial/+14792460547/3150) EXECUTE sofia/external/+14792460547 at flowroute.com hash(insert/192.168.4.15-spymap/+14792460547/d276f012-5b65-11e2-beae-8564ff827a25) 2013-01-10 13:39:25.816280 [DEBUG] mod_dptools.c:1344 sofia/external/+14792460547 at flowroute.com SET [call_direction]=[local] EXECUTE sofia/external/+14792460547 at flowroute.com set(call_direction=local) 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:209 sofia/external/+14792460547 at flowroute.com Standard EXECUTE 2013-01-10 13:39:25.816280 [DEBUG] mod_sofia.c:242 sofia/external/+14792460547 at flowroute.com SOFIA EXECUTE 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:477 (sofia/external/+14792460547 at flowroute.com) State EXECUTE 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:415 (sofia/external/+14792460547 at flowroute.com) Running State Change CS_EXECUTE 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:470 (sofia/external/+14792460547 at flowroute.com) State ROUTING going to sleep 2013-01-10 13:39:25.816280 [DEBUG] switch_core_session.c:1283 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:167 (sofia/external/+14792460547 at flowroute.com) State Change CS_ROUTING -> CS_EXECUTE Dialplan: sofia/external/+14792460547 at flowroute.com Action hangup() Dialplan: sofia/external/+14792460547 at flowroute.com Action rxfax(/usr/local/freeswitch/storage/fax/3150/inbox/${last_fax}.tif) Dialplan: sofia/external/+14792460547 at flowroute.com Action playback(silence_stream://2000) Dialplan: sofia/external/+14792460547 at flowroute.com Action set(last_fax=${caller_id_number}-${strftime(%Y-%m-%d-%H-%M-%S)}) Dialplan: sofia/external/+14792460547 at flowroute.com Action set(fax_enable_t38_request=true) Dialplan: sofia/external/+14792460547 at flowroute.com Action set(fax_enable_t38=true) Dialplan: sofia/external/+14792460547 at flowroute.com Action answer() Dialplan: sofia/external/+14792460547 at flowroute.com Action set(api_hangup_hook=system /usr/bin/php /var/www/html/fusionpbx/secure/fax_to_email.php email=robert.l.craig at craignetwork.com extension=3150 name=\\\${last_fax} messages='result: \\\${fax_result_text} sender:\\\${fax_remote_station_id} pages:\\\${fax_document_total_pages}' domain=192.168.4.15 caller_id_name='\\\${caller_id_name}' caller_id_number=\\\${caller_id_number}) Dialplan: sofia/external/+14792460547 at flowroute.com Regex (PASS) [Test Fax] destination_number(3150) =~ /^3150$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->Test Fax] continue=false Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [vmain_user] destination_number(3150) =~ /^\*97$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->vmain_user] continue=false Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [vmain] destination_number(3150) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->vmain] continue=false Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [send_to_voicemail] destination_number(3150) =~ /^\*99(\d{2,7})$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->send_to_voicemail] continue=false Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [extension-intercom] destination_number(3150) =~ /^\*8(\d{2,7})$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->extension-intercom] continue=false Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [intercept-ext] destination_number(3150) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->intercept-ext] continue=false Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [call_return] destination_number(3150) =~ /^\*69$|^lcr$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->call_return] continue=false Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [call_privacy] destination_number(3150) =~ /^\*67(\d+)$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->call_privacy] continue=false Dialplan: sofia/external/+14792460547 at flowroute.com Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/external/+14792460547 at flowroute.com Action hash(insert/${domain_name}-last_dial/global/${uuid}) Dialplan: sofia/external/+14792460547 at flowroute.com Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: sofia/external/+14792460547 at flowroute.com Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) Dialplan: sofia/external/+14792460547 at flowroute.com Absolute Condition [global] Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->global] continue=true Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [redial] destination_number(3150) =~ /^(redial|\*870)$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->redial] continue=false Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [group-intercept] destination_number(3150) =~ /^\*8$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->group-intercept] continue=false Dialplan: sofia/external/+14792460547 at flowroute.com ANTI-Action set(call_direction=local) Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->call-direction] continue=true Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [Flowroute.11d] destination_number(3150) =~ /^\+?(\d{11})$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [default->Flowroute.11d] continue=false 2013-01-10 13:39:25.816280 [INFO] mod_dialplan_xml.c:498 Processing +14792460547 <+14792460547>->3150 in context default 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:117 sofia/external/+14792460547 at flowroute.com Standard ROUTING 2013-01-10 13:39:25.816280 [DEBUG] mod_sofia.c:149 sofia/external/+14792460547 at flowroute.com SOFIA ROUTING 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:470 (sofia/external/+14792460547 at flowroute.com) State ROUTING 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:415 (sofia/external/+14792460547 at flowroute.com) Running State Change CS_ROUTING 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:477 (sofia/external/+14792460547 at flowroute.com) State EXECUTE going to sleep 2013-01-10 13:39:25.816280 [NOTICE] switch_ivr.c:1781 Transfer sofia/external/+14792460547 at flowroute.com to XML[3150 at default] 2013-01-10 13:39:25.816280 [DEBUG] switch_core_session.c:830 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:25.816280 [DEBUG] switch_core_session.c:1283 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:25.816280 [DEBUG] switch_ivr.c:1774 (sofia/external/+14792460547 at flowroute.com) State Change CS_EXECUTE -> CS_ROUTING EXECUTE sofia/external/+14792460547 at flowroute.com transfer(3150 XML default) 2013-01-10 13:39:25.816280 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 10 Jan 2013 13:39:25 -0700] EXECUTE sofia/external/+14792460547 at flowroute.com export(RFC2822_DATE=Thu, 10 Jan 2013 13:39:25 -0700) 2013-01-10 13:39:25.816280 [DEBUG] mod_dptools.c:1344 sofia/external/+14792460547 at flowroute.com SET [outside_call]=[true] EXECUTE sofia/external/+14792460547 at flowroute.com set(outside_call=true) 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:209 sofia/external/+14792460547 at flowroute.com Standard EXECUTE 2013-01-10 13:39:25.816280 [DEBUG] mod_sofia.c:242 sofia/external/+14792460547 at flowroute.com SOFIA EXECUTE 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:477 (sofia/external/+14792460547 at flowroute.com) State EXECUTE 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:415 (sofia/external/+14792460547 at flowroute.com) Running State Change CS_EXECUTE 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:470 (sofia/external/+14792460547 at flowroute.com) State ROUTING going to sleep 2013-01-10 13:39:25.816280 [DEBUG] switch_core_session.c:1283 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:167 (sofia/external/+14792460547 at flowroute.com) State Change CS_ROUTING -> CS_EXECUTE Dialplan: sofia/external/+14792460547 at flowroute.com Action transfer(3150 XML default) Dialplan: sofia/external/+14792460547 at flowroute.com Regex (PASS) [Test_Number] destination_number(15203039810) =~ /^15203039810$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com Regex (PASS) [Test_Number] context(public) =~ /public/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [public->Test_Number] continue=false Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/external/+14792460547 at flowroute.com parsing [public->call_debug] continue=true Dialplan: sofia/external/+14792460547 at flowroute.com Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/external/+14792460547 at flowroute.com Action set(outside_call=true) Dialplan: sofia/external/+14792460547 at flowroute.com Absolute Condition [outside_call] Dialplan: sofia/external/+14792460547 at flowroute.com parsing [public->outside_call] continue=true Dialplan: sofia/external/+14792460547 at flowroute.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/external/+14792460547 at flowroute.com parsing [public->unloop] continue=false 2013-01-10 13:39:25.816280 [INFO] mod_dialplan_xml.c:498 Processing +14792460547 <+14792460547>->15203039810 in context public 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:117 sofia/external/+14792460547 at flowroute.com Standard ROUTING 2013-01-10 13:39:25.816280 [DEBUG] mod_sofia.c:149 sofia/external/+14792460547 at flowroute.com SOFIA ROUTING 2013-01-10 13:39:25.816280 [DEBUG] switch_core_state_machine.c:470 (sofia/external/+14792460547 at flowroute.com) State ROUTING 2013-01-10 13:39:25.806120 [DEBUG] switch_channel.c:2003 (sofia/external/+14792460547 at flowroute.com) Callstate Change DOWN -> RINGING 2013-01-10 13:39:25.806120 [DEBUG] switch_core_state_machine.c:415 (sofia/external/+14792460547 at flowroute.com) Running State Change CS_ROUTING 2013-01-10 13:39:25.806120 [DEBUG] switch_core_state_machine.c:454 (sofia/external/+14792460547 at flowroute.com) State INIT going to sleep 2013-01-10 13:39:25.806120 [DEBUG] switch_core_session.c:1283 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:25.806120 [DEBUG] mod_sofia.c:126 (sofia/external/+14792460547 at flowroute.com) State Change CS_INIT -> CS_ROUTING 2013-01-10 13:39:25.806120 [DEBUG] mod_sofia.c:86 sofia/external/+14792460547 at flowroute.com SOFIA INIT 2013-01-10 13:39:25.806120 [DEBUG] switch_core_state_machine.c:454 (sofia/external/+14792460547 at flowroute.com) State INIT 2013-01-10 13:39:25.806120 [DEBUG] switch_core_state_machine.c:415 (sofia/external/+14792460547 at flowroute.com) Running State Change CS_INIT 2013-01-10 13:39:25.806120 [DEBUG] switch_core_session.c:1283 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:25.806120 [DEBUG] sofia.c:5831 (sofia/external/+14792460547 at flowroute.com) State Change CS_NEW -> CS_INIT 2013-01-10 13:39:25.806120 [DEBUG] sofia_glue.c:5263 Set 2833 dtmf send/recv payload to 101 2013-01-10 13:39:25.806120 [DEBUG] switch_core_codec.c:111 sofia/external/+14792460547 at flowroute.com Original read codec set to PCMU:0 2013-01-10 13:39:25.806120 [DEBUG] sofia_glue.c:3093 Set Codec sofia/external/+14792460547 at flowroute.com PCMU/8000 20 ms 160 samples 64000 bits 2013-01-10 13:39:25.806120 [DEBUG] sofia_glue.c:5134 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 2013-01-10 13:39:25.806120 [DEBUG] sofia_glue.c:5134 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000] a=ptime:20 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=fmtp:18 annexb=no a=rtpmap:18 G729/8000 m=audio 31632 RTP/AVP 0 18 101 t=0 0 c=IN IP4 66.42.121.5 s=- o=- 275426305 1357849875 IN IP4 66.42.121.5 v=0 2013-01-10 13:39:25.806120 [DEBUG] sofia.c:5614 Remote SDP: 2013-01-10 13:39:25.806120 [DEBUG] sofia.c:5603 Channel sofia/external/+14792460547 at flowroute.com entering state [received][100] 2013-01-10 13:39:25.786370 [DEBUG] switch_core_state_machine.c:433 (sofia/external/+14792460547 at flowroute.com) State NEW 2013-01-10 13:39:25.786370 [DEBUG] switch_core_state_machine.c:415 (sofia/external/+14792460547 at flowroute.com) Running State Change CS_NEW 2013-01-10 13:39:25.786370 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:25.786370 [DEBUG] switch_core_session.c:975 Send signal sofia/external/+14792460547 at flowroute.com [BREAK] 2013-01-10 13:39:25.786370 [NOTICE] switch_channel.c:968 New Channel sofia/external/+14792460547 at flowroute.com [d276f012-5b65-11e2-beae-8564ff827a25]
Robert
Date: Thu, 10 Jan 2013 09:47:18 -0800
From: msc at freeswitch.org
To: freeswitch-users at lists.freeswitch.org
Subject: Re: [Freeswitch-users] T38 Configuration
Can you pastebin a console debug log w/ sip trace of a call failing to negotiate t.38 correctly? That might help us figure out where to look.
-MC
On Wed, Jan 9, 2013 at 7:42 PM, Robert Craig <craig.robert at hotmail.com> wrote:
Just installed FusionPBX using the script built for a Centos 6 install. Everything works great in regards to calls, but my main goal of this project was to utilize the fax capabilities of Freeswitch/FusionPBX. I have tried many things in media bypass, routing to a fax extension, using an ATA, etc., but I can't get T38 to negotiate correctly. Can someone point me in the right direction as to where I should begin? I am guessing it isn't supposed to work right out of the box. I am using Flowroute as my provider right now and the have confirmed that they utilize T38 in both directions. Thanks!
Robert
_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting at freeswitch.org
http://www.freeswitchsolutions.com
Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com
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
--
Michael S Collins
Twitter: @mercutioviz
http://www.FreeSWITCH.org
http://www.ClueCon.com
http://www.OSTAG.org
_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting at freeswitch.org
http://www.freeswitchsolutions.com
Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com
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/20130110/2a4a00c8/attachment-0001.html
Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users
mailing list