Cannot Receive faxes

Post your configuration issues while installing ictfax

Cannot Receive faxes

Postby attaserv » Fri Apr 26, 2013 6:48 am

Hello,

I have followed all the installation instructions twice. Everything seems to work except, I cannot receive a fax. When I dial the fax number, it seems to just hang up.
If I dial from a regular phone, I hear no fax tone. Following is some log info:
plivo-rest.log:
2013-03-11 12:48:48,864 plivo-rest[3411]: INFO: Hangup for Incoming CallUUID ec58b71c-8a73-11e2-821d-358268a2268b Completed, HangupCause NORMAL_CLEARING
2013-03-11 12:48:48,864 plivo-rest[3411]: DEBUG: Using HangupUrl from DefaultHangupUrl for CallUUID ec58b71c-8a73-11e2-821d-358268a2268b
2013-03-11 12:48:48,864 plivo-rest[3411]: DEBUG: Extra params found in url query for POST http://127.0.0.1/ictfax/index.php?q=ict ... ax_billing
2013-03-11 12:48:48,864 plivo-rest[3411]: INFO: Fetching POST http://127.0.0.1/ictfax/index.php?q=ict ... ax_billing with {'q': 'ictfax/receive_fax_billing', 'To': u'14696564446', 'Direction': u'inbound', 'From': u'+18324267737', 'variable_duration': u'2', 'CallUUID': u'ec58b71c-8a73-11e2-821d-358268a2268b', 'HangupCause': u'NORMAL_CLEARING', 'CallStatus': 'completed'}
2013-03-11 12:48:49,049 plivo-rest[3411]: INFO: Sent to POST http://127.0.0.1/ictfax/index.php?q=ict ... ax_billing with {'q': 'ictfax/receive_fax_billing', 'To': u'14696564446', 'Direction': u'inbound', 'From': u'+18324267737', 'variable_duration': u'2', 'CallUUID': u'ec58b71c-8a73-11e2-821d-358268a2268b', 'HangupCause': u'NORMAL_CLEARING', 'CallStatus': 'completed'} -- Result: ^M
^M
^M
^M
^M
^M
^M
<!DOCTYPE html PUBLIC "-//W3C//DTD XHTML+RDFa 1.0//EN"
"http://www.w3.org/MarkUp/DTD/xhtml-rdfa-1.dtd">
<html xmlns="http://www.w3.org/1999/xhtml" xml:lang="en" version="XHTML+RDFa 1.0" dir="ltr"
xmlns:content="http://purl.org/rss/1.0/modules/content/"
xmlns:dc="http://purl.org/dc/terms/"
xmlns:foaf="http://xmlns.com/foaf/0.1/"
xmlns:og="http://ogp.me/ns#"
xmlns:rdfs="http://www.w3.org/2000/01/rdf-schema#"
xmlns:sioc="http://rdfs.org/sioc/ns#"
xmlns:sioct="http://rdfs.org/sioc/types#"
xmlns:skos="http://www.w3.org/2004/02/skos/core#"
xmlns:xsd="http://www.w3.org/2001/XMLSchema#">

<head profile="http://www.w3.org/1999/xhtml/vocab">
<meta http-equiv="Content-Type" content="text/html; charset=utf-8" />
<link rel="shortcut icon" href="http://127.0.0.1/ictfax/misc/favicon.ico" type="image/vnd.microsoft.icon" />
<meta name="Generator" content="Drupal 7 (http://drupal.org)" />
<title>Fax Account | faxserver1.onyxwa.com</title>
<style type="text/css" media="all">@import url("http://127.0.0.1/ictfax/modules/system/system.base.css?mji3x7");
@import url("http://127.0.0.1/ictfax/modules/system/system.menus.css?mji3x7");
@import url("http://127.0.0.1/ictfax/modules/system/system.messages.css?mji3x7");
@import url("http://127.0.0.1/ictfax/modules/system/system.theme.css?mji3x7");</style>
<style type="text/css" media="all">@import url("http://127.0.0.1/ictfax/modules/comment/comment.css?mji3x7");
@import url("http://127.0.0.1/ictfax/modules/field/theme/field.css?mji3x7");
@import url("http://127.0.0.1/ictfax/modules/node/node.css?mji3x7");
@import url("http://127.0.0.1/ictfax/modules/search/search.css?mji3x7");
@import url("http://127.0.0.1/ictfax/modules/user/user.css?mji3x7");
@import url("http://127.0.0.1/ictfax/sites/all/modules/views/css/views.css?mji3x7");</style>
<style type="text/css" media="all">@import url("http://127.0.0.1/ictfax/sites/all/modules/ctools/css/ctools.css?mji3x7");</style>
<style type="text/css" media="all">@import url("http://127.0.0.1/ictfax/themes/bartik/css/layout.css?mji3x7");
@import url("http://127.0.0.1/ictfax/themes/bartik/css/style.css?mji3x7");
@import url("http://127.0.0.1/ictfax/themes/bartik/css/colors.css?mji3x7");</style>
<style type="text/css" media="print">@import url("http://127.0.0.1/ictfax/themes/bartik/css/print.css?mji3x7");</style>

<!--[if lte IE 7]>
<link type="text/css" rel="stylesheet" href="http://127.0.0.1/ictfax/themes/bartik/css/ie.css?mji3x7" media="all" />
<![endif]-->

<!--[if IE 6]>
<link type="text/css" rel="stylesheet" href="http://127.0.0.1/ictfax/themes/bartik/css/ie6.css?mji3x7" media="all" />
<![endif]-->
<script type="text/javascript" src="http://127.0.0.1/ictfax/misc/jquery.js?v=1.4.4"></script>
...


freeswitch.log

2013-03-11 12:48:46.547352 [NOTICE] switch_channel.c:976 New Channel sofia/internal/+18324267737@flowroute.com [ec58b71c-8a73-11e2-821d-358268a2268b]
2013-03-11 12:48:46.547352 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/+18324267737@flowroute.com [BREAK]
2013-03-11 12:48:46.547352 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/+18324267737@flowroute.com [BREAK]
2013-03-11 12:48:46.547352 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/+18324267737@flowroute.com) Running State Change CS_NEW
2013-03-11 12:48:46.547352 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/+18324267737@flowroute.com) State NEW
2013-03-11 12:48:46.567342 [DEBUG] sofia.c:7697 IP 216.115.69.144 Approved by acl "domains[]". Access Granted.
2013-03-11 12:48:46.587266 [DEBUG] sofia.c:5597 Channel sofia/internal/+18324267737@flowroute.com entering state [received][100]
2013-03-11 12:48:46.587266 [DEBUG] sofia.c:5608 Remote SDP:
v=0^M
o=- 25212 14276 IN IP4 4.55.17.2^M
s=-^M
c=IN IP4 4.55.17.2^M
t=0 0^M
m=audio 20864 RTP/AVP 0 18 101^M
a=rtpmap:18 G729/8000^M
a=fmtp:18 annexb=no^M
a=rtpmap:101 telephone-event/8000^M
a=fmtp:101 0-15^M
2013-03-11 12:48:46.587266 [DEBUG] sofia.c:5821 (sofia/internal/+18324267737@flowroute.com) State Change CS_NEW -> CS_INIT
2013-03-11 12:48:46.587266 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/+18324267737@flowroute.com [BREAK]
2013-03-11 12:48:46.587266 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/+18324267737@flowroute.com) Running State Change CS_INIT
2013-03-11 12:48:46.587266 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/+18324267737@flowroute.com) State INIT
2013-03-11 12:48:46.587266 [DEBUG] mod_sofia.c:86 sofia/internal/+18324267737@flowroute.com SOFIA INIT
2013-03-11 12:48:46.587266 [DEBUG] mod_sofia.c:126 (sofia/internal/+18324267737@flowroute.com) State Change CS_INIT -> CS_ROUTING
2013-03-11 12:48:46.587266 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/+18324267737@flowroute.com [BREAK]
2013-03-11 12:48:46.587266 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/+18324267737@flowroute.com) State INIT going to sleep
2013-03-11 12:48:46.587266 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/+18324267737@flowroute.com) Running State Change CS_ROUTING
2013-03-11 12:48:46.587266 [DEBUG] switch_channel.c:2012 (sofia/internal/+18324267737@flowroute.com) Callstate Change DOWN -> RINGING
2013-03-11 12:48:46.587266 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/+18324267737@flowroute.com) State ROUTING
2013-03-11 12:48:46.587266 [DEBUG] mod_sofia.c:149 sofia/internal/+18324267737@flowroute.com SOFIA ROUTING
2013-03-11 12:48:46.587266 [DEBUG] switch_core_state_machine.c:117 sofia/internal/+18324267737@flowroute.com Standard ROUTING
2013-03-11 12:48:46.587266 [INFO] mod_dialplan_xml.c:557 Processing +18324267737 <+18324267737>->14696564446 in context public
Dialplan: sofia/internal/+18324267737@flowroute.com parsing [public->unloop] continue=false
Dialplan: sofia/internal/+18324267737@flowroute.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/+18324267737@flowroute.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/+18324267737@flowroute.com parsing [public->outside_call] continue=true
Dialplan: sofia/internal/+18324267737@flowroute.com Absolute Condition [outside_call]
Dialplan: sofia/internal/+18324267737@flowroute.com Action set(outside_call=true)
Dialplan: sofia/internal/+18324267737@flowroute.com Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/+18324267737@flowroute.com parsing [public->call_debug] continue=true
Dialplan: sofia/internal/+18324267737@flowroute.com Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/+18324267737@flowroute.com parsing [public->plivo_public_did] continue=false
Dialplan: sofia/internal/+18324267737@flowroute.com Regex (PASS) [plivo_public_did] destination_number(14696564446) =~ /^(\d+)$/ break=on-false
Dialplan: sofia/internal/+18324267737@flowroute.com Action enable_heartbeat(60)
Dialplan: sofia/internal/+18324267737@flowroute.com Action socket(127.0.0.1:8084 async full)
2013-03-11 12:48:46.587266 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/+18324267737@flowroute.com) State Change CS_ROUTING -> CS_EXECUTE
2013-03-11 12:48:46.587266 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/+18324267737@flowroute.com [BREAK]
2013-03-11 12:48:46.587266 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/+18324267737@flowroute.com) State ROUTING going to sleep
2013-03-11 12:48:46.587266 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/+18324267737@flowroute.com) Running State Change CS_EXECUTE
2013-03-11 12:48:46.587266 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/+18324267737@flowroute.com) State EXECUTE
2013-03-11 12:48:46.587266 [DEBUG] mod_sofia.c:242 sofia/internal/+18324267737@flowroute.com SOFIA EXECUTE
2013-03-11 12:48:46.587266 [DEBUG] switch_core_state_machine.c:209 sofia/internal/+18324267737@flowroute.com Standard EXECUTE
EXECUTE sofia/internal/+18324267737@flowroute.com set(outside_call=true)
2013-03-11 12:48:46.587266 [DEBUG] mod_dptools.c:1367 sofia/internal/+18324267737@flowroute.com SET [outside_call]=[true]
EXECUTE sofia/internal/+18324267737@flowroute.com set(RFC2822_DATE=Mon, 11 Mar 2013 12:48:46 -0500)
2013-03-11 12:48:46.587266 [DEBUG] mod_dptools.c:1367 sofia/internal/+18324267737@flowroute.com SET [RFC2822_DATE]=[Mon, 11 Mar 2013 12:48:46 -0500]
EXECUTE sofia/internal/+18324267737@flowroute.com enable_heartbeat(60)
2013-03-11 12:48:46.587266 [INFO] switch_core_session.c:1470 sofia/internal/+18324267737@flowroute.com setting session heartbeat to 60 second(s).
EXECUTE sofia/internal/+18324267737@flowroute.com socket(127.0.0.1:8084 async full)
2013-03-11 12:48:46.607211 [DEBUG] switch_core_session.c:1110 Send signal sofia/internal/+18324267737@flowroute.com [BREAK]
2013-03-11 12:48:46.607211 [DEBUG] switch_core_session.c:1110 Send signal sofia/internal/+18324267737@flowroute.com [BREAK]
2013-03-11 12:48:46.607211 [DEBUG] switch_ivr.c:612 sofia/internal/+18324267737@flowroute.com Command Execute set(plivo_app=true)
EXECUTE sofia/internal/+18324267737@flowroute.com set(plivo_app=true)
2013-03-11 12:48:46.627184 [DEBUG] mod_dptools.c:1367 sofia/internal/+18324267737@flowroute.com SET [plivo_app]=[true]
2013-03-11 12:48:46.627184 [DEBUG] switch_ivr.c:612 sofia/internal/+18324267737@flowroute.com Command Execute set(hangup_after_bridge=false)
EXECUTE sofia/internal/+18324267737@flowroute.com set(hangup_after_bridge=false)
2013-03-11 12:48:46.627184 [DEBUG] mod_dptools.c:1367 sofia/internal/+18324267737@flowroute.com SET [hangup_after_bridge]=[false]
2013-03-11 12:48:48.827352 [DEBUG] mod_event_socket.c:2647 (sofia/internal/+18324267737@flowroute.com) State Change CS_EXECUTE -> CS_RESET
2013-03-11 12:48:48.827352 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/+18324267737@flowroute.com [BREAK]
2013-03-11 12:48:48.847335 [DEBUG] mod_event_socket.c:499 (sofia/internal/+18324267737@flowroute.com) State Change CS_RESET -> CS_EXECUTE
2013-03-11 12:48:48.847335 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/+18324267737@flowroute.com [BREAK]
2013-03-11 12:48:48.847335 [NOTICE] switch_core_state_machine.c:262 sofia/internal/+18324267737@flowroute.com has executed the last dialplan instruction, hanging up.
2013-03-11 12:48:48.847335 [DEBUG] switch_channel.c:3011 (sofia/internal/+18324267737@flowroute.com) Callstate Change RINGING -> HANGUP


I appreciate anyone's help!
attaserv
 
Posts: 4
Joined: Wed Apr 24, 2013 1:22 pm

Re: Cannot Receive faxes

Postby attaserv » Fri Apr 26, 2013 6:49 am

Update:

I found this in the forums:
If you have done everything right so far what I have said. Then there is one last thing that could be causing problem.
In source file ictpbx/ictfax/ictfax.module before line 1133, add the following two lines:
$ictpbx_user['free_bundle'] = 1;
$ictpbx_user['reserve_free_bundle'] = 0;

Once I made this change, I now receive a fax tone.

But now I receive this error:
Error: <urlopen error [Errno 111] Connection refused>
attaserv
 
Posts: 4
Joined: Wed Apr 24, 2013 1:22 pm

Re: Cannot Receive faxes

Postby falak » Fri Apr 26, 2013 6:50 am

Where are you getting this Error [111]: Connection refused? at Freeswitch or Plivo log? This is a network layer error. Following links might help you.
http://stackoverflow.com/questions/2558 ... on-refused
http://stackoverflow.com/questions/1214 ... n-time-out
falak
Site Admin
 
Posts: 166
Joined: Tue Jan 17, 2012 7:33 am

Re: Cannot Receive faxes

Postby attaserv » Fri Apr 26, 2013 6:51 am

I appreicate your reply. The error is in the plivo-rest.log

Here is the full error message
2013-03-25 17:44:16,250 plivo-rest[1663]: ERROR: Sending to POST http://127.0.0.1:5000/heartbeat/ with {'Direction': u'inbound', 'From': u'18324267737', 'HeartbeatTime': '1364251456.23', 'ElapsedTime': '0.0', 'To': u'14696565411', 'CallUUID': u'85953e8e-959d-11e2-a492-7b684d828cb2', 'AnsweredTime': '1364251456.23', 'CallStatus': 'in-progress'} -- Error: <urlopen error [Errno 111] Connection refused>


I looked at the links you sent, but they did not help.

Please let me know any other ideas you may have.

Thank you!
attaserv
 
Posts: 4
Joined: Wed Apr 24, 2013 1:22 pm

Re: Cannot Receive faxes

Postby attaserv » Fri Apr 26, 2013 6:51 am

I got rid of the error message by commenting out the following line in /usr/local/plivo/etc/plivo/default.conf :

CALL_HEARTBEAT_URL = http://127.0.0.1:5000/heartbeat/

However, I still cannot receive faxes.
attaserv
 
Posts: 4
Joined: Wed Apr 24, 2013 1:22 pm


Return to Configuration

Who is online

Users browsing this forum: No registered users and 2 guests