Can't send/recv over SIP Trunk

Led
2010-11-30
2013-04-05
  • Led
    Led
    2010-11-30

    Hi,
    i have troubles in sending/receiving faxes over a Patton Smartnode 4960 SIP trunk (over LAN). The IAXmodem is connected to hyla and Asterisk. I tried sending to another IAXmodem in the same domain and everything works well. I was pretty sure it was a Patton configuration issue, but when trying connecting an analog Fax by an ATA i could sending faxes as well. I'm using alaw codec, i've already try slin and ulaw with no luck: modem always disconnect after speed training faliure.
    Here's the error log, any ideas?
    Nov 29 12:01:01.93: : SESSION BEGIN 000000118 39******79
    Nov 29 12:01:01.93: : HylaFAX ™ Version 6.0.4
    Nov 29 12:01:01.93: : SEND FAX: JOB 42 DEST **79 COMMID 000000118 DEVICE '/dev/ttyIAX4' FROM 'luca ***** <luca.*******@mydomain.it>' USER luca.******-**06
    Nov 29 12:01:01.93: : STATE CHANGE: RUNNING -> SENDING
    Nov 29 12:01:01.93: : <-
    Nov 29 12:01:01.94: : ->
    Nov 29 12:01:01.94: : MODEM set XON/XOFF/FLUSH: input ignored, output disabled
    Nov 29 12:01:01.94: : DIAL **79
    Nov 29 12:01:01.94: : <-
    Nov 29 12:01:24.86: : ->
    Nov 29 12:01:26.35: : -> HDLC<25:FF C0 04 00 00 A4 00 00 00 49 08 B2 40 00 1A 00 14 11 05 01 01 8D 06 99 ED>
    Nov 29 12:01:26.35: : ->
    Nov 29 12:01:26.35: : REMOTE NSF "00 00 25 00 00 00 92 10 4D 02 00 58 00 28 88 A0 80 80 B1 60"
    Nov 29 12:01:26.35: : NSF remote fax equipment: Ricoh
    Nov 29 12:01:26.35: : <-
    Nov 29 12:01:26.91: : ->
    Nov 29 12:01:26.91: : -> HDLC<20:FF C0 04 00 00 A4 80 F2 72 92 42 4A AA FA 6A 92 72 AA 32 42>
    Nov 29 12:01:26.91: : ->
    Nov 29 12:01:26.91: : REMOTE NSF "00 00 25 01 4F 4E 49 42 52 55 5F 56 49 4E 55"
    Nov 29 12:01:26.91: : NSF remote fax equipment: Ricoh
    Nov 29 12:01:26.91: : NSF remote station ID: "UNIV_*****"
    Nov 29 12:01:26.91: : <-
    Nov 29 12:01:27.64: : ->
    Nov 29 12:01:27.64: : -> HDLC<25:FF C0 02 9C EC 4C AC 0C CC 4C 4C EC 0C 9C CC 0C 0C 04 04 04 04 04 04 FD D9>
    Nov 29 12:01:27.64: : ->
    Nov 29 12:01:27.64: : REMOTE CSI "0********79"
    Nov 29 12:01:27.64: : <-
    Nov 29 12:01:28.06: : ->
    Nov 29 12:01:28.06: : -> HDLC<15:FF C8 01 04 77 55 23 01 19 C1 01 01 06 BC B7>
    Nov 29 12:01:28.14: : ->
    Nov 29 12:01:28.14: : REMOTE best rate 33600 bit/s
    Nov 29 12:01:28.14: : REMOTE max A3 page width (303 mm)
    Nov 29 12:01:28.14: : REMOTE max unlimited page length
    Nov 29 12:01:28.14: : REMOTE best vres 200 x 200 dpi
    Nov 29 12:01:28.14: : REMOTE format support: MH, MR, MMR, JBIG
    Nov 29 12:01:28.14: : REMOTE supports T.30 Annex A, 256-byte ECM
    Nov 29 12:01:28.14: : REMOTE best 10 ms/scanline
    Nov 29 12:01:28.14: : USE 9600 bit/s
    Nov 29 12:01:28.14: : SEND file "docq/doc42.pdf;70"
    Nov 29 12:01:28.14: : USE A4 page width (215 mm)
    Nov 29 12:01:28.14: : USE unlimited page length
    Nov 29 12:01:28.14: : USE 3.85 line/mm
    Nov 29 12:01:28.14: : USE 2-D MR
    Nov 29 12:01:28.14: : USE 10 ms/scanline
    Nov 29 12:01:28.14: : SEND training at v.29 9600 bit/s
    Nov 29 12:01:28.14: : <-
    Nov 29 12:01:28.19: : ->
    Nov 29 12:01:28.19: : <-
    Nov 29 12:01:28.21: : ->
    Nov 29 12:01:28.21: : <- HDLC<23:FF C0 C2 46 4E AA 96 76 AA 04 1E 86 62 04 F6 96 5E 96 6E 4E A6 CA 04>
    Nov 29 12:01:28.21: : <- data
    Nov 29 12:01:28.21: : <- data
    Nov 29 12:01:29.61: : ->
    Nov 29 12:01:29.61: : <- HDLC<6:FF C8 C1 00 61 14>
    Nov 29 12:01:29.61: : <- data
    Nov 29 12:01:29.61: : <- data
    Nov 29 12:01:29.94: : ->
    Nov 29 12:01:29.94: : <-
    Nov 29 12:01:30.02: : ->
    Nov 29 12:01:30.02: : MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
    Nov 29 12:01:30.02: : <-
    Nov 29 12:01:30.04: : ->
    Nov 29 12:01:30.04: : DELAY 400 ms
    Nov 29 12:01:30.44: : <- data
    Nov 29 12:01:30.44: : <- data
    Nov 29 12:01:30.44: : <- data
    Nov 29 12:01:31.81: : ->
    Nov 29 12:01:31.81: : MODEM set XON/XOFF/DRAIN: input ignored, output disabled
    Nov 29 12:01:31.81: : <-
    Nov 29 12:01:33.79: : ->
    Nov 29 12:01:34.76: : -> HDLC<5:FF C8 22 67 DD>
    Nov 29 12:01:34.83: : ->
    Nov 29 12:01:34.83: : SEND training at v.29 7200 bit/s
    Nov 29 12:01:34.83: : <-
    Nov 29 12:01:34.90: : ->
    Nov 29 12:01:34.90: : <-
    Nov 29 12:01:34.91: : ->
    Nov 29 12:01:34.91: : <- HDLC<23:FF C0 C2 46 4E AA 96 76 AA 04 1E 86 62 04 F6 96 5E 96 6E 4E A6 CA 04>
    Nov 29 12:01:34.91: : <- data
    Nov 29 12:01:34.91: : <- data
    Nov 29 12:01:36.21: : ->
    Nov 29 12:01:36.21: : <- HDLC<6:FF C8 C1 00 71 14>
    Nov 29 12:01:36.21: : <- data
    Nov 29 12:01:36.21: : <- data
    Nov 29 12:01:36.50: : ->
    Nov 29 12:01:36.50: : <-
    Nov 29 12:01:36.59: : ->
    Nov 29 12:01:36.59: : MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
    Nov 29 12:01:36.59: : <-
    Nov 29 12:01:36.60: : ->
    Nov 29 12:01:36.60: : DELAY 400 ms
    Nov 29 12:01:37.00: : <- data
    Nov 29 12:01:37.00: : <- data
    Nov 29 12:01:37.00: : <- data
    Nov 29 12:01:38.27: : ->
    Nov 29 12:01:38.27: : MODEM set XON/XOFF/DRAIN: input ignored, output disabled
    Nov 29 12:01:38.27: : <-
    Nov 29 12:01:40.17: : ->
    Nov 29 12:01:41.13: : -> HDLC<5:FF C8 22 67 DD>
    Nov 29 12:01:41.21: : ->
    Nov 29 12:01:41.21: : SEND training at v.27ter 4800 bit/s
    Nov 29 12:01:41.21: : <-
    Nov 29 12:01:41.26: : ->
    Nov 29 12:01:41.26: : <-
    Nov 29 12:01:41.27: : ->
    Nov 29 12:01:41.27: : <- HDLC<23:FF C0 C2 46 4E AA 96 76 AA 04 1E 86 62 04 F6 96 5E 96 6E 4E A6 CA 04>
    Nov 29 12:01:41.27: : <- data
    Nov 29 12:01:41.27: : <- data
    Nov 29 12:01:42.47: : ->
    Nov 29 12:01:42.47: : <- HDLC<6:FF C8 C1 00 51 14>
    Nov 29 12:01:42.47: : <- data
    Nov 29 12:01:42.47: : <- data
    Nov 29 12:01:42.75: : ->
    Nov 29 12:01:42.75: : <-
    Nov 29 12:01:42.83: : ->
    Nov 29 12:01:42.83: : MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
    Nov 29 12:01:42.83: : <-
    Nov 29 12:01:42.84: : ->
    Nov 29 12:01:42.84: : DELAY 400 ms
    Nov 29 12:01:43.24: : <- data
    Nov 29 12:01:43.24: : <- data
    Nov 29 12:01:44.58: : ->
    Nov 29 12:01:44.58: : MODEM set XON/XOFF/DRAIN: input ignored, output disabled
    Nov 29 12:01:44.58: : <-
    Nov 29 12:01:47.68: : ->
    Nov 29 12:01:47.68: : MODEM <Empty line>
    Nov 29 12:01:47.68: : MODEM TIMEOUT: waiting for v.21 carrier
    Nov 29 12:01:47.68: : <- data
    Nov 29 12:01:47.69: : ->
    Nov 29 12:01:47.69: : <-
    Nov 29 12:01:47.71: : ->
    Nov 29 12:01:47.71: : SEND training at v.27ter fallback mode 2400 bit/s
    Nov 29 12:01:47.71: : <-
    Nov 29 12:01:47.72: : ->
    Nov 29 12:01:47.72: : <- HDLC<23:FF C0 C2 46 4E AA 96 76 AA 04 1E 86 62 04 F6 96 5E 96 6E 4E A6 CA 04>
    Nov 29 12:01:47.72: : <- data
    Nov 29 12:01:47.72: : <- data
    Nov 29 12:01:48.84: : ->
    Nov 29 12:01:48.84: : <- HDLC<6:FF C8 C1 00 41 14>
    Nov 29 12:01:48.84: : <- data
    Nov 29 12:01:48.84: : <- data
    Nov 29 12:01:49.10: : ->
    Nov 29 12:01:49.10: : <-
    Nov 29 12:01:49.17: : ->
    Nov 29 12:01:49.17: : MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
    Nov 29 12:01:49.17: : <-
    Nov 29 12:01:49.18: : ->
    Nov 29 12:01:49.18: : DELAY 400 ms
    Nov 29 12:01:49.58: : <- data
    Nov 29 12:01:49.58: : <- data
    Nov 29 12:01:50.97: : ->
    Nov 29 12:01:50.97: : MODEM set XON/XOFF/DRAIN: input ignored, output disabled
    Nov 29 12:01:50.97: : <-
    Nov 29 12:01:54.07: : ->
    Nov 29 12:01:54.07: : MODEM <Empty line>
    Nov 29 12:01:54.07: : MODEM TIMEOUT: waiting for v.21 carrier
    Nov 29 12:01:54.07: : <- data
    Nov 29 12:01:54.08: : ->
    Nov 29 12:01:54.08: : <-
    Nov 29 12:01:54.09: : ->
    Nov 29 12:01:54.09: : TRAINING failed
    Nov 29 12:01:54.09: : SEND: Giving up after 3 attempts to send same page "docq/doc42.pdf;70", dirnum 0
    Nov 29 12:01:54.09: : <-
    Nov 29 12:01:54.11: : ->
    Nov 29 12:01:54.11: : <- HDLC<3:FF C8 DF>
    Nov 29 12:01:54.11: : <- data
    Nov 29 12:01:54.11: : <- data
    Nov 29 12:01:54.84: : ->
    Nov 29 12:01:54.84: : MODEM input buffering enabled
    Nov 29 12:01:55.84: : <-
    Nov 29 12:01:55.96: : ->
    Nov 29 12:01:55.96: : MODEM set DTR OFF
    Nov 29 12:01:55.96: : MODEM set baud rate: 0 baud (flow control unchanged)
    Nov 29 12:01:55.96: : STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
    Nov 29 12:01:55.96: : SESSION END

     
  • Led
    Led
    2010-11-30

    That's Ok: i read about it…But it shouldn't work even with ATAs  while i can send faxes over the same trunk with Analog Fax + ATA…How is this possible? Maybe a different Class1 implementation in IAXmodem?
    Thanks in advance
    Luca

     
  • Lee Howard
    Lee Howard
    2010-12-01

    Could it be that your ATA is performing T.38 through Asterisk to your SIP provider?  What version of IAXmodem are you using?

     
  • Led
    Led
    2010-12-01

    My ATA is not performing T.38, and no T.38 passtrough is configured on my Asterisk…I'm using IAXmodem 1.2.0, asterisk 1.6.2.11, hylafax 6.0.4.

     
  • Lee Howard
    Lee Howard
    2010-12-02

    Well, firstly, I can't vouch at all for HylaFAX 6.0.4.  I don't expect that it's doing much harm, but please understand that it's years behind to where I have developed HylaFAX+ 5.4.3.  It's unlikely, but it's certainly possible that a switch to HylaFAX+ 5.4.3 could make some difference.  But, as I expect that's not likely to resolve things, after you do that the only way to proceed is to make a recording from the receiver's end of the call… and then examine the recording for an explanation as to why it's not working.