HylaFax not receiving faxes - new setup

Discussion in 'General' started by mismatrix, Aug 16, 2009.

  1. mismatrix

    Joined:
    Aug 16, 2009
    Messages:
    11
    Likes Received:
    0
    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.
     
  2. jgutierrez

    Joined:
    Feb 28, 2008
    Messages:
    5,737
    Likes Received:
    0
    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
     
  3. mismatrix

    Joined:
    Aug 16, 2009
    Messages:
    11
    Likes Received:
    0
    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.
     
  4. maumar

    Joined:
    Sep 12, 2008
    Messages:
    80
    Likes Received:
    0
    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 ;)
     
  5. maumar

    Joined:
    Sep 12, 2008
    Messages:
    80
    Likes Received:
    0
    i have alaw as codec, too
     
  6. jessie

    Joined:
    Sep 17, 2008
    Messages:
    124
    Likes Received:
    0
    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
     
  7. jessie

    Joined:
    Sep 17, 2008
    Messages:
    124
    Likes Received:
    0
    Here is the diagram... [​IMG]
     
  8. ramoncio

    Joined:
    May 12, 2010
    Messages:
    1,663
    Likes Received:
    0
    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.
     
  9. jessie

    Joined:
    Sep 17, 2008
    Messages:
    124
    Likes Received:
    0
    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
     
  10. cmrahman

    Joined:
    Jul 21, 2008
    Messages:
    47
    Likes Received:
    0
    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
     
  11. jgutierrez

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

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

    Thanks
     
  13. cmrahman

    Joined:
    Jul 21, 2008
    Messages:
    47
    Likes Received:
    0
    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
     

Share This Page