HylaFAX The world's most advanced open source fax server
[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]

[hylafax-users] Re[2]: how to write restartmodem script?

Hello Lee,

> I'd like to see this log, but chances are good that this is fixed in 4.2.5.
don't have that log, however have 3 new ones.

One fax in one of our branch offices manages to wedge the modem at
least once/day. Logs attached (if this group accepts attachments).

0_crash.txt - wedged recieving a fax. leds on the modem:
CS RS CD OH RD SD TR MR (+/on, -/off)
+  +  +  +  -  -  +  +
powercycled the modem.
/etc/init.d/hylafax stop
/etc/init.d/hylafax start
hylafax is ready.
1_nocrash.txt - sent from the same fax just after reviving fax server.

2_crash.txt - wedged recieving a fax. leds on the modem!:
this fax had 11 pages.
CS RS CD OH RD SD TR MR (+/on, -/off)
+  +  -  -  -  -  -  +
after revivng the fax server, got all 11 pages without a problem.

> With an external modem a useful resetmodem script may be difficult to
> get... you may just need to 'reboot'... unless you have the serial 
> driver loaded as a module, can unload and then reload it, and that 
> solves the problem.  If indeed it is the modem that is wedged and not
> the serial driver or the underlying system, then you may not be able to
> reset the modem without a power cycle.
It seems that it is the modem not underlying system. Though, i would
like to try something to softreset it.

>   So even rebooting may not 
> resolve it... since a reboot will not power-cycle the modem's power.
it gets unwedged after powercycling the modem and restarting hylafax -
no reboot necessary.

> Lee.

-- 
Best regards,
 Aurimas                            mailto:aurimas@xxxxxxxxx
Mar 06 15:12:02.19: [18937]: SESSION BEGIN 000000677 13702477755
Mar 06 15:12:02.19: [18937]: HylaFAX (tm) Version 4.2.1
Mar 06 15:12:02.19: [18937]: <-- [4:ATA\r]
Mar 06 15:12:08.19: [18937]: --> [7:CONNECT]
Mar 06 15:12:08.19: [18937]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Mar 06 15:12:08.19: [18937]: RECV FAX: begin
Mar 06 15:12:08.20: [18937]: <-- data [32]
Mar 06 15:12:08.20: [18937]: <-- data [2]
Mar 06 15:12:08.22: [18937]: --> [7:CONNECT]
Mar 06 15:12:08.22: [18937]: <-- data [23]
Mar 06 15:12:08.22: [18937]: <-- data [2]
Mar 06 15:12:08.24: [18937]: --> [7:CONNECT]
Mar 06 15:12:08.24: [18937]: <-- data [10]
Mar 06 15:12:08.24: [18937]: <-- data [2]
Mar 06 15:12:11.35: [18937]: --> [2:OK]
Mar 06 15:12:11.35: [18937]: <-- [9:AT+FRH=3\r]
Mar 06 15:12:11.68: [18937]: --> [7:CONNECT]
Mar 06 15:12:13.27: [18937]: --> [2:OK]
Mar 06 15:12:13.27: [18937]: REMOTE TSI "46300591"
Mar 06 15:12:13.27: [18937]: <-- [9:AT+FRH=3\r]
Mar 06 15:12:13.28: [18937]: --> [7:CONNECT]
Mar 06 15:12:13.56: [18937]: --> [2:OK]
Mar 06 15:12:13.56: [18937]: REMOTE wants 14400 bit/s
Mar 06 15:12:13.56: [18937]: REMOTE wants A4 page width (215 mm)
Mar 06 15:12:13.56: [18937]: REMOTE wants unlimited page length
Mar 06 15:12:13.56: [18937]: REMOTE wants 3.85 line/mm
Mar 06 15:12:13.56: [18937]: REMOTE wants 1-D MH
Mar 06 15:12:13.56: [18937]: RECV training at v.17 14400 bit/s
Mar 06 15:12:13.56: [18937]: <-- [11:AT+FRM=145\r]
Mar 06 15:12:15.25: [18937]: --> [7:CONNECT]
Mar 06 15:12:17.03: [18937]: RECV: TCF 2520 bytes, 0% non-zero, 2520 zero-run
Mar 06 15:12:17.03: [18937]: --> [10:NO CARRIER]
Mar 06 15:12:17.03: [18937]: DELAY 75 ms
Mar 06 15:12:17.11: [18937]: TRAINING succeeded
Mar 06 15:12:17.11: [18937]: <-- [9:AT+FTH=3\r]
Mar 06 15:12:17.16: [18937]: --> [7:CONNECT]
Mar 06 15:12:17.16: [18937]: <-- data [3]
Mar 06 15:12:17.16: [18937]: <-- data [2]
Mar 06 15:12:18.47: [18937]: --> [2:OK]
Mar 06 15:12:18.47: [18937]: <-- [11:AT+FRM=146\r]
Mar 06 15:12:19.75: [18937]: --> [7:CONNECT]
Mar 06 15:12:19.75: [18937]: RECV: begin page
Mar 06 15:13:37.18: [18937]: <-- data [1]
Mar 06 15:13:37.18: [18937]: RECV: Missing EOL after 5 seconds
Mar 06 15:13:37.18: [18937]: RECV: end page
Mar 06 15:13:39.18: [18937]: MODEM <Timeout>
Mar 06 15:13:41.18: [18937]: MODEM <Empty line>
Mar 06 15:13:43.19: [18937]: MODEM <Empty line>
Mar 06 15:13:43.19: [18937]: <-- data [1]
Mar 06 15:13:43.39: [18937]: MODEM <Timeout>
Mar 06 15:13:43.39: [18937]: RECV FAX (000000677): recvq/fax000000578.tif from 46300591, route to <unspecified>, 0 pages in 1:35
Mar 06 15:13:43.39: [18937]: RECV FAX: T.30 T2 timeout, expected page not received
Mar 06 15:13:43.39: [18937]: <-- [9:AT+FTH=3\r]
Mar 06 15:13:50.94: [18937]: --> [0:]
Mar 06 15:13:50.94: [18937]: RECV FAX (000000677): session with 46300591 terminated abnormally: T.30 T2 timeout, expected page not received
Mar 06 15:13:50.94: [18937]: RECV FAX: bin/faxrcvd "recvq/fax000000578.tif" "ttyS0" "000000677" "T.30 T2 timeout, expected page not received" "" ""
Mar 06 15:13:50.95: [18937]: RECV FAX: end
Mar 06 15:13:50.95: [18937]: SESSION END

Mar 06 15:29:14.98: [23588]: SESSION BEGIN 000000678 13702477755
Mar 06 15:29:14.98: [23588]: HylaFAX (tm) Version 4.2.1
Mar 06 15:29:14.98: [23588]: <-- [4:ATA\r]
Mar 06 15:29:24.84: [23588]: --> [7:CONNECT]
Mar 06 15:29:24.84: [23588]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Mar 06 15:29:24.84: [23588]: RECV FAX: begin
Mar 06 15:29:24.84: [23588]: <-- data [32]
Mar 06 15:29:24.84: [23588]: <-- data [2]
Mar 06 15:29:24.87: [23588]: --> [7:CONNECT]
Mar 06 15:29:24.87: [23588]: <-- data [23]
Mar 06 15:29:24.87: [23588]: <-- data [2]
Mar 06 15:29:24.89: [23588]: --> [7:CONNECT]
Mar 06 15:29:24.89: [23588]: <-- data [10]
Mar 06 15:29:24.89: [23588]: <-- data [2]
Mar 06 15:29:28.00: [23588]: --> [2:OK]
Mar 06 15:29:28.00: [23588]: <-- [9:AT+FRH=3\r]
Mar 06 15:29:28.30: [23588]: --> [7:CONNECT]
Mar 06 15:29:29.92: [23588]: --> [2:OK]
Mar 06 15:29:29.92: [23588]: REMOTE TSI "46300591"
Mar 06 15:29:29.92: [23588]: <-- [9:AT+FRH=3\r]
Mar 06 15:29:29.93: [23588]: --> [7:CONNECT]
Mar 06 15:29:30.21: [23588]: --> [2:OK]
Mar 06 15:29:30.21: [23588]: REMOTE wants 14400 bit/s
Mar 06 15:29:30.21: [23588]: REMOTE wants A4 page width (215 mm)
Mar 06 15:29:30.21: [23588]: REMOTE wants unlimited page length
Mar 06 15:29:30.21: [23588]: REMOTE wants 3.85 line/mm
Mar 06 15:29:30.21: [23588]: REMOTE wants 1-D MH
Mar 06 15:29:30.21: [23588]: RECV training at v.17 14400 bit/s
Mar 06 15:29:30.21: [23588]: <-- [11:AT+FRM=145\r]
Mar 06 15:29:31.90: [23588]: --> [7:CONNECT]
Mar 06 15:29:33.67: [23588]: RECV: TCF 2520 bytes, 0% non-zero, 2520 zero-run
Mar 06 15:29:33.68: [23588]: --> [10:NO CARRIER]
Mar 06 15:29:33.68: [23588]: DELAY 75 ms
Mar 06 15:29:33.75: [23588]: TRAINING succeeded
Mar 06 15:29:33.75: [23588]: <-- [9:AT+FTH=3\r]
Mar 06 15:29:33.80: [23588]: --> [7:CONNECT]
Mar 06 15:29:33.80: [23588]: <-- data [3]
Mar 06 15:29:33.80: [23588]: <-- data [2]
Mar 06 15:29:35.11: [23588]: --> [2:OK]
Mar 06 15:29:35.11: [23588]: <-- [11:AT+FRM=146\r]
Mar 06 15:29:36.40: [23588]: --> [7:CONNECT]
Mar 06 15:29:36.40: [23588]: RECV: begin page
Mar 06 15:29:53.21: [23588]: RECV: 917 total lines, 12 bad lines, 7 consecutive bad lines
Mar 06 15:29:53.21: [23588]: RECV: REJECT page quality, 7-line run (max 5)
Mar 06 15:29:53.21: [23588]: RECV: end page
Mar 06 15:29:53.21: [23588]: --> [10:NO CARRIER]
Mar 06 15:29:53.21: [23588]: <-- [9:AT+FRH=3\r]
Mar 06 15:29:53.49: [23588]: --> [7:CONNECT]
Mar 06 15:29:54.48: [23588]: --> [2:OK]
Mar 06 15:29:54.48: [23588]: RECV recv MPS (more pages, same document)
Mar 06 15:29:54.48: [23588]: DELAY 70 ms
Mar 06 15:29:54.55: [23588]: <-- [9:AT+FTH=3\r]
Mar 06 15:29:54.70: [23588]: --> [7:CONNECT]
Mar 06 15:29:54.70: [23588]: <-- data [3]
Mar 06 15:29:54.70: [23588]: <-- data [2]
Mar 06 15:29:56.01: [23588]: --> [2:OK]
Mar 06 15:29:56.01: [23588]: RECV send RTN (retrain negative)
Mar 06 15:29:56.01: [23588]: <-- [9:AT+FRH=3\r]
Mar 06 15:29:57.49: [23588]: --> [7:CONNECT]
Mar 06 15:29:59.10: [23588]: --> [2:OK]
Mar 06 15:29:59.10: [23588]: REMOTE TSI "46300591"
Mar 06 15:29:59.10: [23588]: <-- [9:AT+FRH=3\r]
Mar 06 15:29:59.21: [23588]: --> [7:CONNECT]
Mar 06 15:29:59.39: [23588]: --> [2:OK]
Mar 06 15:29:59.39: [23588]: REMOTE wants 12000 bit/s
Mar 06 15:29:59.39: [23588]: REMOTE wants A4 page width (215 mm)
Mar 06 15:29:59.39: [23588]: REMOTE wants unlimited page length
Mar 06 15:29:59.39: [23588]: REMOTE wants 3.85 line/mm
Mar 06 15:29:59.39: [23588]: REMOTE wants 1-D MH
Mar 06 15:29:59.39: [23588]: RECV training at v.17 12000 bit/s
Mar 06 15:29:59.39: [23588]: <-- [11:AT+FRM=121\r]
Mar 06 15:30:01.08: [23588]: --> [7:CONNECT]
Mar 06 15:30:02.65: [23588]: RECV: TCF 2100 bytes, 0% non-zero, 2100 zero-run
Mar 06 15:30:02.65: [23588]: --> [10:NO CARRIER]
Mar 06 15:30:02.65: [23588]: DELAY 75 ms
Mar 06 15:30:02.73: [23588]: TRAINING succeeded
Mar 06 15:30:02.73: [23588]: <-- [9:AT+FTH=3\r]
Mar 06 15:30:02.87: [23588]: --> [7:CONNECT]
Mar 06 15:30:02.87: [23588]: <-- data [3]
Mar 06 15:30:02.87: [23588]: <-- data [2]
Mar 06 15:30:04.19: [23588]: --> [2:OK]
Mar 06 15:30:04.19: [23588]: <-- [11:AT+FRM=122\r]
Mar 06 15:30:05.24: [23588]: --> [7:CONNECT]
Mar 06 15:30:05.24: [23588]: RECV: begin page
Mar 06 15:30:23.57: [23588]: RECV: 1150 total lines, 0 bad lines, 0 consecutive bad lines
Mar 06 15:30:23.57: [23588]: RECV: end page
Mar 06 15:30:23.68: [23588]: --> [10:NO CARRIER]
Mar 06 15:30:23.68: [23588]: <-- [9:AT+FRH=3\r]
Mar 06 15:30:23.95: [23588]: --> [7:CONNECT]
Mar 06 15:30:24.97: [23588]: --> [2:OK]
Mar 06 15:30:24.97: [23588]: RECV recv MPS (more pages, same document)
Mar 06 15:30:24.97: [23588]: DELAY 70 ms
Mar 06 15:30:25.04: [23588]: <-- [9:AT+FTH=3\r]
Mar 06 15:30:25.19: [23588]: --> [7:CONNECT]
Mar 06 15:30:25.19: [23588]: RECV send MCF (message confirmation)
Mar 06 15:30:25.19: [23588]: RECV FAX (000000678): from 46300591, page 1 in 0:52, INF, 3.85 line/mm, 1-D MH, 12000 bit/s
Mar 06 15:30:25.19: [23588]: <-- data [3]
Mar 06 15:30:25.19: [23588]: <-- data [2]
Mar 06 15:30:26.50: [23588]: --> [2:OK]
Mar 06 15:30:26.50: [23588]: <-- [11:AT+FRM=122\r]
Mar 06 15:30:28.63: [23588]: --> [7:CONNECT]
Mar 06 15:30:28.63: [23588]: RECV: begin page
Mar 06 15:30:59.88: [23588]: RECV: 1144 total lines, 0 bad lines, 0 consecutive bad lines
Mar 06 15:30:59.88: [23588]: RECV: end page
Mar 06 15:30:59.88: [23588]: --> [10:NO CARRIER]
Mar 06 15:30:59.88: [23588]: <-- [9:AT+FRH=3\r]
Mar 06 15:31:00.15: [23588]: --> [7:CONNECT]
Mar 06 15:31:01.17: [23588]: --> [2:OK]
Mar 06 15:31:01.17: [23588]: RECV recv EOP (no more pages or documents)
Mar 06 15:31:01.17: [23588]: DELAY 70 ms
Mar 06 15:31:01.24: [23588]: <-- [9:AT+FTH=3\r]
Mar 06 15:31:01.39: [23588]: --> [7:CONNECT]
Mar 06 15:31:01.39: [23588]: <-- data [3]
Mar 06 15:31:01.39: [23588]: <-- data [2]
Mar 06 15:31:02.70: [23588]: --> [2:OK]
Mar 06 15:31:02.70: [23588]: RECV send MCF (message confirmation)
Mar 06 15:31:02.71: [23588]: RECV FAX (000000678): from 46300591, page 2 in 0:37, INF, 3.85 line/mm, 1-D MH, 12000 bit/s
Mar 06 15:31:02.71: [23588]: RECV FAX (000000678): recvq/fax000000579.tif from 46300591, route to <unspecified>, 2 pages in 1:38
Mar 06 15:31:02.71: [23588]: <-- [9:AT+FRH=3\r]
Mar 06 15:31:02.99: [23588]: --> [7:CONNECT]
Mar 06 15:31:04.07: [23588]: --> [2:OK]
Mar 06 15:31:04.07: [23588]: RECV FAX: bin/faxrcvd "recvq/fax000000579.tif" "ttyS0" "000000678" "" "" ""
Mar 06 15:31:04.08: [23588]: RECV FAX: end
Mar 06 15:31:04.08: [23588]: SESSION END

Mar 07 14:19:36.52: [23588]: SESSION BEGIN 000000759 13702477755
Mar 07 14:19:36.52: [23588]: HylaFAX (tm) Version 4.2.1
Mar 07 14:19:36.52: [23588]: <-- [4:ATA\r]
Mar 07 14:19:46.36: [23588]: --> [7:CONNECT]
Mar 07 14:19:46.36: [23588]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Mar 07 14:19:46.36: [23588]: RECV FAX: begin
Mar 07 14:19:46.36: [23588]: <-- data [32]
Mar 07 14:19:46.36: [23588]: <-- data [2]
Mar 07 14:19:46.38: [23588]: --> [7:CONNECT]
Mar 07 14:19:46.38: [23588]: <-- data [23]
Mar 07 14:19:46.38: [23588]: <-- data [2]
Mar 07 14:19:46.41: [23588]: --> [7:CONNECT]
Mar 07 14:19:46.41: [23588]: <-- data [10]
Mar 07 14:19:46.41: [23588]: <-- data [2]
Mar 07 14:19:49.52: [23588]: --> [2:OK]
Mar 07 14:19:49.52: [23588]: <-- [9:AT+FRH=3\r]
Mar 07 14:19:49.84: [23588]: --> [7:CONNECT]
Mar 07 14:19:51.43: [23588]: --> [2:OK]
Mar 07 14:19:51.43: [23588]: REMOTE TSI "46300591"
Mar 07 14:19:51.43: [23588]: <-- [9:AT+FRH=3\r]
Mar 07 14:19:51.44: [23588]: --> [7:CONNECT]
Mar 07 14:19:51.72: [23588]: --> [2:OK]
Mar 07 14:19:51.72: [23588]: REMOTE wants 14400 bit/s
Mar 07 14:19:51.72: [23588]: REMOTE wants A4 page width (215 mm)
Mar 07 14:19:51.72: [23588]: REMOTE wants unlimited page length
Mar 07 14:19:51.72: [23588]: REMOTE wants 3.85 line/mm
Mar 07 14:19:51.72: [23588]: REMOTE wants 1-D MH
Mar 07 14:19:51.72: [23588]: RECV training at v.17 14400 bit/s
Mar 07 14:19:51.72: [23588]: <-- [11:AT+FRM=145\r]
Mar 07 14:19:53.42: [23588]: --> [7:CONNECT]
Mar 07 14:19:55.19: [23588]: RECV: TCF 2520 bytes, 0% non-zero, 2520 zero-run
Mar 07 14:19:55.19: [23588]: --> [10:NO CARRIER]
Mar 07 14:19:55.19: [23588]: DELAY 75 ms
Mar 07 14:19:55.27: [23588]: TRAINING succeeded
Mar 07 14:19:55.27: [23588]: <-- [9:AT+FTH=3\r]
Mar 07 14:19:55.32: [23588]: --> [7:CONNECT]
Mar 07 14:19:55.32: [23588]: <-- data [3]
Mar 07 14:19:55.32: [23588]: <-- data [2]
Mar 07 14:19:56.63: [23588]: --> [2:OK]
Mar 07 14:19:56.63: [23588]: <-- [11:AT+FRM=146\r]
Mar 07 14:19:57.92: [23588]: --> [7:CONNECT]
Mar 07 14:19:57.92: [23588]: RECV: begin page
Mar 07 14:20:14.32: [23588]: RECV: 1140 total lines, 0 bad lines, 0 consecutive bad lines
Mar 07 14:20:14.32: [23588]: RECV: end page
Mar 07 14:20:14.32: [23588]: --> [10:NO CARRIER]
Mar 07 14:20:14.32: [23588]: <-- [9:AT+FRH=3\r]
Mar 07 14:20:14.59: [23588]: --> [7:CONNECT]
Mar 07 14:20:15.61: [23588]: --> [2:OK]
Mar 07 14:20:15.61: [23588]: RECV recv MPS (more pages, same document)
Mar 07 14:20:15.61: [23588]: DELAY 70 ms
Mar 07 14:20:15.68: [23588]: <-- [9:AT+FTH=3\r]
Mar 07 14:20:15.83: [23588]: --> [7:CONNECT]
Mar 07 14:20:15.83: [23588]: RECV send MCF (message confirmation)
Mar 07 14:20:15.83: [23588]: RECV FAX (000000759): from 46300591, page 1 in 0:20, INF, 3.85 line/mm, 1-D MH, 14400 bit/s
Mar 07 14:20:15.83: [23588]: <-- data [3]
Mar 07 14:20:15.83: [23588]: <-- data [2]
Mar 07 14:20:17.14: [23588]: --> [2:OK]
Mar 07 14:20:17.14: [23588]: <-- [11:AT+FRM=146\r]
Mar 07 14:20:19.52: [23588]: --> [7:CONNECT]
Mar 07 14:20:19.52: [23588]: RECV: begin page
Mar 07 14:21:46.99: [23588]: <-- data [1]
Mar 07 14:21:46.99: [23588]: RECV: Missing EOL after 5 seconds
Mar 07 14:21:46.99: [23588]: RECV: end page
Mar 07 14:21:48.99: [23588]: MODEM <Timeout>
Mar 07 14:21:50.99: [23588]: MODEM <Empty line>
Mar 07 14:21:52.99: [23588]: MODEM <Empty line>
Mar 07 14:21:52.99: [23588]: <-- data [1]
Mar 07 14:21:53.19: [23588]: MODEM <Timeout>
Mar 07 14:21:53.19: [23588]: RECV FAX (000000759): recvq/fax000000660.tif from 46300591, route to <unspecified>, 1 pages in 2:07
Mar 07 14:21:53.19: [23588]: RECV FAX: T.30 T2 timeout, expected page not received
Mar 07 14:21:53.20: [23588]: <-- [9:AT+FTH=3\r]
Mar 07 14:22:00.75: [23588]: --> [0:]
Mar 07 14:22:00.75: [23588]: RECV FAX (000000759): session with 46300591 terminated abnormally: T.30 T2 timeout, expected page not received
Mar 07 14:22:00.75: [23588]: RECV FAX: bin/faxrcvd "recvq/fax000000660.tif" "ttyS0" "000000759" "T.30 T2 timeout, expected page not received" "" ""
Mar 07 14:22:00.79: [23588]: RECV FAX: end
Mar 07 14:22:00.79: [23588]: SESSION END

Project hosted by iFAX Solutions