Fax Not working after upgrade

General Help
1

I was a few version back on FreePBX so when through the setups of updating modules and restarting and updating and restarting. (The main reason was it was suppose to resolve Google trunks disconnecting)

It seems after that my Avant fax server is no longer making or receiving faxes. This use to work perfect over my google trunk and was install originally with the "incrediblefax11.sh " script.

How can I start to troubleshoot this?

Steps taken so far:

-Have fax call me…i get modem sounds
-Call fax number…i get modem sounds

(silly i know but it’s a start)

The errors I am getting are mostly:

“requeued No receiver protocol (T.30 T1 timeout)”

Current server details:
? ? PIAF Installed Version = 2.0.6.2 under VMWARE ? ? FreePBX Version = 2.11.0.8 ? ? Running Asterisk Version = 1.8.8.0 ? ? Asterisk Source Version = 1.8.8.0 ? ? Dahdi Source Version = 2.6.0+2.6.0 ? ? Libpri Source Version = 1.4.12 ? ? Operating System = CentOS release 6.2 (Final) ? ? Kernel Version = 2.6.32-220.2.1.el6.i686 - 32 Bit ? ? Incredible PBX 3 Version = 3.0.3

Thanks!

2

Avantfax is not the fax server the underlying hylafax is, debug at that hylafax level.

3

Does the below give more details or do I need to find a Hylafax forum for this kind of debugging?

Jul 15 16:58:31.43: [ 1905]: SESSION BEGIN 000000189 18005551212
Jul 15 16:58:31.43: [ 1905]: HylaFAX ™ Version 6.0.5
Jul 15 16:58:31.43: [ 1905]: CallID: “” “” “” "s"
Jul 15 16:58:31.43: [ 1905]: DynamicConfig: bin/dynconf.php “/dev/ttyIAX1” “” “” “” "s"
Jul 15 16:58:31.57: [ 1905]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Jul 15 16:58:31.57: [ 1905]: <-- [4:ATA\r]
Jul 15 16:58:35.29: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:35.29: [ 1905]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyIAX1’
Jul 15 16:58:35.29: [ 1905]: STATE CHANGE: ANSWERING -> RECEIVING
Jul 15 16:58:35.29: [ 1905]: RECV FAX: begin
Jul 15 16:58:35.29: [ 1905]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:58:35.29: [ 1905]: <-- data [32]
Jul 15 16:58:35.29: [ 1905]: <-- data [2]
Jul 15 16:58:36.25: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:36.25: [ 1905]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:58:36.25: [ 1905]: <-- data [23]
Jul 15 16:58:36.25: [ 1905]: <-- data [2]
Jul 15 16:58:36.99: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:36.99: [ 1905]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:58:36.99: [ 1905]: <-- data [13]
Jul 15 16:58:36.99: [ 1905]: <-- data [2]
Jul 15 16:58:37.57: [ 1905]: --> [2:OK]
Jul 15 16:58:37.57: [ 1905]: <-- [9:AT+FRH=3\r]
Jul 15 16:58:38.57: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:39.43: [ 1905]: --> HDLC<5:FF C8 D8 29 88>
Jul 15 16:58:39.49: [ 1905]: --> [2:OK]
Jul 15 16:58:39.49: [ 1905]: RECV recv CRP (command repeat)
Jul 15 16:58:39.49: [ 1905]: <-- [9:AT+FTH=3\r]
Jul 15 16:58:39.51: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:39.51: [ 1905]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:58:39.51: [ 1905]: <-- data [32]
Jul 15 16:58:39.51: [ 1905]: <-- data [2]
Jul 15 16:58:41.29: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:41.29: [ 1905]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:58:41.29: [ 1905]: <-- data [23]
Jul 15 16:58:41.29: [ 1905]: <-- data [2]
Jul 15 16:58:42.03: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:42.03: [ 1905]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:58:42.03: [ 1905]: <-- data [13]
Jul 15 16:58:42.03: [ 1905]: <-- data [2]
Jul 15 16:58:42.61: [ 1905]: --> [2:OK]
Jul 15 16:58:42.61: [ 1905]: <-- [9:AT+FRH=3\r]
Jul 15 16:58:43.61: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:44.47: [ 1905]: --> HDLC<5:FF C8 D8 29 88>
Jul 15 16:58:44.53: [ 1905]: --> [2:OK]
Jul 15 16:58:44.53: [ 1905]: RECV recv CRP (command repeat)
Jul 15 16:58:44.53: [ 1905]: <-- [9:AT+FTH=3\r]
Jul 15 16:58:44.55: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:44.55: [ 1905]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:58:44.55: [ 1905]: <-- data [32]
Jul 15 16:58:44.55: [ 1905]: <-- data [2]
Jul 15 16:58:46.33: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:46.33: [ 1905]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:58:46.33: [ 1905]: <-- data [23]
Jul 15 16:58:46.33: [ 1905]: <-- data [2]
Jul 15 16:58:47.07: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:47.07: [ 1905]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:58:47.07: [ 1905]: <-- data [13]
Jul 15 16:58:47.07: [ 1905]: <-- data [2]
Jul 15 16:58:47.65: [ 1905]: --> [2:OK]
Jul 15 16:58:47.65: [ 1905]: <-- [9:AT+FRH=3\r]
Jul 15 16:58:48.65: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:49.51: [ 1905]: --> HDLC<5:FF C8 D8 29 88>
Jul 15 16:58:49.57: [ 1905]: --> [2:OK]
Jul 15 16:58:49.57: [ 1905]: RECV recv CRP (command repeat)
Jul 15 16:58:49.57: [ 1905]: <-- [9:AT+FTH=3\r]
Jul 15 16:58:49.59: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:49.59: [ 1905]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:58:49.59: [ 1905]: <-- data [32]
Jul 15 16:58:49.59: [ 1905]: <-- data [2]
Jul 15 16:58:51.37: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:51.37: [ 1905]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:58:51.37: [ 1905]: <-- data [23]
Jul 15 16:58:51.37: [ 1905]: <-- data [2]
Jul 15 16:58:52.11: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:52.11: [ 1905]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:58:52.11: [ 1905]: <-- data [13]
Jul 15 16:58:52.11: [ 1905]: <-- data [2]
Jul 15 16:58:52.69: [ 1905]: --> [2:OK]
Jul 15 16:58:52.69: [ 1905]: <-- [9:AT+FRH=3\r]
Jul 15 16:58:59.69: [ 1905]: --> [0:]
Jul 15 16:58:59.69: [ 1905]: MODEM
Jul 15 16:58:59.69: [ 1905]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 15 16:58:59.69: [ 1905]: <-- data [1]
Jul 15 16:58:59.71: [ 1905]: --> [2:OK]
Jul 15 16:58:59.71: [ 1905]: <-- [9:AT+FTH=3\r]
Jul 15 16:58:59.73: [ 1905]: --> [7:CONNECT]
Jul 15 16:58:59.73: [ 1905]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:58:59.73: [ 1905]: <-- data [32]
Jul 15 16:58:59.73: [ 1905]: <-- data [2]
Jul 15 16:59:01.51: [ 1905]: --> [7:CONNECT]
Jul 15 16:59:01.51: [ 1905]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:59:01.51: [ 1905]: <-- data [23]
Jul 15 16:59:01.51: [ 1905]: <-- data [2]
Jul 15 16:59:02.25: [ 1905]: --> [7:CONNECT]
Jul 15 16:59:02.25: [ 1905]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:59:02.25: [ 1905]: <-- data [13]
Jul 15 16:59:02.25: [ 1905]: <-- data [2]
Jul 15 16:59:02.83: [ 1905]: --> [2:OK]
Jul 15 16:59:02.83: [ 1905]: <-- [9:AT+FRH=3\r]
Jul 15 16:59:03.83: [ 1905]: --> [7:CONNECT]
Jul 15 16:59:04.53: [ 1905]: --> [10:NO CARRIER]
Jul 15 16:59:04.53: [ 1905]: MODEM No carrier
Jul 15 16:59:04.53: [ 1905]: <-- [10:AT+FRS=21\r]
Jul 15 16:59:04.73: [ 1905]: --> [2:OK]
Jul 15 16:59:04.73: [ 1905]: <-- [9:AT+FTH=3\r]
Jul 15 16:59:04.75: [ 1905]: --> [7:CONNECT]
Jul 15 16:59:04.75: [ 1905]: <-- HDLC<3:FF C8 58>
Jul 15 16:59:04.75: [ 1905]: <-- data [3]
Jul 15 16:59:04.75: [ 1905]: <-- data [2]
Jul 15 16:59:05.83: [ 1905]: --> [2:OK]
Jul 15 16:59:05.83: [ 1905]: RECV send CRP (command repeat)
Jul 15 16:59:05.83: [ 1905]: <-- [9:AT+FRH=3\r]
Jul 15 16:59:06.83: [ 1905]: --> [7:CONNECT]
Jul 15 16:59:07.53: [ 1905]: --> [10:NO CARRIER]
Jul 15 16:59:07.53: [ 1905]: MODEM No carrier
Jul 15 16:59:07.53: [ 1905]: <-- [10:AT+FRS=21\r]
Jul 15 16:59:07.73: [ 1905]: --> [2:OK]
Jul 15 16:59:07.73: [ 1905]: <-- [9:AT+FTH=3\r]
Jul 15 16:59:07.75: [ 1905]: --> [7:CONNECT]
Jul 15 16:59:07.75: [ 1905]: <-- HDLC<3:FF C8 58>
Jul 15 16:59:07.75: [ 1905]: <-- data [3]
Jul 15 16:59:07.75: [ 1905]: <-- data [2]
Jul 15 16:59:08.83: [ 1905]: --> [2:OK]
Jul 15 16:59:08.83: [ 1905]: RECV send CRP (command repeat)
Jul 15 16:59:08.83: [ 1905]: <-- [9:AT+FRH=3\r]
Jul 15 16:59:09.83: [ 1905]: --> [7:CONNECT]
Jul 15 16:59:10.53: [ 1905]: --> [10:NO CARRIER]
Jul 15 16:59:10.53: [ 1905]: MODEM No carrier
Jul 15 16:59:10.53: [ 1905]: <-- [10:AT+FRS=21\r]
Jul 15 16:59:10.73: [ 1905]: --> [2:OK]
Jul 15 16:59:10.73: [ 1905]: <-- [9:AT+FTH=3\r]
Jul 15 16:59:10.75: [ 1905]: --> [7:CONNECT]
Jul 15 16:59:10.75: [ 1905]: <-- HDLC<3:FF C8 58>
Jul 15 16:59:10.75: [ 1905]: <-- data [3]
Jul 15 16:59:10.75: [ 1905]: <-- data [2]
Jul 15 16:59:11.83: [ 1905]: --> [2:OK]
Jul 15 16:59:11.83: [ 1905]: RECV send CRP (command repeat)
Jul 15 16:59:11.83: [ 1905]: <-- [9:AT+FRH=3\r]
Jul 15 16:59:12.69: [ 1905]: --> [7:CONNECT]
Jul 15 16:59:13.39: [ 1905]: --> [10:NO CARRIER]
Jul 15 16:59:13.39: [ 1905]: MODEM No carrier
Jul 15 16:59:13.39: [ 1905]: <-- [9:AT+FTH=3\r]
Jul 15 16:59:13.41: [ 1905]: --> [7:CONNECT]
Jul 15 16:59:13.41: [ 1905]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:59:13.41: [ 1905]: <-- data [32]
Jul 15 16:59:13.41: [ 1905]: <-- data [2]
Jul 15 16:59:13.75: [ 1905]: --> [10:NO CARRIER]
Jul 15 16:59:13.75: [ 1905]: MODEM No carrier
Jul 15 16:59:13.75: [ 1905]: <-- [9:AT+FTH=3\r]
Jul 15 16:59:13.75: [ 1905]: --> [5:ERROR]
Jul 15 16:59:13.75: [ 1905]: RECV FAX: RSPREC error/got EOT
Jul 15 16:59:13.75: [ 1905]: RECV FAX: end
Jul 15 16:59:13.75: [ 1905]: RSPREC error/got EOT
Jul 15 16:59:13.75: [ 1905]: SESSION END
root@pbx:/etc/hylafax/log $ more c000000188
Jul 15 16:58:27.09: [ 3577]: SESSION BEGIN 000000188 18009706823299
Jul 15 16:58:27.09: [ 3577]: HylaFAX ™ Version 6.0.5
Jul 15 16:58:27.09: [ 3577]: SEND FAX: JOB 76 DEST 970-682-3299 COMMID 000000188 DEVICE ‘/dev/ttyIAX0’ FROM ‘fax [email protected]’ USER maint
Jul 15 16:58:27.09: [ 3577]: STATE CHANGE: RUNNING -> SENDING
Jul 15 16:58:27.09: [ 3577]: <-- [12:AT+FCLASS=1\r]
Jul 15 16:58:27.09: [ 3577]: --> [2:OK]
Jul 15 16:58:27.09: [ 3577]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Jul 15 16:58:27.09: [ 3577]: DIAL
Jul 15 16:58:27.09: [ 3577]: <-- [15:ATDT9706823299\r]
Jul 15 16:58:34.87: [ 3577]: --> [7:CONNECT]
Jul 15 16:58:35.38: [ 3577]: --> [4:9E 7D 10 03]
Jul 15 16:58:35.38: [ 3577]: --> [5:ERROR]
Jul 15 16:58:35.38: [ 3577]: MODEM Command error
Jul 15 16:58:35.38: [ 3577]: FCS error
Jul 15 16:58:35.38: [ 3577]: <-- [10:AT+FRS=21\r]
Jul 15 16:58:38.07: [ 3577]: --> [2:OK]
Jul 15 16:58:38.08: [ 3577]: <-- [9:AT+FTH=3\r]
Jul 15 16:58:38.09: [ 3577]: --> [7:CONNECT]
Jul 15 16:58:38.10: [ 3577]: <-- HDLC<3:FF C8 D8>
Jul 15 16:58:38.10: [ 3577]: <-- data [3]
Jul 15 16:58:38.10: [ 3577]: <-- data [2]
Jul 15 16:58:39.18: [ 3577]: --> [2:OK]
Jul 15 16:58:39.18: [ 3577]: SEND send CRP (command repeat)
Jul 15 16:58:39.18: [ 3577]: <-- [9:AT+FRH=3\r]
Jul 15 16:58:39.95: [ 3577]: --> [7:CONNECT]
Jul 15 16:58:40.04: [ 3577]: --> [4:3F 00 10 03]
Jul 15 16:58:40.04: [ 3577]: --> [5:ERROR]
Jul 15 16:58:40.04: [ 3577]: MODEM Command error
Jul 15 16:58:40.04: [ 3577]: FCS error
Jul 15 16:58:40.04: [ 3577]: <-- [10:AT+FRS=21\r]
Jul 15 16:58:43.11: [ 3577]: --> [2:OK]
Jul 15 16:58:43.12: [ 3577]: <-- [9:AT+FTH=3\r]
Jul 15 16:58:43.13: [ 3577]: --> [7:CONNECT]
Jul 15 16:58:43.14: [ 3577]: <-- HDLC<3:FF C8 D8>
Jul 15 16:58:43.14: [ 3577]: <-- data [3]
Jul 15 16:58:43.14: [ 3577]: <-- data [2]
Jul 15 16:58:44.21: [ 3577]: --> [2:OK]
Jul 15 16:58:44.22: [ 3577]: SEND send CRP (command repeat)
Jul 15 16:58:44.22: [ 3577]: <-- [9:AT+FRH=3\r]
Jul 15 16:58:44.99: [ 3577]: --> [7:CONNECT]
Jul 15 16:58:46.65: [ 3577]: --> HDLC<35:FF C2 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 6C 74 0C 74 AC 81 46 1A>
Jul 15 16:58:46.66: [ 3577]: --> [5:ERROR]
Jul 15 16:58:46.66: [ 3577]: MODEM Command error
Jul 15 16:58:46.66: [ 3577]: FCS error
Jul 15 16:58:46.66: [ 3577]: <-- [10:AT+FRS=21\r]
Jul 15 16:58:48.16: [ 3577]: --> [2:OK]
Jul 15 16:58:48.16: [ 3577]: <-- [9:AT+FTH=3\r]
Jul 15 16:58:48.17: [ 3577]: --> [7:CONNECT]
Jul 15 16:58:48.18: [ 3577]: <-- HDLC<3:FF C8 D8>
Jul 15 16:58:48.18: [ 3577]: <-- data [3]
Jul 15 16:58:48.18: [ 3577]: <-- data [2]
Jul 15 16:58:49.25: [ 3577]: --> [2:OK]
Jul 15 16:58:49.26: [ 3577]: SEND send CRP (command repeat)
Jul 15 16:58:49.26: [ 3577]: <-- [9:AT+FRH=3\r]
Jul 15 16:58:50.04: [ 3577]: --> [7:CONNECT]
Jul 15 16:58:51.67: [ 3577]: --> HDLC<34:FF C0 04 92 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 6C B0 0C 74 AC 81 46>
Jul 15 16:58:51.68: [ 3577]: --> [5:ERROR]
Jul 15 16:58:51.68: [ 3577]: MODEM Command error
Jul 15 16:58:51.68: [ 3577]: FCS error
Jul 15 16:58:51.68: [ 3577]: <-- [9:AT+FRS=7\r]
Jul 15 16:58:52.47: [ 3577]: ABORT: job abort requested: Send aborted due to operator intervention
Jul 15 16:58:53.06: [ 3577]: --> [2:OK]
Jul 15 16:58:53.06: [ 3577]: <-- [9:AT+FRH=3\r]
Jul 15 16:59:00.58: [ 3577]: --> [7:CONNECT]
Jul 15 16:59:01.81: [ 3577]: --> HDLC<34: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 DA 04 6C 74 0C 74 AC 81 46>
Jul 15 16:59:01.82: [ 3577]: --> [5:ERROR]
Jul 15 16:59:01.82: [ 3577]: MODEM Command error
Jul 15 16:59:01.82: [ 3577]: FCS error
Jul 15 16:59:01.82: [ 3577]: <-- [10:AT+FRS=21\r]
Jul 15 16:59:03.34: [ 3577]: --> [2:OK]
Jul 15 16:59:03.34: [ 3577]: <-- [9:AT+FTH=3\r]
Jul 15 16:59:03.35: [ 3577]: --> [7:CONNECT]
Jul 15 16:59:03.35: [ 3577]: <-- HDLC<3:FF C8 D8>
Jul 15 16:59:03.36: [ 3577]: <-- data [2]
Jul 15 16:59:03.36: [ 3577]: <-- [9:AT+FRS=7\r]
Jul 15 16:59:04.21: [ 3577]: --> [2:OK]
Jul 15 16:59:04.22: [ 3577]: <-- [9:AT+FRH=3\r]
Jul 15 16:59:05.25: [ 3577]: --> [7:CONNECT]
Jul 15 16:59:05.89: [ 3577]: --> [4:9E 00 10 03]
Jul 15 16:59:05.90: [ 3577]: --> [5:ERROR]
Jul 15 16:59:05.90: [ 3577]: MODEM Command error
Jul 15 16:59:05.90: [ 3577]: FCS error
Jul 15 16:59:05.90: [ 3577]: <-- [10:AT+FRS=21\r]
Jul 15 16:59:06.33: [ 3577]: --> [2:OK]
Jul 15 16:59:06.34: [ 3577]: <-- [9:AT+FTH=3\r]
Jul 15 16:59:06.35: [ 3577]: --> [7:CONNECT]
Jul 15 16:59:06.35: [ 3577]: <-- HDLC<3:FF C8 D8>
Jul 15 16:59:06.36: [ 3577]: <-- data [2]
Jul 15 16:59:06.36: [ 3577]: <-- [9:AT+FRS=7\r]
Jul 15 16:59:07.22: [ 3577]: --> [2:OK]
Jul 15 16:59:07.22: [ 3577]: <-- [9:AT+FRH=3\r]
Jul 15 16:59:08.20: [ 3577]: --> [7:CONNECT]
Jul 15 16:59:08.67: [ 3577]: --> [4:3F 00 10 03]
Jul 15 16:59:08.68: [ 3577]: --> [5:ERROR]
Jul 15 16:59:08.68: [ 3577]: MODEM Command error
Jul 15 16:59:08.68: [ 3577]: FCS error
Jul 15 16:59:08.68: [ 3577]: <-- [10:AT+FRS=21\r]
Jul 15 16:59:09.33: [ 3577]: --> [2:OK]
Jul 15 16:59:09.34: [ 3577]: <-- [9:AT+FTH=3\r]
Jul 15 16:59:09.35: [ 3577]: --> [7:CONNECT]
Jul 15 16:59:09.36: [ 3577]: <-- HDLC<3:FF C8 D8>
Jul 15 16:59:09.36: [ 3577]: <-- data [2]
Jul 15 16:59:09.36: [ 3577]: <-- [9:AT+FRS=7\r]
Jul 15 16:59:10.22: [ 3577]: --> [2:OK]
Jul 15 16:59:10.22: [ 3577]: <-- [9:AT+FRH=3\r]
Jul 15 16:59:11.19: [ 3577]: --> [7:CONNECT]
Jul 15 16:59:12.05: [ 3577]: --> HDLC<5:FF C8 58 B8 00>
Jul 15 16:59:12.11: [ 3577]: --> [2:OK]
Jul 15 16:59:12.12: [ 3577]: COMREC invalid command received/no DIS or DTC
Jul 15 16:59:12.12: [ 3577]: SEND FAILED: JOB 76 DEST 970-682-3299 ERR [125] COMREC invalid command received/no DIS or DTC
Jul 15 16:59:12.12: [ 3577]: <-- [9:AT+FRS=7\r]
Jul 15 16:59:12.19: [ 3577]: --> [2:OK]
Jul 15 16:59:12.20: [ 3577]: <-- [9:AT+FTH=3\r]
Jul 15 16:59:12.21: [ 3577]: --> [7:CONNECT]
Jul 15 16:59:12.21: [ 3577]: <-- HDLC<3:FF C8 DF>
Jul 15 16:59:12.21: [ 3577]: <-- data [2]
Jul 15 16:59:12.22: [ 3577]: MODEM input buffering enabled
Jul 15 16:59:12.22: [ 3577]: SEND FAILED: JOB 76 DEST 970-682-3299 ERR [345] Call aborted by user
Jul 15 16:59:13.22: [ 3577]: <-- [5:ATH0\r]
Jul 15 16:59:13.34: [ 3577]: --> [2:OK]
Jul 15 16:59:13.34: [ 3577]: MODEM set DTR OFF
Jul 15 16:59:13.34: [ 3577]: MODEM set baud rate: 0 baud (flow control unchanged)
Jul 15 16:59:13.34: [ 3577]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jul 15 16:59:13.34: [ 3577]: SESSION END
root@pbx:/etc/hylafax/log $ more c000000187
Jul 15 16:56:27.22: [ 1904]: SESSION BEGIN 000000187 18005551212
Jul 15 16:56:27.22: [ 1904]: HylaFAX ™ Version 6.0.5
Jul 15 16:56:27.22: [ 1904]: CallID: “” “” “” "s"
Jul 15 16:56:27.22: [ 1904]: DynamicConfig: bin/dynconf.php “/dev/ttyIAX2” “9707445646” “9707445646” “” "s"
Jul 15 16:56:27.36: [ 1904]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Jul 15 16:56:27.36: [ 1904]: <-- [4:ATA\r]
Jul 15 16:56:31.08: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:31.08: [ 1904]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyIAX2’
Jul 15 16:56:31.08: [ 1904]: STATE CHANGE: ANSWERING -> RECEIVING
Jul 15 16:56:31.08: [ 1904]: RECV FAX: begin
Jul 15 16:56:31.08: [ 1904]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:56:31.08: [ 1904]: <-- data [32]
Jul 15 16:56:31.08: [ 1904]: <-- data [2]
Jul 15 16:56:32.04: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:32.04: [ 1904]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:56:32.04: [ 1904]: <-- data [23]
Jul 15 16:56:32.04: [ 1904]: <-- data [2]
Jul 15 16:56:32.78: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:32.78: [ 1904]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:56:32.78: [ 1904]: <-- data [13]
Jul 15 16:56:32.78: [ 1904]: <-- data [2]
Jul 15 16:56:33.36: [ 1904]: --> [2:OK]
Jul 15 16:56:33.36: [ 1904]: <-- [9:AT+FRH=3\r]
Jul 15 16:56:34.26: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:35.16: [ 1904]: --> HDLC<5:FF C8 D8 29 88>
Jul 15 16:56:35.22: [ 1904]: --> [2:OK]
Jul 15 16:56:35.22: [ 1904]: RECV recv CRP (command repeat)
Jul 15 16:56:35.22: [ 1904]: <-- [9:AT+FTH=3\r]
Jul 15 16:56:35.24: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:35.24: [ 1904]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:56:35.24: [ 1904]: <-- data [32]
Jul 15 16:56:35.24: [ 1904]: <-- data [2]
Jul 15 16:56:37.02: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:37.02: [ 1904]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:56:37.02: [ 1904]: <-- data [23]
Jul 15 16:56:37.02: [ 1904]: <-- data [2]
Jul 15 16:56:37.76: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:37.76: [ 1904]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:56:37.76: [ 1904]: <-- data [13]
Jul 15 16:56:37.76: [ 1904]: <-- data [2]
Jul 15 16:56:38.34: [ 1904]: --> [2:OK]
Jul 15 16:56:38.34: [ 1904]: <-- [9:AT+FRH=3\r]
Jul 15 16:56:39.22: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:40.12: [ 1904]: --> HDLC<5:FF C8 D8 29 88>
Jul 15 16:56:40.18: [ 1904]: --> [2:OK]
Jul 15 16:56:40.18: [ 1904]: RECV recv CRP (command repeat)
Jul 15 16:56:40.18: [ 1904]: <-- [9:AT+FTH=3\r]
Jul 15 16:56:40.20: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:40.20: [ 1904]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:56:40.20: [ 1904]: <-- data [32]
Jul 15 16:56:40.20: [ 1904]: <-- data [2]
Jul 15 16:56:41.98: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:41.98: [ 1904]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:56:41.98: [ 1904]: <-- data [23]
Jul 15 16:56:41.98: [ 1904]: <-- data [2]
Jul 15 16:56:42.72: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:42.72: [ 1904]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:56:42.72: [ 1904]: <-- data [13]
Jul 15 16:56:42.72: [ 1904]: <-- data [2]
Jul 15 16:56:43.30: [ 1904]: --> [2:OK]
Jul 15 16:56:43.30: [ 1904]: <-- [9:AT+FRH=3\r]
Jul 15 16:56:44.18: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:45.08: [ 1904]: --> HDLC<5:FF C8 D8 29 88>
Jul 15 16:56:45.14: [ 1904]: --> [2:OK]
Jul 15 16:56:45.14: [ 1904]: RECV recv CRP (command repeat)
Jul 15 16:56:45.14: [ 1904]: <-- [9:AT+FTH=3\r]
Jul 15 16:56:45.16: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:45.16: [ 1904]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:56:45.16: [ 1904]: <-- data [32]
Jul 15 16:56:45.16: [ 1904]: <-- data [2]
Jul 15 16:56:46.94: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:46.94: [ 1904]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:56:46.94: [ 1904]: <-- data [23]
Jul 15 16:56:46.94: [ 1904]: <-- data [2]
Jul 15 16:56:47.68: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:47.68: [ 1904]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:56:47.68: [ 1904]: <-- data [13]
Jul 15 16:56:47.68: [ 1904]: <-- data [2]
Jul 15 16:56:48.26: [ 1904]: --> [2:OK]
Jul 15 16:56:48.26: [ 1904]: <-- [9:AT+FRH=3\r]
Jul 15 16:56:55.26: [ 1904]: --> [0:]
Jul 15 16:56:55.26: [ 1904]: MODEM
Jul 15 16:56:55.26: [ 1904]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 15 16:56:55.26: [ 1904]: <-- data [1]
Jul 15 16:56:55.28: [ 1904]: --> [2:OK]
Jul 15 16:56:55.28: [ 1904]: <-- [9:AT+FTH=3\r]
Jul 15 16:56:55.30: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:55.30: [ 1904]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:56:55.30: [ 1904]: <-- data [32]
Jul 15 16:56:55.30: [ 1904]: <-- data [2]
Jul 15 16:56:57.08: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:57.08: [ 1904]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:56:57.08: [ 1904]: <-- data [23]
Jul 15 16:56:57.08: [ 1904]: <-- data [2]
Jul 15 16:56:57.82: [ 1904]: --> [7:CONNECT]
Jul 15 16:56:57.82: [ 1904]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:56:57.82: [ 1904]: <-- data [13]
Jul 15 16:56:57.82: [ 1904]: <-- data [2]
Jul 15 16:56:58.40: [ 1904]: --> [2:OK]
Jul 15 16:56:58.40: [ 1904]: <-- [9:AT+FRH=3\r]
Jul 15 16:56:59.30: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:00.18: [ 1904]: --> HDLC<5:FF C8 D8 29 88>
Jul 15 16:57:00.24: [ 1904]: --> [2:OK]
Jul 15 16:57:00.24: [ 1904]: RECV recv CRP (command repeat)
Jul 15 16:57:00.24: [ 1904]: <-- [9:AT+FTH=3\r]
Jul 15 16:57:00.26: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:00.26: [ 1904]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:57:00.26: [ 1904]: <-- data [32]
Jul 15 16:57:00.26: [ 1904]: <-- data [2]
Jul 15 16:57:02.04: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:02.04: [ 1904]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:57:02.04: [ 1904]: <-- data [23]
Jul 15 16:57:02.04: [ 1904]: <-- data [2]
Jul 15 16:57:02.78: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:02.78: [ 1904]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:57:02.78: [ 1904]: <-- data [13]
Jul 15 16:57:02.78: [ 1904]: <-- data [2]
Jul 15 16:57:03.36: [ 1904]: --> [2:OK]
Jul 15 16:57:03.36: [ 1904]: <-- [9:AT+FRH=3\r]
Jul 15 16:57:04.24: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:05.14: [ 1904]: --> HDLC<5:FF C8 D8 29 88>
Jul 15 16:57:05.18: [ 1904]: --> [2:OK]
Jul 15 16:57:05.18: [ 1904]: RECV recv CRP (command repeat)
Jul 15 16:57:05.18: [ 1904]: <-- [9:AT+FTH=3\r]
Jul 15 16:57:05.20: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:05.20: [ 1904]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:57:05.20: [ 1904]: <-- data [32]
Jul 15 16:57:05.20: [ 1904]: <-- data [2]
Jul 15 16:57:06.98: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:06.98: [ 1904]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:57:06.98: [ 1904]: <-- data [23]
Jul 15 16:57:06.98: [ 1904]: <-- data [2]
Jul 15 16:57:07.72: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:07.72: [ 1904]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:57:07.72: [ 1904]: <-- data [13]
Jul 15 16:57:07.72: [ 1904]: <-- data [2]
Jul 15 16:57:08.30: [ 1904]: --> [2:OK]
Jul 15 16:57:08.30: [ 1904]: <-- [9:AT+FRH=3\r]
Jul 15 16:57:09.18: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:10.08: [ 1904]: --> HDLC<5:FF C8 D8 29 88>
Jul 15 16:57:10.12: [ 1904]: --> [2:OK]
Jul 15 16:57:10.12: [ 1904]: RECV recv CRP (command repeat)
Jul 15 16:57:10.12: [ 1904]: <-- [9:AT+FTH=3\r]
Jul 15 16:57:10.14: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:10.14: [ 1904]: <-- 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 6C 74 0C 74 AC>
Jul 15 16:57:10.14: [ 1904]: <-- data [32]
Jul 15 16:57:10.14: [ 1904]: <-- data [2]
Jul 15 16:57:11.92: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:11.92: [ 1904]: <-- HDLC<23:FF C0 02 1E 86 62 A6 36 46 96 26 A6 4E C6 76 92 04 04 04 04 04 04 04>
Jul 15 16:57:11.92: [ 1904]: <-- data [23]
Jul 15 16:57:11.92: [ 1904]: <-- data [2]
Jul 15 16:57:12.66: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:12.66: [ 1904]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jul 15 16:57:12.66: [ 1904]: <-- data [13]
Jul 15 16:57:12.66: [ 1904]: <-- data [2]
Jul 15 16:57:13.24: [ 1904]: --> [2:OK]
Jul 15 16:57:13.24: [ 1904]: <-- [9:AT+FRH=3\r]
Jul 15 16:57:14.16: [ 1904]: --> [7:CONNECT]
Jul 15 16:57:15.06: [ 1904]: --> HDLC<5:FF C8 D8 29 88>
Jul 15 16:57:15.10: [ 1904]: --> [2:OK]
Jul 15 16:57:15.10: [ 1904]: RECV recv CRP (command repeat)
Jul 15 16:57:15.10: [ 1904]: RECV FAX: No sender protocol (T.30 T1 timeout)
Jul 15 16:57:15.10: [ 1904]: RECV FAX: end
Jul 15 16:57:15.10: [ 1904]: No sender protocol (T.30 T1 timeout)
Jul 15 16:57:15.10: [ 1904]: SESSION END

4

These suggests that your iaxmodem /dev/ttyIAX2 is not working properly connecting to the far end anymore. I would guess that your google trunk is no longer working as it was. try it with a less volatile technology .

5

Just a follow up…after searching logs I couldn’t find any root cause but what I did do is continue updating my system with the pbx in a flash patches and after they all applied and a reboot it seems to work again for in and out bound faxing. (Even a 25 page fax)

Thanks for the tips and replies!