HylaFax not receiving faxes - new setup

mismatrix

Joined
Aug 16, 2009
Messages
11
Likes
0
Points
0
#1
Just learning Elastix and trying to get it setup to receive faxes over our SIP trunk. I think I have everything configured correctly and I see the call being transferred to the IAXModem but after about 2 seconds, it hang's up and never receives the fax. Here is some debug info...

Hylafax Log:
Aug 16 15:19:36.50: [15511]: SESSION BEGIN 000000001
Aug 16 15:19:36.50: [15511]: HylaFAX (tm) Version 4.3.3
Aug 16 15:19:36.50: [15511]: CallID: "8582020301" "8582020301" "<NONE>" "s"
Aug 16 15:19:36.50: [15511]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Aug 16 15:19:36.50: [15511]: <-- [4:ATA\r]
Aug 16 15:19:41.25: [15511]: --> [7]
Aug 16 15:19:41.25: [15511]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyIAX2'
Aug 16 15:19:41.25: [15511]: STATE CHANGE: ANSWERING -> RECEIVING
Aug 16 15:19:41.25: [15511]: RECV FAX: begin
Aug 16 15:19:41.25: [15511]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 CC 74 CC>
Aug 16 15:19:41.25: [15511]: <-- data [32]
Aug 16 15:19:41.25: [15511]: <-- data [2]
Aug 16 15:19:41.26: [15511]: --> [10:NO CARRIER]
Aug 16 15:19:41.26: [15511]: MODEM No carrier
Aug 16 15:19:41.26: [15511]: DELAY 1500 ms
Aug 16 15:19:42.76: [15511]: <-- [9:AT+FTH=3\r]
Aug 16 15:19:42.76: [15511]: --> [5]
Aug 16 15:19:42.76: [15511]: RECV FAX: RSPREC error/got EOT
Aug 16 15:19:42.76: [15511]: RECV FAX: end
Aug 16 15:19:42.76: [15511]: SESSION END


Asterisk output:

-- Executing [5626859966@from-sip-external:1] NoOp("SIP/ColorBroadband-b75015c0", "Received incoming SIP connection from unknown peer to 5626859966") in new stack
-- Executing [5626859966@from-sip-external:2] Set("SIP/ColorBroadband-b75015c0", "DID=5626859966") in new stack
-- Executing [5626859966@from-sip-external:3] Goto("SIP/ColorBroadband-b75015c0", "s|1") in new stack
-- Goto (from-sip-external,s,1)
-- Executing [s@from-sip-external:1] GotoIf("SIP/ColorBroadband-b75015c0", "1?from-trunk|5626859966|1") in new stack
-- Goto (from-trunk,5626859966,1)
-- Executing [5626859966@from-trunk:1] Set("SIP/ColorBroadband-b75015c0", "__FROM_DID=5626859966") in new stack
-- Executing [5626859966@from-trunk:2] Gosub("SIP/ColorBroadband-b75015c0", "app-blacklist-check|s|1") in new stack
-- Executing [s@app-blacklist-check:1] LookupBlacklist("SIP/ColorBroadband-b75015c0", "") in new stack
-- Executing [s@app-blacklist-check:2] GotoIf("SIP/ColorBroadband-b75015c0", "0?blacklisted") in new stack
-- Executing [s@app-blacklist-check:3] Return("SIP/ColorBroadband-b75015c0", "") in new stack
-- Executing [5626859966@from-trunk:3] ExecIf("SIP/ColorBroadband-b75015c0", "1 |Set|CALLERID(name)=8582020301") in new stack
-- Executing [5626859966@from-trunk:4] Set("SIP/ColorBroadband-b75015c0", "FAX_RX=151") in new stack
-- Executing [5626859966@from-trunk:5] Set("SIP/ColorBroadband-b75015c0", "FAX_RX_EMAIL=support@mismatrix.com") in new stack
-- Executing [5626859966@from-trunk:6] Answer("SIP/ColorBroadband-b75015c0", "") in new stack
-- Executing [5626859966@from-trunk:7] PlayTones("SIP/ColorBroadband-b75015c0", "ring") in new stack
-- Executing [5626859966@from-trunk:8] NVFaxDetect("SIP/ColorBroadband-b75015c0", "0|t") in new stack
-- Executing [fax@from-trunk:1] Goto("SIP/ColorBroadband-b75015c0", "ext-fax|in_fax|1") in new stack
-- Goto (ext-fax,in_fax,1)
-- Executing [in_fax@ext-fax:1] StopPlayTones("SIP/ColorBroadband-b75015c0", "") in new stack
-- Executing [in_fax@ext-fax:2] GotoIf("SIP/ColorBroadband-b75015c0", "0?3:analog_fax|1") in new stack
-- Goto (ext-fax,analog_fax,1)
-- Executing [analog_fax@ext-fax:1] GotoIf("SIP/ColorBroadband-b75015c0", "0?4:2") in new stack
-- Goto (ext-fax,analog_fax,2)
-- Executing [analog_fax@ext-fax:2] Set("SIP/ColorBroadband-b75015c0", "DIAL=IAX2/151") in new stack
-- Executing [analog_fax@ext-fax:3] Dial("SIP/ColorBroadband-b75015c0", "IAX2/151|20|d") in new stack
-- Called 151
-- Call accepted by 127.0.0.1 (format ulaw)
-- Format for call is ulaw
-- IAX2/151-185 is ringing
-- IAX2/151-185 answered SIP/ColorBroadband-b75015c0
-- Executing [h@ext-fax:1] System("SIP/ColorBroadband-b75015c0", "/var/lib/asterisk/bin/fax-process.pl --to --from freepbx@gmail.com --dest "5626859966" --subject "Fax from 8582020301 8582020301" --attachment fax_8582020301.pdf --type application/pdf --file ") in new stack
-- Executing [h@ext-fax:2] Hangup("SIP/ColorBroadband-b75015c0", "") in new stack
== Spawn h extension (ext-fax, h, 2) exited non-zero on 'SIP/ColorBroadband-b75015c0'
-- Hungup 'IAX2/151-185'
== Spawn extension (ext-fax, analog_fax, 3) exited non-zero on 'SIP/ColorBroadband-b75015c0'


Thanks in advance for any help in figuring this out.
 

jgutierrez

Joined
Feb 28, 2008
Messages
5,737
Likes
0
Points
0
#2
Just take into account that if you are using a sip trunk with a voip provider, your trunk shall not have any codec, fax MAY work on a sip trunk if you use g711 ulaw alaw, if you use g729 or gsm or any other compresion codec, your fax system won't work. That is so, since fax was thought to be used on an analog medium
 

mismatrix

Joined
Aug 16, 2009
Messages
11
Likes
0
Points
0
#3
Thanks for the info. I should have mentioned two things. I have Elastix configure to use only ulaw and we have a dedicated point-2-point to our VOIP provider.
 

maumar

Joined
Sep 12, 2008
Messages
80
Likes
0
Points
0
#4
fyi, i am using a voip sip trunk (alice of telecomitalia) and i have the same issues u have, here is the c0000006 file:
Sep 07 09:18:34.44: [ 7631]: SESSION BEGIN 000000006 397210721870286
Sep 07 09:18:34.44: [ 7631]: HylaFAX (tm) Version 4.3.3
Sep 07 09:18:34.44: [ 7631]: CallID: "0245446333" "0245446333" "<NONE>" "s"
Sep 07 09:18:34.44: [ 7631]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Sep 07 09:18:34.44: [ 7631]: <-- [4:ATA\r]
Sep 07 09:18:38.24: [ 7631]: --> [7]
Sep 07 09:18:38.24: [ 7631]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyIAX1'
Sep 07 09:18:38.24: [ 7631]: STATE CHANGE: ANSWERING -> RECEIVING
Sep 07 09:18:38.24: [ 7631]: RECV FAX: begin
Sep 07 09:18:38.24: [ 7631]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 CC 74 CC>
Sep 07 09:18:38.24: [ 7631]: <-- data [32]
Sep 07 09:18:38.24: [ 7631]: <-- data [2]
Sep 07 09:18:39.30: [ 7631]: --> [7]
Sep 07 09:18:39.30: [ 7631]: <-- HDLC<23:FF C0 02 96 76 96 4E 86 B2 04 F6 96 5E 96 4E AE 86 B2 04 1E 86 62 04>
Sep 07 09:18:39.30: [ 7631]: <-- data [23]
Sep 07 09:18:39.30: [ 7631]: <-- data [2]
Sep 07 09:18:40.14: [ 7631]: --> [7]
Sep 07 09:18:40.14: [ 7631]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18>
Sep 07 09:18:40.14: [ 7631]: <-- data [13]
Sep 07 09:18:40.14: [ 7631]: <-- data [2]
Sep 07 09:18:40.72: [ 7631]: --> [2]
Sep 07 09:18:40.72: [ 7631]: <-- [9:AT+FRH=3\r]
Sep 07 09:18:41.56: [ 7631]: --> [7]
Sep 07 09:18:51.56: [ 7631]: MODEM TIMEOUT: receiving HDLC frame data
Sep 07 09:18:51.56: [ 7631]: <-- data [1]
Sep 07 09:18:51.56: [ 7631]: --> [2]
Sep 07 09:18:51.56: [ 7631]: DELAY 1500 ms
Sep 07 09:18:53.06: [ 7631]: <-- [9:AT+FTH=3\r]
Sep 07 09:18:53.06: [ 7631]: --> [7]
Sep 07 09:18:53.06: [ 7631]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 CC 74 CC>
Sep 07 09:18:53.06: [ 7631]: <-- data [32]
Sep 07 09:18:53.06: [ 7631]: <-- data [2]
Sep 07 09:18:55.02: [ 7631]: --> [7]
Sep 07 09:18:55.02: [ 7631]: <-- HDLC<23:FF C0 02 96 76 96 4E 86 B2 04 F6 96 5E 96 4E AE 86 B2 04 1E 86 62 04>
Sep 07 09:18:55.02: [ 7631]: <-- data [23]
Sep 07 09:18:55.02: [ 7631]: <-- data [2]
Sep 07 09:18:55.89: [ 7631]: --> [7]
Sep 07 09:18:55.89: [ 7631]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18>
Sep 07 09:18:55.89: [ 7631]: <-- data [13]
Sep 07 09:18:55.89: [ 7631]: <-- data [2]
Sep 07 09:18:56.48: [ 7631]: --> [2]
Sep 07 09:18:56.49: [ 7631]: <-- [9:AT+FRH=3\r]
Sep 07 09:19:03.49: [ 7631]: --> [0:]
Sep 07 09:19:03.49: [ 7631]: MODEM <Empty line>
Sep 07 09:19:03.49: [ 7631]: MODEM TIMEOUT: waiting for v.21 carrier
Sep 07 09:19:03.49: [ 7631]: <-- data [1]
Sep 07 09:19:03.49: [ 7631]: --> [2]
Sep 07 09:19:03.49: [ 7631]: DELAY 1500 ms
Sep 07 09:19:04.99: [ 7631]: <-- [9:AT+FTH=3\r]
Sep 07 09:19:04.99: [ 7631]: --> [7]
Sep 07 09:19:04.99: [ 7631]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 CC 74 CC>
Sep 07 09:19:04.99: [ 7631]: <-- data [32]
Sep 07 09:19:04.99: [ 7631]: <-- data [2]
Sep 07 09:19:06.95: [ 7631]: --> [7]
Sep 07 09:19:06.95: [ 7631]: <-- HDLC<23:FF C0 02 96 76 96 4E 86 B2 04 F6 96 5E 96 4E AE 86 B2 04 1E 86 62 04>
Sep 07 09:19:06.95: [ 7631]: <-- data [23]
Sep 07 09:19:06.95: [ 7631]: <-- data [2]
Sep 07 09:19:07.81: [ 7631]: --> [7]
Sep 07 09:19:07.81: [ 7631]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18>
Sep 07 09:19:07.81: [ 7631]: <-- data [13]
Sep 07 09:19:07.81: [ 7631]: <-- data [2]
Sep 07 09:19:08.41: [ 7631]: --> [2]
Sep 07 09:19:08.41: [ 7631]: <-- [9:AT+FRH=3\r]
Sep 07 09:19:13.14: [ 7631]: --> [10:NO CARRIER]
Sep 07 09:19:13.14: [ 7631]: MODEM No carrier
Sep 07 09:19:13.14: [ 7631]: DELAY 1500 ms
Sep 07 09:19:14.64: [ 7631]: <-- [9:AT+FTH=3\r]
Sep 07 09:19:14.64: [ 7631]: --> [5]
Sep 07 09:19:14.64: [ 7631]: RECV FAX: RSPREC error/got EOT
Sep 07 09:19:14.64: [ 7631]: RECV FAX: end
Sep 07 09:19:14.64: [ 7631]: SESSION END



i note that the field to is not valued, as in my case:
-- Executing [h@ext-fax:1] System("SIP/ColorBroadband-b75015c0", "/var/lib/asterisk/bin/fax-process.pl --to --from freepbx@gmail.comThis e-mail address is being protected from spam bots, you need JavaScript enabled to view it --dest "5626859966" --subject "Fax from 8582020301 8582020301" --attachment fax_8582020301.pdf --type application/pdf --file " in new stack

if u manage to solve this issues please post solution here ;)
 

maumar

Joined
Sep 12, 2008
Messages
80
Likes
0
Points
0
#5
i have alaw as codec, too
 

jessie

Joined
Sep 17, 2008
Messages
124
Likes
0
Points
0
#6
Gentlemen,

We share the common problem here. I used to have a working Hylafax with my old version of Elastix, but after upgrading it I have the same issue like yours. I read in this page about - http://www.voip-info.org/wiki/view/T.38, T.38 on Asterisk version 1.4.xx and it was been noted that the new version of Asterisk 1.6, T.38 issue in fax is now supported.

The main issue why we are having problem in sending fax is because of the T.38. Other than this, your fax should be working.

Just to share my simulatiuons, I attached herewith my test result with a diagram.


Cheers,


Jessie http://forum.elastix.org/old_files/Fax_Application_Scenario_text.doc
 

jessie

Joined
Sep 17, 2008
Messages
124
Likes
0
Points
0
#7
Here is the diagram...
 

ramoncio

Joined
May 12, 2010
Messages
1,663
Likes
0
Points
0
#8
Fax over SIP trunks is not very reliable at the moment. If you really need it for a serious bussiness you should use T38. Elastix, as it is Asterisk 1.4 based, doesn't support T38 termination, just passthrough. So either you use Asterisk 1.6, Switchbox, etc, or you get a T38 capable faxing machine that can terminate T38 faxes.
If you don't need high reliability (maybe at your home pbx) you can use Elastix as it is, but you need to have g711 and good line quality, with no echos or clicks, which are fax stoppers. Most of faxes are sent, but some of them after several retries. If the fax has many pages, the possibility of failure increases quite a bit.
 

jessie

Joined
Sep 17, 2008
Messages
124
Likes
0
Points
0
#9
Hi Ramncio,

The frustrating part here is that I used to have a working Fax with the same setup from Elastix 1.3.2 version. As you know this version have Asterisk 1.4.xx too, the same what Elastix 1.6.7 has. The main reason I upgraded it solve the one way audio in SIP trunk (which probably unusual for others). So, my boss expected that whatever the version I used, I should be working. Will I assume it does but not.

I have here below my logs to share and hoping you might give me some recommendation to start with.

Sep 16 16:10:04.73: [24994]: SESSION BEGIN 000000016
Sep 16 16:10:04.73: [24994]: HylaFAX (tm) Version 4.3.3
Sep 16 16:10:04.73: [24994]: CallID: "Unknown" "Unknown" "<NONE>" "s"
Sep 16 16:10:04.73: [24994]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Sep 16 16:10:04.73: [24994]: <-- [4:ATA\r]
Sep 16 16:10:08.55: [24994]: --> [7]
Sep 16 16:10:08.55: [24994]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyIAX1'
Sep 16 16:10:08.55: [24994]: STATE CHANGE: ANSWERING -> RECEIVING
Sep 16 16:10:08.55: [24994]: RECV FAX: begin
Sep 16 16:10:08.56: [24994]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 CC 74 CC>
Sep 16 16:10:08.56: [24994]: <-- data [32]
Sep 16 16:10:08.56: [24994]: <-- data [2]
Sep 16 16:10:09.65: [24994]: --> [7]
Sep 16 16:10:09.65: [24994]: <-- HDLC<23:FF C0 02 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04>
Sep 16 16:10:09.65: [24994]: <-- data [23]
Sep 16 16:10:09.65: [24994]: <-- data [2]
Sep 16 16:10:10.47: [24994]: --> [7]
Sep 16 16:10:10.47: [24994]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18>
Sep 16 16:10:10.47: [24994]: <-- data [13]
Sep 16 16:10:10.47: [24994]: <-- data [2]
Sep 16 16:10:11.05: [24994]: --> [2]
Sep 16 16:10:11.05: [24994]: <-- [9:AT+FRH=3\r]
Sep 16 16:10:11.66: [24994]: --> [7]
Sep 16 16:10:16.70: [24994]: --> [2:10 03]
Sep 16 16:10:16.70: [24994]: --> [10:NO CARRIER]
Sep 16 16:10:16.70: [24994]: MODEM No carrier
Sep 16 16:10:16.70: [24994]: <-- [9:AT+FRS=7\r]
Sep 16 16:10:16.70: [24994]: --> [5]
Sep 16 16:10:16.70: [24994]: MODEM Command error
Sep 16 16:10:16.70: [24994]: DELAY 1500 ms
Sep 16 16:10:18.20: [24994]: <-- [9:AT+FTH=3\r]
Sep 16 16:10:18.20: [24994]: --> [5]
Sep 16 16:10:18.20: [24994]: RECV FAX: RSPREC error/got EOT
Sep 16 16:10:18.20: [24994]: RECV FAX: end
Sep 16 16:10:18.20: [24994]: SESSION END

I tried to look this error log in Hylafax community site but found nothing. BTW, do you happen to know how to send error logs to Hylafax community moderators?


Best regards,


Jessie
 

cmrahman

Joined
Jul 21, 2008
Messages
47
Likes
0
Points
0
#10
I have the same problem. after upgrading to 1.5.2 from 1.3.x, I am getting this error. I don't have sip trunk. I have Sangoma 200 card with 6 pot lines. So no sip issue in between. The call received via pstn and the server should receive fax via iaxmodem and email to the end user. Do we need to reinstall iaxmodem to make this work? any config file need to be fixed?

Any clue?

Thanks
CM
 

jgutierrez

Joined
Feb 28, 2008
Messages
5,737
Likes
0
Points
0
#11
Try to upgrade again your Elastix (all packages), from 1.5.2 to 1.5.2-3
Execute:
yum update
 

cmrahman

Joined
Jul 21, 2008
Messages
47
Likes
0
Points
0
#12
I am on 1.5.2-2.3. Do i need to rerun the upgrade again?

Thanks
 

cmrahman

Joined
Jul 21, 2008
Messages
47
Likes
0
Points
0
#13
I have solved the problem. It is Sangoma Card that caused it. When you configure the dahdi, there is a option where it ask for if i want the card to HW detect fax and dtmf. If you put yes, that will brake both. Just put NO to both of those option.

Thanks
 

Members online

No members online now.

Latest posts

Forum statistics

Threads
30,914
Messages
130,919
Members
17,590
Latest member
johneldc90
Top