Cannot send fax

Post your configuration issues while installing ictfax

Cannot send fax

Postby dinaras » Wed Oct 10, 2012 9:50 am

Hello,
I have installed and configured ictfax according to the instructions in the webpage. This is my first FS installation and i am struggling a bit.

My setup

ictfax FS --> asterisk 1.8.12 (T.38 passthourgh enabled) --> t.38 provider --> my fax machine

Below you can find the cli when trying to send a fax using ictfax interface. Fax processing not successful - result (49) The call dropped prematurely.


One comment is that my provider is looking for 9600 bitrate, but i see on FS cli a=T38MaxBitRate:14400, maybe this is a problem

[This app Best viewed at 160x60 or more..]
+OK log level [7]
freeswitch@internal> sofia profile external siptrace on
Enabled sip debugging on external
2012-10-10 15:34:45.931032 [DEBUG] switch_ivr_originate.c:2013 Parsing global variables
2012-10-10 15:34:45.931032 [DEBUG] switch_event.c:1569 Parsing variable [plivo_request_uuid]=[dfa433f8-12d6-11e2-b7ed-0016e66a88ba]
2012-10-10 15:34:45.931032 [DEBUG] switch_event.c:1569 Parsing variable [plivo_answer_url]=[http://127.0.0.1/ictfax/index.php?q=ictfax/send_fax/8]
2012-10-10 15:34:45.931032 [DEBUG] switch_event.c:1569 Parsing variable [plivo_ring_url]=[http://127.0.0.1/ictfax/index.php/ringing/]
2012-10-10 15:34:45.931032 [DEBUG] switch_event.c:1569 Parsing variable [plivo_hangup_url]=[http://127.0.0.1/ictfax/index.php?q=ictfax/send_fax_hangup/8]
2012-10-10 15:34:45.931032 [DEBUG] switch_event.c:1569 Parsing variable [origination_caller_id_number]=[302XXXXXXXXX]
2012-10-10 15:34:45.931032 [DEBUG] switch_event.c:1569 Parsing variable [plivo_from]=[302XXXXXXXXX]
2012-10-10 15:34:45.931032 [DEBUG] switch_event.c:1569 Parsing variable [plivo_to]=[302XXXXXXXXX]
2012-10-10 15:34:45.931032 [DEBUG] switch_event.c:1569 Parsing variable [plivo_app]=[true]
2012-10-10 15:34:45.931032 [DEBUG] switch_event.c:1569 Parsing variable [absolute_codec_string]=[PCMA]
2012-10-10 15:34:45.931032 [DEBUG] switch_event.c:1569 Parsing variable [originate_timeout]=[60]
2012-10-10 15:34:45.931032 [DEBUG] switch_event.c:1569 Parsing variable [ignore_early_media]=[true]
2012-10-10 15:34:45.931032 [NOTICE] switch_channel.c:951 New Channel sofia/external/302XXXXXXXXX [dfaaadfa-12d6-11e2-b9ad-6b16b24e2b27]
2012-10-10 15:34:45.931032 [DEBUG] mod_sofia.c:4880 (sofia/external/302XXXXXXXXX) State Change CS_NEW -> CS_INIT
2012-10-10 15:34:45.931032 [DEBUG] switch_core_session.c:1210 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:45.931032 [DEBUG] switch_core_state_machine.c:398 (sofia/external/302XXXXXXXXX) Running State Change CS_INIT
2012-10-10 15:34:45.931032 [DEBUG] switch_core_state_machine.c:437 (sofia/external/302XXXXXXXXX) State INIT
2012-10-10 15:34:45.931032 [DEBUG] mod_sofia.c:86 sofia/external/302XXXXXXXXX SOFIA INIT
2012-10-10 15:34:45.931032 [DEBUG] sofia_glue.c:2637 Local SDP:
v=0
o=FreeSWITCH 1349850935 1349850936 IN IP4 62.XXX.XXX.XXX.XXX
s=FreeSWITCH
c=IN IP4 62.XXX.XXX.XXX.XXX
t=0 0
m=audio 21550 RTP/AVP 8 101 13
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2012-10-10 15:34:45.931032 [DEBUG] mod_sofia.c:126 (sofia/external/302XXXXXXXXX) State Change CS_INIT -> CS_ROUTING
2012-10-10 15:34:45.931032 [DEBUG] switch_core_session.c:1210 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:45.931032 [DEBUG] switch_core_state_machine.c:437 (sofia/external/302XXXXXXXXX) State INIT going to sleep
2012-10-10 15:34:45.931032 [DEBUG] switch_core_state_machine.c:398 (sofia/external/302XXXXXXXXX) Running State Change CS_ROUTING
2012-10-10 15:34:45.931032 [DEBUG] switch_channel.c:1964 (sofia/external/302XXXXXXXXX) Callstate Change DOWN -> RINGING
2012-10-10 15:34:45.931032 [DEBUG] switch_core_state_machine.c:446 (sofia/external/302XXXXXXXXX) State ROUTING
2012-10-10 15:34:45.931032 [DEBUG] mod_sofia.c:149 sofia/external/302XXXXXXXXX SOFIA ROUTING
2012-10-10 15:34:45.931032 [DEBUG] switch_ivr_originate.c:67 (sofia/external/302XXXXXXXXX) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2012-10-10 15:34:45.931032 [DEBUG] switch_core_session.c:1210 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:45.931032 [DEBUG] switch_core_state_machine.c:446 (sofia/external/302XXXXXXXXX) State ROUTING going to sleep
2012-10-10 15:34:45.931032 [DEBUG] switch_core_state_machine.c:398 (sofia/external/302XXXXXXXXX) Running State Change CS_CONSUME_MEDIA
2012-10-10 15:34:45.931032 [DEBUG] switch_core_state_machine.c:465 (sofia/external/302XXXXXXXXX) State CONSUME_MEDIA
2012-10-10 15:34:45.931032 [DEBUG] switch_core_state_machine.c:465 (sofia/external/302XXXXXXXXX) State CONSUME_MEDIA going to sleep
send 1031 bytes to udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:45.936150:
------------------------------------------------------------------------
INVITE sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX SIP/2.0
Via: SIP/2.0/UDP 62.XXX.XXX.XXX.XXX;rport;branch=z9hG4bKNZ5Xj8BcN7yUg
Max-Forwards: 70
From: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
To: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 34600178 INVITE
Contact: <sip:gw+mygateway@62.XXX.XXX.XXX.XXX:5060;transport=udp;gw=mygateway>
User-Agent: FreeSWITCH-mod_sofia/1.3.0+git~20121001T135651Z~bf060c6396
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 203
X-FS-Support: update_display,send_info
Remote-Party-ID: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;party=calling;screen=yes;privacy=off

v=0
o=FreeSWITCH 1349850935 1349850936 IN IP4 62.XXX.XXX.XXX.XXX
s=FreeSWITCH
c=IN IP4 62.XXX.XXX.XXX.XXX
t=0 0
m=audio 21550 RTP/AVP 8 101 13
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2012-10-10 15:34:45.931032 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:45.931032 [DEBUG] sofia.c:6285 Channel sofia/external/302XXXXXXXXX entering state [calling][0]
recv 517 bytes from udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:46.048827:
------------------------------------------------------------------------
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 62.XXX.XXX.XXX.XXX;received=62.XXX.XXX.XXX.XXX;rport=5060;branch=z9hG4bKNZ5Xj8BcN7yUg
From: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
To: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;tag=4f75927d95c07405cdd8e363365002bf.d88c
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 34600178 INVITE
Proxy-Authenticate: Digest realm="213.XXX.XXX.XXX.XXX", nonce="50755d150000a546f4f20d63191e1d7139959c6fe267eda6"
Server: OpenSIPS (1.8.0-notls (x86_64/linux))
Content-Length: 0

------------------------------------------------------------------------
send 348 bytes to udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:46.049107:
------------------------------------------------------------------------
ACK sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX SIP/2.0
Via: SIP/2.0/UDP 62.XXX.XXX.XXX.XXX;rport;branch=z9hG4bKNZ5Xj8BcN7yUg
Max-Forwards: 70
From: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
To: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;tag=4f75927d95c07405cdd8e363365002bf.d88c
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 34600178 ACK
Content-Length: 0

------------------------------------------------------------------------
2012-10-10 15:34:46.031040 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
send 1255 bytes to udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:46.049636:
------------------------------------------------------------------------
INVITE sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX SIP/2.0
Via: SIP/2.0/UDP 62.XXX.XXX.XXX.XXX;rport;branch=z9hG4bKp8ypm3vFjgNec
Max-Forwards: 70
From: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
To: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 34600179 INVITE
Contact: <sip:gw+mygateway@62.XXX.XXX.XXX.XXX:5060;transport=udp;gw=mygateway>
User-Agent: FreeSWITCH-mod_sofia/1.3.0+git~20121001T135651Z~bf060c6396
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Allow-Events: talk, hold, conference, refer
Proxy-Authorization: Digest username="1025", realm="213.XXX.XXX.XXX.XXX", nonce="50755d150000a546f4f20d63191e1d7139959c6fe267eda6", algorithm=MD5, uri="sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX", response="4a3263f01d535962d25ef7ce206be959"
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 203
X-FS-Support: update_display,send_info
Remote-Party-ID: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;party=calling;screen=yes;privacy=off

v=0
o=FreeSWITCH 1349850935 1349850936 IN IP4 62.XXX.XXX.XXX.XXX
s=FreeSWITCH
c=IN IP4 62.XXX.XXX.XXX.XXX
t=0 0
m=audio 21550 RTP/AVP 8 101 13
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2012-10-10 15:34:46.031040 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:46.031040 [DEBUG] sofia.c:6285 Channel sofia/external/302XXXXXXXXX entering state [calling][0]
recv 349 bytes from udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:46.162941:
------------------------------------------------------------------------
SIP/2.0 100 Giving a try
Via: SIP/2.0/UDP 62.XXX.XXX.XXX.XXX;received=62.XXX.XXX.XXX.XXX;rport=5060;branch=z9hG4bKp8ypm3vFjgNec
From: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
To: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 34600179 INVITE
Server: OpenSIPS (1.8.0-notls (x86_64/linux))
Content-Length: 0

------------------------------------------------------------------------
recv 852 bytes from udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:47.168095:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 62.XXX.XXX.XXX.XXX;received=62.XXX.XXX.XXX.XXX;rport=5060;branch=z9hG4bKp8ypm3vFjgNec
Record-Route: <sip:213.XXX.XXX.XXX.XXX;lr;ftag=jtSQaKtrgtFFQ;did=6c4.8c20a405>
From: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
To: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;tag=as5e6cf76e
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 34600179 INVITE
Server: MOR Softswitch
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Contact: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX:5060>
Content-Type: application/sdp
Content-Length: 241

v=0
o=root 1484846420 1484846420 IN IP4 213.XXX.XXX.XXX.XXX
s=Asterisk PBX 1.8.12.0
c=IN IP4 213.XXX.XXX.XXX.XXX
t=0 0
m=audio 13344 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
------------------------------------------------------------------------
2012-10-10 15:34:47.151038 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:47.151038 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:47.151038 [DEBUG] sofia.c:6285 Channel sofia/external/302XXXXXXXXX entering state [proceeding][183]
2012-10-10 15:34:47.151038 [DEBUG] sofia.c:6296 Remote SDP:
v=0
o=root 1484846420 1484846420 IN IP4 213.XXX.XXX.XXX.XXX
s=Asterisk PBX 1.8.12.0
c=IN IP4 213.XXX.XXX.XXX.XXX
t=0 0
m=audio 13344 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

2012-10-10 15:34:47.151038 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
2012-10-10 15:34:47.151038 [DEBUG] sofia_glue.c:3077 Set Codec sofia/external/302XXXXXXXXX PCMA/8000 20 ms 160 samples 64000 bits
2012-10-10 15:34:47.151038 [DEBUG] switch_core_codec.c:111 sofia/external/302XXXXXXXXX Original read codec set to PCMA:8
2012-10-10 15:34:47.151038 [DEBUG] sofia_glue.c:5212 Set 2833 dtmf send payload to 101
2012-10-10 15:34:47.151038 [DEBUG] sofia_glue.c:3327 AUDIO RTP [sofia/external/302XXXXXXXXX] 62.XXX.XXX.XXX.XXX port 21550 -> 213.XXX.XXX.XXX.XXX port 13344 codec: 8 ms: 20
2012-10-10 15:34:47.151038 [DEBUG] switch_rtp.c:1927 Starting timer [soft] 160 bytes per 20ms
2012-10-10 15:34:47.171143 [DEBUG] sofia_glue.c:3591 Set 2833 dtmf send payload to 101
2012-10-10 15:34:47.171143 [DEBUG] sofia_glue.c:3597 Set 2833 dtmf receive payload to 101
2012-10-10 15:34:47.171143 [DEBUG] sofia_glue.c:3624 sofia/external/302XXXXXXXXX Set rtp dtmf delay to 40
2012-10-10 15:34:47.171143 [NOTICE] sofia_glue.c:4226 Pre-Answer sofia/external/302XXXXXXXXX!
2012-10-10 15:34:47.171143 [DEBUG] switch_channel.c:3092 (sofia/external/302XXXXXXXXX) Callstate Change RINGING -> EARLY
recv 838 bytes from udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:47.958521:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 62.XXX.XXX.XXX.XXX;received=62.XXX.XXX.XXX.XXX;rport=5060;branch=z9hG4bKp8ypm3vFjgNec
Record-Route: <sip:213.XXX.XXX.XXX.XXX;lr;ftag=jtSQaKtrgtFFQ;did=6c4.8c20a405>
From: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
To: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;tag=as5e6cf76e
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 34600179 INVITE
Server: MOR Softswitch
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Contact: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX:5060>
Content-Type: application/sdp
Content-Length: 241

v=0
o=root 1484846420 1484846421 IN IP4 213.XXX.XXX.XXX.XXX
s=Asterisk PBX 1.8.12.0
c=IN IP4 213.XXX.XXX.XXX.XXX
t=0 0
m=audio 13344 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
------------------------------------------------------------------------
2012-10-10 15:34:47.951032 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:47.951032 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:47.951032 [DEBUG] sofia.c:6285 Channel sofia/external/302XXXXXXXXX entering state [completing][200]
2012-10-10 15:34:47.951032 [DEBUG] sofia.c:6296 Remote SDP:
v=0
o=root 1484846420 1484846421 IN IP4 213.XXX.XXX.XXX.XXX
s=Asterisk PBX 1.8.12.0
c=IN IP4 213.XXX.XXX.XXX.XXX
t=0 0
m=audio 13344 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20

send 687 bytes to udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:47.960321:
------------------------------------------------------------------------
ACK sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX:5060 SIP/2.0
Via: SIP/2.0/UDP 62.XXX.XXX.XXX.XXX;rport;branch=z9hG4bKQHrFpyDKFSB1Q
Route: <sip:213.XXX.XXX.XXX.XXX;lr;ftag=jtSQaKtrgtFFQ;did=6c4.8c20a405>
Max-Forwards: 70
From: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
To: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;tag=as5e6cf76e
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 34600179 ACK
Contact: <sip:gw+mygateway@62.XXX.XXX.XXX.XXX:5060;transport=udp;gw=mygateway>
Proxy-Authorization: Digest username="1025", realm="213.XXX.XXX.XXX.XXX", nonce="50755d150000a546f4f20d63191e1d7139959c6fe267eda6", algorithm=MD5, uri="sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX", response="4a3263f01d535962d25ef7ce206be959"
Content-Length: 0

------------------------------------------------------------------------
2012-10-10 15:34:47.951032 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:47.951032 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:47.951032 [DEBUG] sofia.c:6285 Channel sofia/external/302XXXXXXXXX entering state [ready][200]
2012-10-10 15:34:47.951032 [DEBUG] switch_channel.c:3351 (sofia/external/302XXXXXXXXX) Callstate Change EARLY -> ACTIVE
2012-10-10 15:34:47.951032 [NOTICE] sofia.c:6969 Channel [sofia/external/302XXXXXXXXX] has been answered
2012-10-10 15:34:47.951032 [DEBUG] switch_ivr_originate.c:3418 Originate Resulted in Success: [sofia/external/302XXXXXXXXX]
2012-10-10 15:34:47.951032 [INFO] switch_channel.c:2806 sofia/external/302XXXXXXXXX Flipping CID from "" <302XXXXXXXXX> to "Outbound Call" <302XXXXXXXXX>
2012-10-10 15:34:47.951032 [DEBUG] mod_commands.c:3784 (sofia/external/302XXXXXXXXX) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
2012-10-10 15:34:47.951032 [DEBUG] switch_core_session.c:1210 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:47.951032 [DEBUG] switch_core_state_machine.c:398 (sofia/external/302XXXXXXXXX) Running State Change CS_EXECUTE
2012-10-10 15:34:47.951032 [DEBUG] switch_core_state_machine.c:453 (sofia/external/302XXXXXXXXX) State EXECUTE
2012-10-10 15:34:47.951032 [DEBUG] mod_sofia.c:242 sofia/external/302XXXXXXXXX SOFIA EXECUTE
2012-10-10 15:34:47.951032 [DEBUG] switch_core_state_machine.c:209 sofia/external/302XXXXXXXXX Standard EXECUTE
EXECUTE sofia/external/302XXXXXXXXX socket(127.0.0.1:8084 async full)
2012-10-10 15:34:47.971039 [DEBUG] switch_core_session.c:1042 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:47.971039 [DEBUG] switch_core_session.c:1042 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:47.991045 [DEBUG] switch_ivr.c:605 sofia/external/302XXXXXXXXX Command Execute set(plivo_app=true)
EXECUTE sofia/external/302XXXXXXXXX set(plivo_app=true)
2012-10-10 15:34:47.991045 [DEBUG] mod_dptools.c:1335 sofia/external/302XXXXXXXXX SET [plivo_app]=[true]
2012-10-10 15:34:47.991045 [DEBUG] switch_ivr.c:605 sofia/external/302XXXXXXXXX Command Execute set(hangup_after_bridge=false)
EXECUTE sofia/external/302XXXXXXXXX set(hangup_after_bridge=false)
2012-10-10 15:34:47.991045 [DEBUG] mod_dptools.c:1335 sofia/external/302XXXXXXXXX SET [hangup_after_bridge]=[false]
2012-10-10 15:34:48.391076 [DEBUG] switch_core_session.c:1042 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:48.391076 [DEBUG] switch_core_session.c:1042 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:48.411051 [DEBUG] switch_ivr.c:605 sofia/external/302XXXXXXXXX Command Execute set(fax_enable_t38=true)
EXECUTE sofia/external/302XXXXXXXXX set(fax_enable_t38=true)
2012-10-10 15:34:48.411051 [DEBUG] mod_dptools.c:1335 sofia/external/302XXXXXXXXX SET [fax_enable_t38]=[true]
2012-10-10 15:34:48.411051 [DEBUG] switch_ivr.c:605 sofia/external/302XXXXXXXXX Command Execute txfax(/usr/ictfax/sites/default/files/ictfax/webfax/8.tif)
EXECUTE sofia/external/302XXXXXXXXX txfax(/usr/ictfax/sites/default/files/ictfax/webfax/8.tif)
2012-10-10 15:34:48.411051 [DEBUG] mod_spandsp_fax.c:1363 Raw read codec activation Success L16 20000
2012-10-10 15:34:48.411051 [DEBUG] switch_core_codec.c:219 sofia/external/302XXXXXXXXX Push codec L16:70
2012-10-10 15:34:48.411051 [DEBUG] mod_spandsp_fax.c:1379 Raw write codec activation Success L16
recv 929 bytes from udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:50.943290:
------------------------------------------------------------------------
INVITE sip:gw+mygateway@62.XXX.XXX.XXX.XXX:5060;transport=udp;gw=mygateway SIP/2.0
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX;branch=z9hG4bK593a.60ffb904.0
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX:5060;rport=5060;received=213.XXX.XXX.XXX.XXX;branch=z9hG4bK01b16ef9
Max-Forwards: 69
From: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;tag=as5e6cf76e
To: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
Contact: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX:5060>
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 102 INVITE
User-Agent: MOR Softswitch
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 268

v=0
o=root 1484846420 1484846422 IN IP4 213.XXX.XXX.XXX.XXX
s=Asterisk PBX 1.8.12.0
c=IN IP4 213.XXX.XXX.XXX.XXX
t=0 0
m=image 4654 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxDatagram:70
a=T38FaxUdpEC:t38UDPFEC
------------------------------------------------------------------------
send 443 bytes to udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:50.943753:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX;branch=z9hG4bK593a.60ffb904.0
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX:5060;rport=5060;received=213.XXX.XXX.XXX.XXX;branch=z9hG4bK01b16ef9
From: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;tag=as5e6cf76e
To: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 102 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.3.0+git~20121001T135651Z~bf060c6396
Content-Length: 0

------------------------------------------------------------------------
2012-10-10 15:34:50.931033 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:50.931033 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:50.951036 [DEBUG] sofia.c:6285 Channel sofia/external/302XXXXXXXXX entering state [received][100]
2012-10-10 15:34:50.951036 [DEBUG] sofia.c:6296 Remote SDP:
v=0
o=root 1484846420 1484846422 IN IP4 213.XXX.XXX.XXX.XXX
s=Asterisk PBX 1.8.12.0
c=IN IP4 213.XXX.XXX.XXX.XXX
t=0 0
m=image 4654 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxDatagram:70
a=T38FaxUdpEC:t38UDPFEC

2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1005 T38 SDP Origin = root
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1006 T38FaxVersion = 0
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1007 T38MaxBitRate = 14400
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1008 T38FaxFillBitRemoval = 0
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1009 T38FaxTranscodingMMR = 0
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1010 T38FaxTranscodingJBIG = 0
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1011 T38FaxRateManagement = 'transferredTCF'
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1012 T38FaxMaxBuffer = 500
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1013 T38FaxMaxDatagram = 70
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1014 T38FaxUdpEC = 't38UDPFEC'
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1015 T38VendorInfo = ''
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1016 ip = '213.XXX.XXX.XXX.XXX'
2012-10-10 15:34:50.971046 [DEBUG] mod_spandsp_fax.c:1018 port = 4654
2012-10-10 15:34:50.971046 [DEBUG] mod_sofia.c:1465 IMAGE UDPTL CHANGING DEST TO: [213.XXX.XXX.XXX.XXX:4654]
2012-10-10 15:34:50.971046 [DEBUG] sofia_glue.c:173 sofia/external/302XXXXXXXXX image media sdp:
v=0
o=FreeSWITCH 1349850935 1349850937 IN IP4 62.XXX.XXX.XXX.XXX
s=FreeSWITCH
c=IN IP4 62.XXX.XXX.XXX.XXX
t=0 0
m=image 21550 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:2000
a=T38FaxMaxDatagram:400
a=T38FaxUdpEC:t38UDPRedundancy

2012-10-10 15:34:50.971046 [DEBUG] mod_sofia.c:1456 Remote address:port [213.XXX.XXX.XXX.XXX:4654] has not changed.
2012-10-10 15:34:50.971046 [DEBUG] mod_sofia.c:1456 Remote address:port [213.XXX.XXX.XXX.XXX:4654] has not changed.
send 1028 bytes to udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:50.971982:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX;branch=z9hG4bK593a.60ffb904.0
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX:5060;rport=5060;received=213.XXX.XXX.XXX.XXX;branch=z9hG4bK01b16ef9
From: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;tag=as5e6cf76e
To: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 102 INVITE
Contact: <sip:gw+mygateway@62.XXX.XXX.XXX.XXX:5060;transport=udp;gw=mygateway>
User-Agent: FreeSWITCH-mod_sofia/1.3.0+git~20121001T135651Z~bf060c6396
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Content-Type: application/sdp
2012-10-10 15:34:50.971046 [DEBUG] sofia_glue.c:173 sofia/external/302XXXXXXXXX image media sdp:
v=0
o=FreeSWITCH 1349850935 1349850938 IN IP4 62.XXX.XXX.XXX.XXX
s=FreeSWITCH
c=IN IP4 62.XXX.XXX.XXX.XXX
t=0 0
m=image 21550 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:2000
a=T38FaxMaxDatagram:400
a=T38FaxUdpEC:t38UDPRedundancy

Content-Disposition: session
Content-Length: 294

v=0
o=FreeSWITCH 1349850935 1349850937 IN IP4 62.XXX.XXX.XXX.XXX
s=FreeSWITCH
c=IN IP4 62.XXX.XXX.XXX.XXX
t=0 0
m=image 21550 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:2000
a=T38FaxMaxDatagram:400
a=T38FaxUdpEC:t38UDPRedundancy
------------------------------------------------------------------------
2012-10-10 15:34:50.971046 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:50.971046 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:50.971046 [DEBUG] sofia.c:6285 Channel sofia/external/302XXXXXXXXX entering state [completed][200]
recv 511 bytes from udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:34:51.083745:
------------------------------------------------------------------------
ACK sip:gw+mygateway@62.XXX.XXX.XXX.XXX:5060;transport=udp;gw=mygateway SIP/2.0
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX;branch=z9hG4bK593a.60ffb904.2
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX:5060;rport=5060;received=213.XXX.XXX.XXX.XXX;branch=z9hG4bK2b2fcc96
Max-Forwards: 69
From: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;tag=as5e6cf76e
To: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
Contact: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX:5060>
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 102 ACK
User-Agent: MOR Softswitch
Content-Length: 0

------------------------------------------------------------------------
2012-10-10 15:34:51.071035 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:51.071035 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:51.071035 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:34:51.071035 [DEBUG] sofia.c:6285 Channel sofia/external/302XXXXXXXXX entering state [ready][200]
recv 537 bytes from udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:35:30.258215:
------------------------------------------------------------------------
BYE sip:gw+mygateway@62.XXX.XXX.XXX.XXX:5060;transport=udp;gw=mygateway SIP/2.0
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX;branch=z9hG4bK693a.64074615.0
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX:5060;rport=5060;received=213.XXX.XXX.XXX.XXX;branch=z9hG4bK45e3d2fc
Max-Forwards: 69
From: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;tag=as5e6cf76e
To: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 103 BYE
User-Agent: MOR Softswitch
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0

------------------------------------------------------------------------
2012-10-10 15:35:30.251034 [DEBUG] switch_core_session.c:905 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:35:30.251034 [DEBUG] switch_channel.c:2950 (sofia/external/302XXXXXXXXX) Callstate Change ACTIVE -> HANGUP
2012-10-10 15:35:30.251034 [NOTICE] sofia.c:711 Hangup sofia/external/302XXXXXXXXX [CS_EXECUTE] [NORMAL_CLEARING]
2012-10-10 15:35:30.251034 [DEBUG] switch_channel.c:2973 Send signal sofia/external/302XXXXXXXXX [KILL]
2012-10-10 15:35:30.251034 [DEBUG] switch_core_session.c:1210 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:487 ==============================================================================
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:500 Fax processing not successful - result (49) The call dropped prematurely.
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:505 Remote station id:
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:506 Local station id: SpanDSP Fax Ident
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:507 Pages transferred: 0
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:509 Total fax pages: 0
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:510 Image resolution: 0x0
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:511 Transfer Rate: 14400
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:513 ECM status off
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:514 remote country:
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:515 remote vendor:
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:516 remote model:
2012-10-10 15:35:30.251034 [DEBUG] mod_spandsp_fax.c:518 ==============================================================================
send 574 bytes to udp/[213.XXX.XXX.XXX.XXX]:5060 at 12:35:30.260380:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX;branch=z9hG4bK693a.64074615.0
Via: SIP/2.0/UDP 213.XXX.XXX.XXX.XXX:5060;rport=5060;received=213.XXX.XXX.XXX.XXX;branch=z9hG4bK45e3d2fc
From: <sip:302XXXXXXXXX@213.XXX.XXX.XXX.XXX>;tag=as5e6cf76e
To: "" <sip:1025@213.XXX.XXX.XXX.XXX>;tag=jtSQaKtrgtFFQ
Call-ID: b7092db8-8d79-1230-03a0-0016e66a88ba
CSeq: 103 BYE
User-Agent: FreeSWITCH-mod_sofia/1.3.0+git~20121001T135651Z~bf060c6396
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Content-Length: 0

------------------------------------------------------------------------
2012-10-10 15:35:30.251034 [DEBUG] switch_core_codec.c:244 sofia/external/302XXXXXXXXX Restore previous codec PCMA:8.
2012-10-10 15:35:30.251034 [DEBUG] switch_core_session.c:2558 sofia/external/302XXXXXXXXX skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-10-10 15:35:30.251034 [DEBUG] switch_core_session.c:2558 sofia/external/302XXXXXXXXX skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-10-10 15:35:30.251034 [DEBUG] switch_core_state_machine.c:453 (sofia/external/302XXXXXXXXX) State EXECUTE going to sleep
2012-10-10 15:35:30.251034 [DEBUG] switch_core_state_machine.c:398 (sofia/external/302XXXXXXXXX) Running State Change CS_HANGUP
2012-10-10 15:35:30.251034 [DEBUG] switch_core_state_machine.c:638 (sofia/external/302XXXXXXXXX) State HANGUP
2012-10-10 15:35:30.251034 [DEBUG] mod_sofia.c:483 Channel sofia/external/302XXXXXXXXX hanging up, cause: NORMAL_CLEARING
2012-10-10 15:35:30.251034 [DEBUG] switch_core_state_machine.c:48 sofia/external/302XXXXXXXXX Standard HANGUP, cause: NORMAL_CLEARING
2012-10-10 15:35:30.251034 [DEBUG] switch_core_state_machine.c:638 (sofia/external/302XXXXXXXXX) State HANGUP going to sleep
2012-10-10 15:35:30.251034 [DEBUG] switch_core_state_machine.c:429 (sofia/external/302XXXXXXXXX) State Change CS_HANGUP -> CS_REPORTING
2012-10-10 15:35:30.251034 [DEBUG] switch_core_session.c:1210 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:35:30.251034 [DEBUG] switch_core_state_machine.c:398 (sofia/external/302XXXXXXXXX) Running State Change CS_REPORTING
2012-10-10 15:35:30.251034 [DEBUG] switch_core_state_machine.c:720 (sofia/external/302XXXXXXXXX) State REPORTING
2012-10-10 15:35:30.251034 [DEBUG] mod_event_socket.c:1363 sofia/external/302XXXXXXXXX Socket Linger 0
2012-10-10 15:35:30.251034 [DEBUG] switch_core_session.c:1042 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:35:30.291037 [DEBUG] switch_core_state_machine.c:92 sofia/external/302XXXXXXXXX Standard REPORTING, cause: NORMAL_CLEARING
2012-10-10 15:35:30.291037 [DEBUG] switch_core_state_machine.c:720 (sofia/external/302XXXXXXXXX) State REPORTING going to sleep
2012-10-10 15:35:30.291037 [DEBUG] switch_core_state_machine.c:423 (sofia/external/302XXXXXXXXX) State Change CS_REPORTING -> CS_DESTROY
2012-10-10 15:35:30.291037 [DEBUG] switch_core_session.c:1210 Send signal sofia/external/302XXXXXXXXX [BREAK]
2012-10-10 15:35:30.291037 [DEBUG] switch_core_session.c:1415 Session 1 (sofia/external/302XXXXXXXXX) Locked, Waiting on external entities
2012-10-10 15:35:30.291037 [NOTICE] switch_core_session.c:1433 Session 1 (sofia/external/302XXXXXXXXX) Ended
2012-10-10 15:35:30.291037 [NOTICE] switch_core_session.c:1437 Close Channel sofia/external/302XXXXXXXXX [CS_DESTROY]
2012-10-10 15:35:30.291037 [DEBUG] switch_core_state_machine.c:527 (sofia/external/302XXXXXXXXX) Callstate Change HANGUP -> DOWN
2012-10-10 15:35:30.291037 [DEBUG] switch_core_state_machine.c:530 (sofia/external/302XXXXXXXXX) Running State Change CS_DESTROY
2012-10-10 15:35:30.291037 [DEBUG] switch_core_state_machine.c:540 (sofia/external/302XXXXXXXXX) State DESTROY
2012-10-10 15:35:30.291037 [DEBUG] mod_sofia.c:376 sofia/external/302XXXXXXXXX SOFIA DESTROY
2012-10-10 15:35:30.291037 [DEBUG] switch_core_state_machine.c:99 sofia/external/302XXXXXXXXX Standard DESTROY
2012-10-10 15:35:30.291037 [DEBUG] switch_core_state_machine.c:540 (sofia/external/302XXXXXXXXX) State DESTROY going to sleep

Can anybody help with this? Where are the settings for t.38. How can i enable or disable ECM.

Thank you

Dinos
dinaras
 
Posts: 5
Joined: Tue Oct 02, 2012 10:19 am

Re: Cannot send fax

Postby nasir » Mon Sep 09, 2013 1:46 pm

Please check freeswitch website for fax settings http://wiki.freeswitch.org/wiki/Mod_spandsp (fax_transfer_rate) and then visit following thread to have an idea that how you can override default settings viewtopic.php?f=12&t=2472
nasir
 
Posts: 55
Joined: Mon Sep 09, 2013 12:11 pm


Return to Configuration

Who is online

Users browsing this forum: No registered users and 1 guest

cron