Unable to receive faxes through CUCM

Post your configuration issues while installing ictfax

Unable to receive faxes through CUCM

Postby cssimmen » Thu Jun 13, 2013 5:26 pm

I setup ICTFAX per the instructions on CentOS 6. I can call the fax extension from my phone and get a fax tone. When I try to fax using our fax machine, I get an error. "BEARERCAPABILITY_NOTIMPL]"

It looks as though the faxes are being sent over T.38.

I followed the suggestion here: viewtopic.php?f=12&t=2472

But that did not help.

I have posted my FreeSwitch call log, plivo-outbound, and /tmp/mytest.txt

Code: Select all
2013-06-13 13:14:49.944638 [INFO] switch_rtp.c:4682 Auto Changing port from 0.0.0.0:4000 to XXX.XXX.XXX.XXX:19668
recv 439 bytes from tcp/[XXX.XXX.XXX.XXX]:45915 at 17:14:49.958732:
   ------------------------------------------------------------------------
   ACK sip:XXXX@XXX.XXX.XXX.XXX:5080;transport=tcp SIP/2.0
   Via: SIP/2.0/TCP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK27aaa773e070b
   From: <sip:XXXXXXXXXX@XXX.XXX.XXX.XXX>;tag=550058~39001e80-9450-40c2-ac0c-b69f46161ebe-41352781
   To: <sip:XXXX@XXX.XXX.XXX.XXX>;tag=Zr8N3Qg4c783m
   Date: Thu, 13 Jun 2013 17:14:49 GMT
   Call-ID: bbe60900-1b91fe03-25ecd-f1d20a0a@XXX.XXX.XXX.XXX
   Max-Forwards: 70
   CSeq: 102 ACK
   Allow-Events: presence, kpml
   Content-Length: 0

   ------------------------------------------------------------------------
recv 1319 bytes from tcp/[XXX.XXX.XXX.XXX]:45915 at 17:14:50.010330:
   ------------------------------------------------------------------------
   INVITE sip:XXXX@XXX.XXX.XXX.XXX:5080;transport=tcp SIP/2.0
   Via: SIP/2.0/TCP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK27aab48512511
   From: <sip:XXXXXXXXXX@XXX.XXX.XXX.XXX>;tag=550058~39001e80-9450-40c2-ac0c-b69f46161ebe-41352781
   To: <sip:XXXX@XXX.XXX.XXX.XXX>;tag=Zr8N3Qg4c783m
   Date: Thu, 13 Jun 2013 17:14:50 GMT
   Call-ID: bbe60900-1b91fe03-25ecd-f1d20a0a@XXX.XXX.XXX.XXX
   Supported: timer,resource-priority,replaces
   Min-SE:  1800
   User-Agent: Cisco-CUCM8.5
   Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
   CSeq: 103 INVITE
   Max-Forwards: 70
   Expires: 180
   Allow-Events: presence, kpml
   Supported: X-cisco-srtp-fallback
   Supported: Geolocation
   Session-Expires:  1800;refresher=uac
   P-Asserted-Identity: <sip:XXXXXXXXXX@XXX.XXX.XXX.XXX>
   Remote-Party-ID: <sip:XXXXXXXXXX@XXX.XXX.XXX.XXX>;party=calling;screen=yes;privacy=off
   Contact: <sip:XXXXXXXXXX@XXX.XXX.XXX.XXX:5060;transport=tcp>
   Content-Type: application/sdp
   Content-Length: 356

   v=0
   o=CiscoSystemsCCM-SIP 2000 3 IN IP4 XXX.XXX.XXX.XXX
   s=SIP Call
   c=IN IP4 0.0.0.0
   t=0 0
   m=image 4004 udptl t38
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval:0
   a=T38FaxTranscodingMMR:0
   a=T38FaxTranscodingJBIG:0
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxUdpEC:t38UDPRedundancy
   a=T38FaxMaxBuffer:200
   a=T38FaxMaxDatagram:72
   ------------------------------------------------------------------------
send 408 bytes to tcp/[XXX.XXX.XXX.XXX]:45915 at 17:14:50.010505:
   ------------------------------------------------------------------------
   SIP/2.0 100 Trying
   Via: SIP/2.0/TCP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK27aab48512511;rport=45915
   From: <sip:XXXXXXXXXX@XXX.XXX.XXX.XXX>;tag=550058~39001e80-9450-40c2-ac0c-b69f46161ebe-41352781
   To: <sip:XXXX@XXX.XXX.XXX.XXX>;tag=Zr8N3Qg4c783m
   Call-ID: bbe60900-1b91fe03-25ecd-f1d20a0a@XXX.XXX.XXX.XXX
   CSeq: 103 INVITE
   User-Agent: FreeSWITCH-mod_sofia/1.5.2b+git~20130612T195439Z~56f68be4e6
   Content-Length: 0

   ------------------------------------------------------------------------
send 1133 bytes to tcp/[XXX.XXX.XXX.XXX]:45915 at 17:14:50.011228:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/TCP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK27aab48512511;rport=45915
   From: <sip:XXXXXXXXXX@XXX.XXX.XXX.XXX>;tag=550058~39001e80-9450-40c2-ac0c-b69f46161ebe-41352781
   To: <sip:XXXX@XXX.XXX.XXX.XXX>;tag=Zr8N3Qg4c783m
   Call-ID: bbe60900-1b91fe03-25ecd-f1d20a0a@XXX.XXX.XXX.XXX
   CSeq: 103 INVITE
   Contact: <sip:XXXX@XXX.XXX.XXX.XXX:5080;transport=tcp>
   User-Agent: FreeSWITCH-mod_sofia/1.5.2b+git~20130612T195439Z~56f68be4e6
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
   Require: timer
   Supported: timer, precondition, path, replaces
   Session-Expires: 1800;refresher=uac
   Min-SE: 1800
   Content-Type: application/sdp
   Content-Disposition: session
   Content-Length: 383

   v=0
   o=FreeSWITCH 1371118916 1371118918 IN IP4 XXX.XXX.XXX.XXX
   s=FreeSWITCH
   c=IN IP4 XXX.XXX.XXX.XXX
   t=0 0
   m=image 24768 udptl t38
   a=recvonly
   a=T38FaxVersion:0
   a=T38MaxBitRate:14400
   a=T38FaxFillBitRemoval:0
   a=T38FaxTranscodingMMR:0
   a=T38FaxTranscodingJBIG:0
   a=T38FaxRateManagement:transferredTCF
   a=T38FaxMaxBuffer:2000
   a=T38FaxMaxDatagram:400
   a=T38FaxUdpEC:t38UDPRedundancy
   ------------------------------------------------------------------------
recv 439 bytes from tcp/[XXX.XXX.XXX.XXX]:45915 at 17:14:50.024002:
   ------------------------------------------------------------------------
   ACK sip:XXXX@XXX.XXX.XXX.XXX:5080;transport=tcp SIP/2.0
   Via: SIP/2.0/TCP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK27aac1bc06f28
   From: <sip:XXXXXXXXXX@XXX.XXX.XXX.XXX>;tag=550058~39001e80-9450-40c2-ac0c-b69f46161ebe-41352781
   To: <sip:XXXX@XXX.XXX.XXX.XXX>;tag=Zr8N3Qg4c783m
   Date: Thu, 13 Jun 2013 17:14:50 GMT
   Call-ID: bbe60900-1b91fe03-25ecd-f1d20a0a@XXX.XXX.XXX.XXX
   Max-Forwards: 70
   CSeq: 103 ACK
   Allow-Events: presence, kpml
   Content-Length: 0

   ------------------------------------------------------------------------
recv 513 bytes from tcp/[XXX.XXX.XXX.XXX]:45915 at 17:14:50.074925:
   ------------------------------------------------------------------------
   BYE sip:XXXX@XXX.XXX.XXX.XXX:5080;transport=tcp SIP/2.0
   Via: SIP/2.0/TCP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK27aad4d2f23c6
   From: <sip:XXXXXXXXXX@XXX.XXX.XXX.XXX>;tag=550058~39001e80-9450-40c2-ac0c-b69f46161ebe-41352781
   To: <sip:XXXX@XXX.XXX.XXX.XXX>;tag=Zr8N3Qg4c783m
   Date: Thu, 13 Jun 2013 17:14:50 GMT
   Call-ID: bbe60900-1b91fe03-25ecd-f1d20a0a@XXX.XXX.XXX.XXX
   User-Agent: Cisco-CUCM8.5
   Max-Forwards: 70
   P-Asserted-Identity: <sip:XXXXXXXXXX@XXX.XXX.XXX.XXX>
   CSeq: 104 BYE
   Reason: Q.850;cause=65
   Content-Length: 0

   ------------------------------------------------------------------------
2013-06-13 13:14:50.064656 [NOTICE] sofia.c:715 Hangup sofia/external/XXXXXXXXXX@XXX.XXX.XXX.XXX [CS_EXECUTE] [BEARERCAPABILITY_NOTIMPL]
send 539 bytes to tcp/[XXX.XXX.XXX.XXX]:45915 at 17:14:50.075853:
   ------------------------------------------------------------------------
   SIP/2.0 200 OK
   Via: SIP/2.0/TCP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK27aad4d2f23c6;rport=45915
   From: <sip:XXXXXXXXXX@XXX.XXX.XXX.XXX>;tag=550058~39001e80-9450-40c2-ac0c-b69f46161ebe-41352781
   To: <sip:XXXX@XXX.XXX.XXX.XXX>;tag=Zr8N3Qg4c783m
   Call-ID: bbe60900-1b91fe03-25ecd-f1d20a0a@XXX.XXX.XXX.XXX
   CSeq: 104 BYE
   User-Agent: FreeSWITCH-mod_sofia/1.5.2b+git~20130612T195439Z~56f68be4e6
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
   Supported: timer, precondition, path, replaces
   Content-Length: 0

   ------------------------------------------------------------------------
2013-06-13 13:14:50.084642 [NOTICE] switch_core_session.c:1567 Session 3 (sofia/external/XXXXXXXXXX@XXX.XXX.XXX.XXX) Ended
2013-06-13 13:14:50.084642 [NOTICE] switch_core_session.c:1571 Close Channel sofia/external/XXXXXXXXXX@XXX.XXX.XXX.XXX [CS_DESTROY]

Code: Select all
<?xml version="1.0" encoding="UTF-8"?>
        <Response>
        <ReceiveFax>/usr/ictfax/sites/default/files/ictfax/inbox/48.tif</ReceiveFax>
        <Redirect>http://127.0.0.1/ictfax/index.php?q=ictfax/send_email/48</Redirect>
        </Response>
2013-06-13 13:16:32,525 plivo-outbound[4574]: INFO: (4) Requested RESTXML to http://127.0.0.1/ictfax/index.php?q=ictfax/receive_fax
2013-06-13 13:16:32,525 plivo-outbound[4574]: DEBUG: (4) Answering because Element ReceiveFax need it
2013-06-13 13:16:32,525 plivo-outbound[4574]: DEBUG: (4) Execute: answer args=, uuid='', lock=True, loops=1
2013-06-13 13:16:32,526 plivo-outbound[4574]: DEBUG: (4) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK'}, body=>
2013-06-13 13:16:32,526 plivo-outbound[4574]: INFO: (4) [ReceiveFax] /usr/ictfax/sites/default/files/ictfax/inbox/48.tif {'loop': 1}
2013-06-13 13:16:32,526 plivo-outbound[4574]: DEBUG: (4) Faxing 1 times
2013-06-13 13:16:32,526 plivo-outbound[4574]: DEBUG: (4) Execute: set args=fax_enable_t38=true, uuid='', lock=True, loops=1
2013-06-13 13:16:32,527 plivo-outbound[4574]: DEBUG: (4) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK'}, body=>
2013-06-13 13:16:32,527 plivo-outbound[4574]: DEBUG: (4) Execute: rxfax args=/usr/ictfax/sites/default/files/ictfax/inbox/48.tif, uuid='', lock=True, loops=1
2013-06-13 13:16:32,528 plivo-outbound[4574]: DEBUG: (4) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK'}, body=>
2013-06-13 13:16:38,368 plivo-outbound[4574]: DEBUG: (4) ReceiveFax done (_none_)
2013-06-13 13:16:38,368 plivo-outbound[4574]: INFO: (4) ReceiveFax Finished
2013-06-13 13:16:38,368 plivo-outbound[4574]: INFO: (4) [ReceiveFax] Done
2013-06-13 13:16:38,369 plivo-outbound[4574]: INFO: (4) [Redirect] http://127.0.0.1/ictfax/index.php?q=ictfax/send_email/48 {'method': 'POST'}
2013-06-13 13:16:38,369 plivo-outbound[4574]: INFO: (4) [Redirect] Done (redirect)
2013-06-13 13:16:38,369 plivo-outbound[4574]: INFO: (4) Redirecting to POST http://127.0.0.1/ictfax/index.php?q=ictfax/send_email/48 to fetch RESTXML
2013-06-13 13:16:38,370 plivo-outbound[4574]: DEBUG: (4) Execute: api args='uuid_getvar fe4178d8-d44c-11e2-b073-d9717d078b9d plivo_transfer_progress'
2013-06-13 13:16:38,372 plivo-outbound[4574]: INFO: (4) Event: channel fe4178d8-d44c-11e2-b073-d9717d078b9d has hung up (BEARERCAPABILITY_NOTIMPL)
2013-06-13 13:16:38,372 plivo-outbound[4574]: DEBUG: (4) Response: <ApiResponse headers={'Content-Length': '22', 'Content-Type': 'api/response'}, body=-ERR No such channel!
>
2013-06-13 13:16:38,372 plivo-outbound[4574]: WARNING: (4) Channel has hung up, breaking Processing Call
2013-06-13 13:16:38,373 plivo-outbound[4574]: INFO: (4) Processing Call Ended
2013-06-13 13:16:38,373 plivo-outbound[4574]: DEBUG: (4) Releasing Connection ...
2013-06-13 13:16:38,373 plivo-outbound[4574]: DEBUG: (4) Releasing Connection Done
2013-06-13 13:16:38,373 plivo-outbound[4574]: INFO: (4) End request from ('127.0.0.1', 57534)
2013-06-13 13:18:20,489 plivo-outbound[4574]: INFO: (5) New request from ('127.0.0.1', 57538)
2013-06-13 13:18:20,490 plivo-outbound[4574]: DEBUG: (5) Execute: connect args=''
2013-06-13 13:18:20,492 plivo-outbound[4574]: DEBUG: (5) Response: <CommandResponse headers={'Caller-Orig-Caller-ID-Name': 'XXXXXXXXXX', 'variable_recovery_profile_name': 'external', 'variable_sip_Remote-Party-ID': '<sip:XXXXXXXXXX@10.10.210.241>;party=calling;screen=yes;privacy=off', 'variable_rtp_use_codec_string': 'G722,PCMU,PCMA,GSM', 'Channel-Orig-Caller-ID-Number': 'XXXXXXXXXX', 'Channel-Channel-Progress-Media-Time': '0', 'Caller-Screen-Bit': 'true', 'Call-Direction': 'inbound', 'variable_sip_to_uri': 'XXXX@10.5.112.145', 'variable_sip_received_port': '45915', 'Event-Calling-Line-Number': '1875', 'variable_sip_req_uri': 'XXXX@10.5.112.145:5080', 'variable_sip_req_host': '10.5.112.145', 'variable_sip_full_from': '<sip:XXXXXXXXXX@10.10.210.241>;tag=550077~39001e80-9450-40c2-ac0c-b69f46161ebe-41352787', 'Reply-Text': '+OK\n', 'variable_call_uuid': '3ebcc5a2-d44d-11e2-b07e-d9717d078b9d', 'Caller-Channel-Resurrect-Time': '0', 'Caller-ANI': 'XXXXXXXXXX', 'variable_sip_contact_uri': 'XXXXXXXXXX@10.10.210.241:5060', 'Channel-Channel-Created-Time': '1371143900484700', 'Event-Date-GMT': 'Thu, 13 Jun 2013 17:18:20 GMT', 'Event-Date-Timestamp': '1371143900484700', 'Channel-Channel-Last-Hold': '0', 'Event-Name': 'CHANNEL_DATA', 'FreeSWITCH-Switchname': 'FAX01', 'variable_sip_contact_params': 'transport=tcp', 'Caller-Channel-Bridged-Time': '0', 'Unique-ID': '3ebcc5a2-d44d-11e2-b07e-d9717d078b9d', 'variable_sip_via_host': '10.10.210.241', 'Control': 'full', 'Caller-Caller-ID-Number': 'XXXXXXXXXX', 'Caller-Username': 'XXXXXXXXXX', 'variable_channel_name': 'sofia/external/XXXXXXXXXX@10.10.210.241', 'Caller-Privacy-Hide-Number': 'false', 'Content-Type': 'command/reply', 'variable_endpoint_disposition': 'DELAYED NEGOTIATION', 'variable_sip_to_user': 'XXXX', 'Event-Date-Local': '2013-06-13 13:18:20', 'Channel-Channel-Answered-Time': '0', 'Event-Calling-Function': 'parse_command', 'Channel-Channel-Hold-Accum': '0', 'variable_socket_host': '127.0.0.1', 'variable_sip_req_user': 'XXXX', 'Caller-Destination-Number': 'XXXX', 'variable_ep_codec_string': 'PCMU@8000h@20i@64000b', 'Caller-Profile-Index': '1', 'Channel-State': 'CS_EXECUTE', 'variable_sip_via_port': '5060', 'variable_sip_contact_port': '5060', 'Caller-Caller-ID-Name': 'XXXXXXXXXX', 'Core-UUID': '60d59eae-d44b-11e2-b04f-d9717d078b9d', 'Caller-Profile-Created-Time': '1371143900484700', 'variable_sip_call_id': '3d3d9b80-1b91fedc-25ed0-f1d20a0a@10.10.210.241', 'variable_sip_via_protocol': 'tcp', 'Caller-Channel-Answered-Time': '0', 'variable_switch_r_sdp': 'v=0\r\no=CiscoSystemsCCM-SIP 2000 1 IN IP4 10.10.210.241\r\ns=SIP Call\r\nc=IN IP4 10.10.210.252\r\nt=0 0\r\nm=audio 32014 RTP/AVP 0 101\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101 0-15\r\na=ptime:20\r\n', 'Answer-State': 'ringing', 'Channel-ANI': 'XXXXXXXXXX', 'Caller-Context': 'public', 'variable_max_forwards': '70', 'Caller-Channel-Hold-Accum': '0', 'variable_sip_P-Asserted-Identity': 'XXXXXXXXXX', 'Channel-Channel-Resurrect-Time': '0', 'variable_sip_received_ip': '10.10.210.241', 'Channel-Channel-Progress-Time': '0', 'Caller-Channel-Created-Time': '1371143900484700', 'Channel-Context': 'public', 'variable_sip_from_tag': '550077~39001e80-9450-40c2-ac0c-b69f46161ebe-41352787', 'Channel-Channel-Transfer-Time': '0', 'variable_sip_via_rport': '45915', 'variable_sip_from_uri': 'XXXXXXXXXX@10.10.210.241', 'Channel-Channel-Hangup-Time': '0', 'Event-Sequence': '842', 'Caller-Orig-Caller-ID-Number': 'XXXXXXXXXX', 'variable_sofia_profile_name': 'external', 'Channel-Profile-Index': '1', 'Channel-Direction': 'inbound', 'Channel-Name': 'sofia/external/XXXXXXXXXX@10.10.210.241', 'variable_sip_req_port': '5080', 'variable_outside_call': 'true', 'Caller-Channel-Last-Hold': '0', 'variable_current_application_data': '127.0.0.1:8084 async full', 'Channel-Privacy-Hide-Name': 'false', 'Channel-State-Number': '4', 'Channel-Call-UUID': '3ebcc5a2-d44d-11e2-b07e-d9717d078b9d', 'Channel-Caller-ID-Name': 'XXXXXXXXXX', 'Caller-Dialplan': 'XML', 'Caller-Source': 'mod_sofia', 'variable_sip_network_ip': '10.10.210.241', 'Caller-Channel-Name': 'sofia/external/XXXXXXXXXX@10.10.210.241', 'FreeSWITCH-IPv4': '10.5.112.145', 'FreeSWITCH-IPv6': '::1', 'Channel-Network-Addr': '10.10.210.241', 'variable_sip_full_to': '<sip:XXXX@10.5.112.145>', 'Channel-Dialplan': 'XML', 'Channel-HIT-Dialplan': 'true', 'Caller-Privacy-Hide-Name': 'false', 'variable_current_application': 'socket', 'Channel-Orig-Caller-ID-Name': 'XXXXXXXXXX', 'Event-Calling-File': 'mod_event_socket.c', 'Channel-Channel-Bridged-Time': '0', 'Channel-Destination-Number': 'XXXX', 'Caller-Channel-Progress-Media-Time': '0', 'variable_sip_from_user': 'XXXXXXXXXX', 'Channel-Source': 'mod_sofia', 'variable_DP_MATCH': 'ARRAY::XXXX|:XXXX', 'Channel-Profile-Created-Time': '1371143900484700', 'variable_sip_network_port': '45915', 'Caller-Unique-ID': '3ebcc5a2-d44d-11e2-b07e-d9717d078b9d', 'variable_sip_to_host': '10.5.112.145', 'Channel-Screen-Bit': 'true', 'Presence-Call-Direction': 'inbound', 'variable_sip_full_via': 'SIP/2.0/TCP 10.10.210.241:5060;branch=z9hG4bK27ab4a041b18;rport=45915', 'variable_sip_user_agent': 'Cisco-CUCM8.5', 'variable_sip_contact_user': 'XXXXXXXXXX', 'variable_sip_contact_host': '10.10.210.241', 'variable_sip_local_network_addr': '10.5.112.145', 'variable_sip_cid_type': 'pid', 'variable_sip_from_host': '10.10.210.241', 'Caller-Channel-Hangup-Time': '0', 'variable_RFC2822_DATE': 'Thu, 13 Jun 2013 13:18:20 -0400', 'Channel-Caller-ID-Number': 'XXXXXXXXXX', 'Channel-Channel-Name': 'sofia/external/XXXXXXXXXX@10.10.210.241', 'Channel-Privacy-Hide-Number': 'false', 'Caller-Direction': 'inbound', 'Channel-Username': 'XXXXXXXXXX', 'variable_uuid': '3ebcc5a2-d44d-11e2-b07e-d9717d078b9d', 'variable_direction': 'inbound', 'Caller-Network-Addr': '10.10.210.241', 'Channel-Unique-ID': '3ebcc5a2-d44d-11e2-b07e-d9717d078b9d', 'Caller-Channel-Transfer-Time': '0', 'variable_sip_from_user_stripped': 'XXXXXXXXXX', 'variable_session_id': '5', 'Socket-Mode': 'async', 'Caller-Channel-Progress-Time': '0', 'FreeSWITCH-Hostname': 'FAX01', 'Channel-Call-State': 'RINGING'}, body=>
2013-06-13 13:18:20,492 plivo-outbound[4574]: DEBUG: (5) Execute: resume args=''
2013-06-13 13:18:20,493 plivo-outbound[4574]: DEBUG: (5) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK'}, body=>
2013-06-13 13:18:20,493 plivo-outbound[4574]: DEBUG: (5) Execute: linger args=''
2013-06-13 13:18:20,494 plivo-outbound[4574]: DEBUG: (5) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK will linger 0 seconds'}, body=>
2013-06-13 13:18:20,494 plivo-outbound[4574]: DEBUG: (5) Execute: myevents json args=''
2013-06-13 13:18:20,495 plivo-outbound[4574]: DEBUG: (5) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK Events Enabled'}, body=>
2013-06-13 13:18:20,495 plivo-outbound[4574]: DEBUG: (5) Execute: divert_events args='on'
2013-06-13 13:18:20,496 plivo-outbound[4574]: DEBUG: (5) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK events diverted'}, body=>
2013-06-13 13:18:20,496 plivo-outbound[4574]: DEBUG: (5) Execute: event json args='CUSTOM conference::maintenance plivo::dial'
2013-06-13 13:18:20,496 plivo-outbound[4574]: DEBUG: (5) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK event listener enabled json'}, body=>
2013-06-13 13:18:20,496 plivo-outbound[4574]: DEBUG: (5) Execute: set args=plivo_app=true, uuid='', lock=True, loops=1
2013-06-13 13:18:20,497 plivo-outbound[4574]: DEBUG: (5) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK'}, body=>
2013-06-13 13:18:20,497 plivo-outbound[4574]: DEBUG: (5) Execute: set args=hangup_after_bridge=false, uuid='', lock=True, loops=1
2013-06-13 13:18:20,498 plivo-outbound[4574]: DEBUG: (5) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK'}, body=>
2013-06-13 13:18:20,498 plivo-outbound[4574]: DEBUG: (5) Execute: api args='uuid_getvar 3ebcc5a2-d44d-11e2-b07e-d9717d078b9d plivo_transfer_url'
2013-06-13 13:18:20,499 plivo-outbound[4574]: DEBUG: (5) Response: <ApiResponse headers={'Content-Length': '7', 'Content-Type': 'api/response'}, body=_undef_>
2013-06-13 13:18:20,499 plivo-outbound[4574]: DEBUG: (5) Execute: api args='uuid_getvar 3ebcc5a2-d44d-11e2-b07e-d9717d078b9d plivo_answer_url'
2013-06-13 13:18:20,499 plivo-outbound[4574]: DEBUG: (5) Response: <ApiResponse headers={'Content-Length': '7', 'Content-Type': 'api/response'}, body=_undef_>
2013-06-13 13:18:20,499 plivo-outbound[4574]: INFO: (5) Using DefaultAnswerUrl http://127.0.0.1/ictfax/index.php?q=ictfax/receive_fax
2013-06-13 13:18:20,500 plivo-outbound[4574]: DEBUG: (5) Execute: api args='uuid_getvar 3ebcc5a2-d44d-11e2-b07e-d9717d078b9d plivo_sched_hangup_id'
2013-06-13 13:18:20,500 plivo-outbound[4574]: DEBUG: (5) Response: <ApiResponse headers={'Content-Length': '7', 'Content-Type': 'api/response'}, body=_undef_>
2013-06-13 13:18:20,500 plivo-outbound[4574]: INFO: (5) Processing Call
2013-06-13 13:18:20,501 plivo-outbound[4574]: INFO: (5) Fetching RESTXML from http://127.0.0.1/ictfax/index.php?q=ictfax/receive_fax
2013-06-13 13:18:20,501 plivo-outbound[4574]: DEBUG: (5) Extra params found in url query for POST http://127.0.0.1/ictfax/index.php?q=ictfax/receive_fax
2013-06-13 13:18:20,501 plivo-outbound[4574]: INFO: (5) Fetching POST http://127.0.0.1/ictfax/index.php?q=ictfax/receive_fax with {'q': 'ictfax/receive_fax', 'To': 'XXXX', 'Direction': 'inbound', 'From': 'XXXXXXXXXX', 'variable_duration': '', 'CallerName': 'XXXXXXXXXX', 'CallUUID': '3ebcc5a2-d44d-11e2-b07e-d9717d078b9d', 'CallStatus': 'ringing'}
2013-06-13 13:18:20,673 plivo-outbound[4574]: INFO: (5) Sent to POST http://127.0.0.1/ictfax/index.php?q=ictfax/receive_fax with {'q': 'ictfax/receive_fax', 'To': 'XXXX', 'Direction': 'inbound', 'From': 'XXXXXXXXXX', 'variable_duration': '', 'CallerName': 'XXXXXXXXXX', 'CallUUID': '3ebcc5a2-d44d-11e2-b07e-d9717d078b9d', 'CallStatus': 'ringing'} -- Result:






<?xml version="1.0" encoding="UTF-8"?>
        <Response>
        <ReceiveFax>/usr/ictfax/sites/default/files/ictfax/inbox/49.tif</ReceiveFax>
        <Redirect>http://127.0.0.1/ictfax/index.php?q=ictfax/send_email/49</Redirect>
        </Response>
2013-06-13 13:18:20,673 plivo-outbound[4574]: INFO: (5) Requested RESTXML to http://127.0.0.1/ictfax/index.php?q=ictfax/receive_fax
2013-06-13 13:18:20,673 plivo-outbound[4574]: DEBUG: (5) Answering because Element ReceiveFax need it
2013-06-13 13:18:20,674 plivo-outbound[4574]: DEBUG: (5) Execute: answer args=, uuid='', lock=True, loops=1
2013-06-13 13:18:20,674 plivo-outbound[4574]: DEBUG: (5) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK'}, body=>
2013-06-13 13:18:20,674 plivo-outbound[4574]: INFO: (5) [ReceiveFax] /usr/ictfax/sites/default/files/ictfax/inbox/49.tif {'loop': 1}
2013-06-13 13:18:20,675 plivo-outbound[4574]: DEBUG: (5) Faxing 1 times
2013-06-13 13:18:20,675 plivo-outbound[4574]: DEBUG: (5) Execute: set args=fax_enable_t38=true, uuid='', lock=True, loops=1
2013-06-13 13:18:20,675 plivo-outbound[4574]: DEBUG: (5) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK'}, body=>
2013-06-13 13:18:20,675 plivo-outbound[4574]: DEBUG: (5) Execute: rxfax args=/usr/ictfax/sites/default/files/ictfax/inbox/49.tif, uuid='', lock=True, loops=1
2013-06-13 13:18:20,676 plivo-outbound[4574]: DEBUG: (5) Response: <CommandResponse headers={'Content-Type': 'command/reply', 'Reply-Text': '+OK'}, body=>
2013-06-13 13:18:26,536 plivo-outbound[4574]: DEBUG: (5) ReceiveFax done (_none_)
2013-06-13 13:18:26,536 plivo-outbound[4574]: INFO: (5) ReceiveFax Finished
2013-06-13 13:18:26,537 plivo-outbound[4574]: INFO: (5) [ReceiveFax] Done
2013-06-13 13:18:26,537 plivo-outbound[4574]: INFO: (5) [Redirect] http://127.0.0.1/ictfax/index.php?q=ictfax/send_email/49 {'method': 'POST'}
2013-06-13 13:18:26,537 plivo-outbound[4574]: INFO: (5) [Redirect] Done (redirect)
2013-06-13 13:18:26,537 plivo-outbound[4574]: INFO: (5) Redirecting to POST http://127.0.0.1/ictfax/index.php?q=ictfax/send_email/49 to fetch RESTXML
2013-06-13 13:18:26,537 plivo-outbound[4574]: DEBUG: (5) Execute: api args='uuid_getvar 3ebcc5a2-d44d-11e2-b07e-d9717d078b9d plivo_transfer_progress'
2013-06-13 13:18:26,539 plivo-outbound[4574]: INFO: (5) Event: channel 3ebcc5a2-d44d-11e2-b07e-d9717d078b9d has hung up (BEARERCAPABILITY_NOTIMPL)
2013-06-13 13:18:26,540 plivo-outbound[4574]: DEBUG: (5) Response: <ApiResponse headers={'Content-Length': '22', 'Content-Type': 'api/response'}, body=-ERR No such channel!
>
2013-06-13 13:18:26,540 plivo-outbound[4574]: WARNING: (5) Channel has hung up, breaking Processing Call
2013-06-13 13:18:26,540 plivo-outbound[4574]: INFO: (5) Processing Call Ended
2013-06-13 13:18:26,540 plivo-outbound[4574]: DEBUG: (5) Releasing Connection ...
2013-06-13 13:18:26,540 plivo-outbound[4574]: DEBUG: (5) Releasing Connection Done
2013-06-13 13:18:26,541 plivo-outbound[4574]: INFO: (5) End request from ('127.0.0.1', 57538)


Code: Select all
tail: /tmp/mytest.txt: file truncated
XXXX
ictpbx_id 1
if condition success USER@EMAIL.COM
/usr/ictfax/sites/default/files/ictfax/inbox/48.tif
Request=Array
(
    [q] => ictfax/receive_fax
    [To] => XXXX
    [Direction] => inbound
    [From] => XXXXXXXXXX
    [variable_duration] =>
    [CallerName] => XXXXXXXXXX
    [CallUUID] => fe4178d8-d44c-11e2-b073-d9717d078b9d
    [CallStatus] => ringing
)

after fax application run
tail: /tmp/mytest.txt: file truncated
XXXX
ictpbx_id 1
if condition success USER@EMAIL.COM
/usr/ictfax/sites/default/files/ictfax/inbox/49.tif
Request=Array
(
    [q] => ictfax/receive_fax
    [To] => XXXX
    [Direction] => inbound
    [From] => XXXXXXXXXX
    [variable_duration] =>
    [CallerName] => XXXXXXXXXX
    [CallUUID] => 3ebcc5a2-d44d-11e2-b07e-d9717d078b9d
    [CallStatus] => ringing
)

after fax application run
tail: /tmp/mytest.txt: file truncated
XXXX
ictpbx_id 1
if condition success USER@EMAIL.COM
/usr/ictfax/sites/default/files/ictfax/inbox/50.tif
Request=Array
(
    [q] => ictfax/receive_fax
    [To] => XXXX
    [Direction] => inbound
    [From] => XXXXXXXXXX
    [variable_duration] =>
    [CallerName] => XXXXXXXXXX
    [CallUUID] => 7f373bf8-d44d-11e2-b089-d9717d078b9d
    [CallStatus] => ringing
)

after fax application run



I have removed any identifiable information.

Any help is appreciated!

Sean
cssimmen
 
Posts: 4
Joined: Thu Jun 13, 2013 5:09 pm

Re: Unable to receive faxes through CUCM

Postby falak » Fri Jun 14, 2013 10:01 am

falak
Site Admin
 
Posts: 166
Joined: Tue Jan 17, 2012 7:33 am

Re: Unable to receive faxes through CUCM

Postby cssimmen » Fri Jun 14, 2013 3:32 pm

I have. I was able to use those to get it setup initially. I can see the call going to Freeswitch and Plivo, but then I get the T.38 codec issue.

Sean
cssimmen
 
Posts: 4
Joined: Thu Jun 13, 2013 5:09 pm

Re: Unable to receive faxes through CUCM

Postby cssimmen » Mon Jun 17, 2013 8:20 pm

I found out the issue was with the Voice Gateway converting fax to T.38.

Now I am having a few issues sending faxes. First, when I try to use WebFax, I get
Code: Select all
[ERR] mod_spandsp_fax.c:1325 Cannot send non-existant fax file /usr/ictfax/sites/default/files/ictfax/webfax/7.tif]

There doesn't seem to be an indication whether my pdf was uploaded or not.

Beside that, I am also having an issue sending a fax via email, I am unsure if the cron is working to pull the sent emails to the fax server to send. The /var/spool/mail/freeswitch has emails in it and are not getting removed and nothing is showing up in the Outbox.

Lastly, I know falak had said he didn't have much experience with CUCM, but I figure someone may have run into this before, I receive
Code: Select all
-ERR UNALLOCATED_NUMBER

Code: Select all
SIP/2.0 404 Not Found
   Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5080;rport;branch=z9hG4bK0r2jg5eUc8Kem
   From: "" <sip:user@XXX.XXX.XXX.XXX:5060>;tag=vm55XrNr2v6Nm
   To: <sip:8383@XXX.XXX.XXX.XXX:5060>;tag=972885~39001e80-9450-40c2-ac0c-b69f46161ebe-28328719
   Date: Mon, 17 Jun 2013 20:16:31 GMT
   Call-ID: a4006fa4-522d-1231-2281-005056b20355
   CSeq: 45414031 INVITE
   Allow-Events: presence
   Reason: Q.850;cause=1
   Content-Length: 0

when trying this command from Freeswitch
Code: Select all
originate sofia/gateway/cucm/8383 &echo()


Thanks again for the help!
Sean
cssimmen
 
Posts: 4
Joined: Thu Jun 13, 2013 5:09 pm

Re: Unable to receive faxes through CUCM

Postby cssimmen » Tue Jun 18, 2013 6:39 pm

UPDATE

I have figured out the CUCM issues. The SIP trunk was not in the correct Calling Search Space.

I can now email2fax and fax2email.

I still cannot web2email. I checked /tmp and the pdfs I have been uploading are there but they are not being converted to .tif and placed in the /usr/ictfax/sites/default/files/ictfax/webfax folder.

I checked permissions and apache:apache has the correct permissions to both folders.

Thanks!
Sean
cssimmen
 
Posts: 4
Joined: Thu Jun 13, 2013 5:09 pm


Return to Configuration

Who is online

Users browsing this forum: No registered users and 1 guest