HylaFAX The world's most advanced open source fax server

[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]

Document Preparation fail ?



Hello,
	I use a Linksys 10/100 Combo PCMCIA card to fax.  Shot out 38 faxes. 
One of them gave me the message in faxstat -d "Document preparation fail"  I'm
curious as to what this means.  I think I have had problems with this number
before.  Any ideas?  I'm asking, because I would like to fine tune my config
settings if possible.

***********
/var/log/messages
***************
Feb 18 01:43:47 host-name FaxSend[2657]: SEND FAX: JOB 272 DEST 965-9092
COMMID 00000453 Feb 18 01:46:30 host-name FaxQueuer[217]: JOB 272 (active dest
+70959659092 pri 127 tts 0:00 killtime 2:52:50): CMD DONE: exit status 0 
Feb 18 01:46:30 host-name FaxQueuer[217]: JOB 272 (sleeping dest +70959659092
pri 126 tts 0:00 killtime 2:52:50): SEND INCOMPLETE: requeue for 4:57; No
response to MPS or EOP repeated 3 tries 
Feb 18 01:46:30 host-name FaxQueuer[217]: NOTIFY: bin/notify "sendq/q272"
"requeued" "" "01:51"Feb 18 01:43:47.79: [ 2657]: SESSION BEGIN 00000453 70959659092
************
/var/spool/fax/log/c00000453
***************
Feb 18 01:43:47.79: [ 2657]: SESSION BEGIN 00000453 70959659092
Feb 18 01:43:47.79: [ 2657]: SEND FAX: JOB 272 DEST 965-9092 COMMID 00000453
Feb 18 01:43:47.79: [ 2657]: MODEM set DTR OFF
Feb 18 01:43:47.79: [ 2657]: DELAY 2600 ms
Feb 18 01:43:50.39: [ 2657]: MODEM set DTR ON
Feb 18 01:43:50.39: [ 2657]: MODEM set baud rate: 19200 baud, input flow XON/XOFF, output flow XON/XOFF
Feb 18 01:43:50.39: [ 2657]: MODEM flush i/o
Feb 18 01:43:50.39: [ 2657]: <-- [15:ATE0V1Q0S0=0H0\r]
Feb 18 01:43:50.43: [ 2657]: --> [2:OK]
Feb 18 01:43:50.43: [ 2657]: <-- [12:ATS8=2S7=60\r]
Feb 18 01:43:50.44: [ 2657]: --> [2:OK]
Feb 18 01:43:50.44: [ 2657]: <-- [12:AT+FCLASS=1\r]
Feb 18 01:43:50.45: [ 2657]: --> [2:OK]
Feb 18 01:43:50.45: [ 2657]: <-- [5:ATM0\r]
Feb 18 01:43:50.46: [ 2657]: --> [2:OK]
Feb 18 01:43:50.46: [ 2657]: MODEM input buffering enabled
Feb 18 01:43:50.46: [ 2657]: <-- [12:AT+FCLASS=1\r]
Feb 18 01:43:50.57: [ 2657]: --> [2:OK]
Feb 18 01:43:50.57: [ 2657]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Feb 18 01:43:50.57: [ 2657]: DIAL 9659092
Feb 18 01:43:50.57: [ 2657]: <-- [12:ATDP9659092\r]
Feb 18 01:44:28.48: [ 2657]: --> [7:CONNECT]
Feb 18 01:44:28.48: [ 2657]: MODEM input buffering disabled
Feb 18 01:44:30.59: [ 2657]: --> [2:OK]
Feb 18 01:44:30.59: [ 2657]: <-- [9:AT+FRH=3\r]
Feb 18 01:44:30.60: [ 2657]: --> [7:CONNECT]
Feb 18 01:44:31.35: [ 2657]: --> [2:OK]
Feb 18 01:44:31.35: [ 2657]: REMOTE CSI "095 9659092"
Feb 18 01:44:31.35: [ 2657]: <-- [9:AT+FRH=3\r]
Feb 18 01:44:31.36: [ 2657]: --> [7:CONNECT]
Feb 18 01:44:31.72: [ 2657]: --> [2:OK]
Feb 18 01:44:31.72: [ 2657]: REMOTE best rate 9600 bit/s
Feb 18 01:44:31.72: [ 2657]: REMOTE max page width 1728 pixels in 215 mm
Feb 18 01:44:31.72: [ 2657]: REMOTE max unlimited page length 
Feb 18 01:44:31.72: [ 2657]: REMOTE best vres 7.7 line/mm
Feb 18 01:44:31.72: [ 2657]: REMOTE best format 2-D MR
Feb 18 01:44:31.72: [ 2657]: REMOTE best 20 ms, 10 ms/scanline
Feb 18 01:44:31.72: [ 2657]: USE 9600 bit/s
Feb 18 01:44:31.72: [ 2657]: USE 20 ms, 10 ms/scanline
Feb 18 01:44:31.72: [ 2657]: SEND file "docq/doc244.ps;01"
Feb 18 01:44:31.72: [ 2657]: USE page width 1728 pixels in 215 mm
Feb 18 01:44:31.72: [ 2657]: USE unlimited page length 
Feb 18 01:44:31.72: [ 2657]: USE 7.7 line/mm
Feb 18 01:44:31.72: [ 2657]: USE 1-D MR
Feb 18 01:44:31.72: [ 2657]: SEND training at v.29 9600 bit/s
Feb 18 01:44:31.72: [ 2657]: <-- [9:AT+FTH=3\r]
Feb 18 01:44:32.66: [ 2657]: --> [7:CONNECT]
Feb 18 01:44:32.66: [ 2657]: <-- data [23]
Feb 18 01:44:32.66: [ 2657]: <-- data [2]
Feb 18 01:44:32.68: [ 2657]: --> [7:CONNECT]
Feb 18 01:44:32.68: [ 2657]: <-- data [6]
Feb 18 01:44:32.68: [ 2657]: <-- data [2]
Feb 18 01:44:33.76: [ 2657]: --> [2:OK]
Feb 18 01:44:33.76: [ 2657]: DELAY 75 ms
Feb 18 01:44:33.84: [ 2657]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Feb 18 01:44:33.84: [ 2657]: <-- [10:AT+FTM=96\r]
Feb 18 01:44:33.85: [ 2657]: --> [7:CONNECT]
Feb 18 01:44:33.85: [ 2657]: <-- data [1024]
Feb 18 01:44:33.85: [ 2657]: <-- data [776]
Feb 18 01:44:33.85: [ 2657]: <-- data [2]
Feb 18 01:44:35.64: [ 2657]: --> [2:OK]
Feb 18 01:44:35.64: [ 2657]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Feb 18 01:44:35.64: [ 2657]: <-- [9:AT+FRH=3\r]
Feb 18 01:44:36.60: [ 2657]: --> [7:CONNECT]
Feb 18 01:44:37.84: [ 2657]: --> [2:OK]
Feb 18 01:44:37.84: [ 2657]: TRAINING succeeded
Feb 18 01:44:37.84: [ 2657]: <-- [10:AT+FTM=96\r]
Feb 18 01:44:37.93: [ 2657]: --> [7:CONNECT]
Feb 18 01:44:37.93: [ 2657]: SEND begin page
Feb 18 01:44:37.93: [ 2657]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Feb 18 01:44:37.94: [ 2657]: <-- data [1026]
Feb 18 01:44:37.94: [ 2657]: <-- data [1026]
Feb 18 01:44:37.94: [ 2657]: <-- data [1024]
Feb 18 01:44:37.94: [ 2657]: <-- data [1024]
Feb 18 01:44:39.14: [ 2657]: <-- data [1024]
Feb 18 01:44:39.14: [ 2657]: <-- data [1024]
Feb 18 01:44:39.14: [ 2657]: <-- data [1029]
Feb 18 01:44:39.14: [ 2657]: <-- data [1025]
Feb 18 01:44:41.84: [ 2657]: <-- data [1025]
Feb 18 01:44:41.84: [ 2657]: <-- data [1024]
Feb 18 01:44:41.84: [ 2657]: <-- data [1026]
Feb 18 01:44:41.84: [ 2657]: <-- data [1026]
Feb 18 01:44:45.05: [ 2657]: <-- data [1025]
Feb 18 01:44:45.05: [ 2657]: <-- data [1028]
Feb 18 01:44:45.05: [ 2657]: <-- data [1025]
Feb 18 01:44:45.05: [ 2657]: <-- data [1024]
Feb 18 01:44:48.26: [ 2657]: <-- data [1025]
Feb 18 01:44:48.26: [ 2657]: <-- data [1025]
Feb 18 01:44:48.26: [ 2657]: <-- data [1024]
Feb 18 01:44:51.46: [ 2657]: <-- data [1027]
Feb 18 01:44:51.46: [ 2657]: <-- data [1026]
Feb 18 01:44:51.46: [ 2657]: <-- data [1024]
Feb 18 01:44:51.46: [ 2657]: <-- data [1024]
Feb 18 01:44:54.66: [ 2657]: <-- data [1026]
Feb 18 01:44:54.67: [ 2657]: <-- data [1024]
Feb 18 01:44:54.67: [ 2657]: <-- data [1024]
Feb 18 01:44:54.67: [ 2657]: <-- data [810]
Feb 18 01:44:57.87: [ 2657]: SENT 27433 bytes of data
Feb 18 01:44:57.87: [ 2657]: <-- data [1026]
Feb 18 01:44:57.87: [ 2657]: <-- data [1027]
Feb 18 01:44:57.87: [ 2657]: <-- data [1024]
Feb 18 01:44:57.87: [ 2657]: <-- data [1024]
Feb 18 01:45:01.07: [ 2657]: <-- data [1027]
Feb 18 01:45:01.07: [ 2657]: <-- data [1026]
Feb 18 01:45:01.07: [ 2657]: <-- data [1026]
Feb 18 01:45:01.07: [ 2657]: <-- data [1024]
Feb 18 01:45:04.27: [ 2657]: <-- data [1024]
Feb 18 01:45:04.27: [ 2657]: <-- data [1024]
Feb 18 01:45:04.27: [ 2657]: <-- data [1024]
Feb 18 01:45:07.47: [ 2657]: <-- data [1024]
Feb 18 01:45:07.47: [ 2657]: <-- data [1024]
Feb 18 01:45:07.47: [ 2657]: <-- data [1024]
Feb 18 01:45:07.47: [ 2657]: <-- data [1024]
Feb 18 01:45:10.67: [ 2657]: <-- data [50]
Feb 18 01:45:10.67: [ 2657]: SENT 15410 bytes of data
Feb 18 01:45:10.67: [ 2657]: SEND 1D RTC
Feb 18 01:45:10.67: [ 2657]: <-- data [29]
Feb 18 01:45:10.67: [ 2657]: <-- data [2]
Feb 18 01:45:10.67: [ 2657]: SEND end page
Feb 18 01:45:13.95: [ 2657]: --> [2:OK]
Feb 18 01:45:13.95: [ 2657]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Feb 18 01:45:13.95: [ 2657]: DELAY 75 ms
Feb 18 01:45:14.03: [ 2657]: SEND send MPS (more pages, same document)
Feb 18 01:45:14.03: [ 2657]: <-- [9:AT+FTH=3\r]
Feb 18 01:45:14.89: [ 2657]: --> [7:CONNECT]
Feb 18 01:45:14.89: [ 2657]: <-- data [3]
Feb 18 01:45:14.89: [ 2657]: <-- data [2]
Feb 18 01:45:15.21: [ 2657]: --> [2:OK]
Feb 18 01:45:15.21: [ 2657]: <-- [9:AT+FRH=3\r]
Feb 18 01:45:15.75: [ 2657]: --> [7:CONNECT]
Feb 18 01:45:17.03: [ 2657]: --> [2:OK]
Feb 18 01:45:17.03: [ 2657]: SEND recv MCF (message confirmation)
Feb 18 01:45:17.03: [ 2657]: SEND FAX (00000453): FROM russian@host-name.domain-name TO 965-9092 (page 1 of 3 sent in 0:46)
Feb 18 01:45:17.03: [ 2657]: USE page width 1728 pixels in 215 mm
Feb 18 01:45:17.03: [ 2657]: USE unlimited page length 
Feb 18 01:45:17.03: [ 2657]: USE 7.7 line/mm
Feb 18 01:45:17.03: [ 2657]: USE 1-D MR
Feb 18 01:45:17.03: [ 2657]: <-- [10:AT+FTM=96\r]
Feb 18 01:45:17.12: [ 2657]: --> [7:CONNECT]
Feb 18 01:45:17.12: [ 2657]: SEND begin page
Feb 18 01:45:17.12: [ 2657]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Feb 18 01:45:17.13: [ 2657]: <-- data [1025]
Feb 18 01:45:17.13: [ 2657]: <-- data [1026]
Feb 18 01:45:17.13: [ 2657]: <-- data [1027]
Feb 18 01:45:17.13: [ 2657]: <-- data [1025]
Feb 18 01:45:18.33: [ 2657]: <-- data [1025]
Feb 18 01:45:18.33: [ 2657]: <-- data [1027]
Feb 18 01:45:18.33: [ 2657]: <-- data [1025]
Feb 18 01:45:18.33: [ 2657]: <-- data [1025]
Feb 18 01:45:21.03: [ 2657]: <-- data [1025]
Feb 18 01:45:21.03: [ 2657]: <-- data [1026]
Feb 18 01:45:21.03: [ 2657]: <-- data [1027]
Feb 18 01:45:21.03: [ 2657]: <-- data [1024]
Feb 18 01:45:24.24: [ 2657]: <-- data [1026]
Feb 18 01:45:24.24: [ 2657]: <-- data [1026]
Feb 18 01:45:24.24: [ 2657]: <-- data [1026]
Feb 18 01:45:24.24: [ 2657]: <-- data [1027]
Feb 18 01:45:27.44: [ 2657]: <-- data [1025]
Feb 18 01:45:27.44: [ 2657]: <-- data [1027]
Feb 18 01:45:27.44: [ 2657]: <-- data [1025]
Feb 18 01:45:30.64: [ 2657]: <-- data [1029]
Feb 18 01:45:30.64: [ 2657]: <-- data [1025]
Feb 18 01:45:30.64: [ 2657]: <-- data [1036]
Feb 18 01:45:30.64: [ 2657]: <-- data [1027]
Feb 18 01:45:33.84: [ 2657]: <-- data [1029]
Feb 18 01:45:33.84: [ 2657]: <-- data [1027]
Feb 18 01:45:33.84: [ 2657]: <-- data [1026]
Feb 18 01:45:33.84: [ 2657]: <-- data [869]
Feb 18 01:45:37.03: [ 2657]: SENT 27493 bytes of data
Feb 18 01:45:37.04: [ 2657]: <-- data [1027]
Feb 18 01:45:37.04: [ 2657]: <-- data [1028]
Feb 18 01:45:37.04: [ 2657]: <-- data [1024]
Feb 18 01:45:37.04: [ 2657]: <-- data [1029]
Feb 18 01:45:40.24: [ 2657]: <-- data [1029]
Feb 18 01:45:40.24: [ 2657]: <-- data [1026]
Feb 18 01:45:40.24: [ 2657]: <-- data [1025]
Feb 18 01:45:40.24: [ 2657]: <-- data [1025]
Feb 18 01:45:43.44: [ 2657]: <-- data [1026]
Feb 18 01:45:43.44: [ 2657]: <-- data [1026]
Feb 18 01:45:43.44: [ 2657]: <-- data [1026]
Feb 18 01:45:46.65: [ 2657]: <-- data [1025]
Feb 18 01:45:46.65: [ 2657]: <-- data [1027]
Feb 18 01:45:46.65: [ 2657]: <-- data [1026]
Feb 18 01:45:46.65: [ 2657]: <-- data [1028]
Feb 18 01:45:49.85: [ 2657]: <-- data [1026]
Feb 18 01:45:49.85: [ 2657]: <-- data [1024]
Feb 18 01:45:49.85: [ 2657]: <-- data [1024]
Feb 18 01:45:49.85: [ 2657]: <-- data [1024]
Feb 18 01:45:53.05: [ 2657]: <-- data [1024]
Feb 18 01:45:53.05: [ 2657]: <-- data [1026]
Feb 18 01:45:53.05: [ 2657]: <-- data [1026]
Feb 18 01:45:53.05: [ 2657]: <-- data [1026]
Feb 18 01:45:56.25: [ 2657]: <-- data [1024]
Feb 18 01:45:56.25: [ 2657]: <-- data [1025]
Feb 18 01:45:56.25: [ 2657]: <-- data [1029]
Feb 18 01:45:59.45: [ 2657]: <-- data [862]
Feb 18 01:45:59.45: [ 2657]: SENT 27486 bytes of data
Feb 18 01:45:59.45: [ 2657]: <-- data [1026]
Feb 18 01:45:59.45: [ 2657]: <-- data [1025]
Feb 18 01:45:59.45: [ 2657]: <-- data [1025]
Feb 18 01:46:02.65: [ 2657]: <-- data [1024]
Feb 18 01:46:02.65: [ 2657]: <-- data [1028]
Feb 18 01:46:02.65: [ 2657]: <-- data [1024]
Feb 18 01:46:02.65: [ 2657]: <-- data [1025]
Feb 18 01:46:05.85: [ 2657]: <-- data [1025]
Feb 18 01:46:05.85: [ 2657]: <-- data [1024]
Feb 18 01:46:05.85: [ 2657]: <-- data [1024]
Feb 18 01:46:05.85: [ 2657]: <-- data [1024]
Feb 18 01:46:09.05: [ 2657]: <-- data [1024]
Feb 18 01:46:09.05: [ 2657]: <-- data [1024]
Feb 18 01:46:09.05: [ 2657]: <-- data [238]
Feb 18 01:46:09.05: [ 2657]: SENT 13550 bytes of data
Feb 18 01:46:09.05: [ 2657]: SEND 1D RTC
Feb 18 01:46:09.05: [ 2657]: <-- data [29]
Feb 18 01:46:09.05: [ 2657]: <-- data [2]
Feb 18 01:46:09.05: [ 2657]: SEND end page
Feb 18 01:46:14.55: [ 2657]: --> [2:OK]
Feb 18 01:46:14.55: [ 2657]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Feb 18 01:46:14.55: [ 2657]: DELAY 75 ms
Feb 18 01:46:14.63: [ 2657]: SEND send MPS (more pages, same document)
Feb 18 01:46:14.63: [ 2657]: <-- [9:AT+FTH=3\r]
Feb 18 01:46:15.49: [ 2657]: --> [7:CONNECT]
Feb 18 01:46:15.49: [ 2657]: <-- data [3]
Feb 18 01:46:15.49: [ 2657]: <-- data [2]
Feb 18 01:46:15.81: [ 2657]: --> [2:OK]
Feb 18 01:46:15.81: [ 2657]: <-- [9:AT+FRH=3\r]
Feb 18 01:46:18.91: [ 2657]: --> [0:]
Feb 18 01:46:18.91: [ 2657]: MODEM <Empty line>
Feb 18 01:46:18.91: [ 2657]: MODEM TIMEOUT: waiting for v.21 carrier
Feb 18 01:46:18.91: [ 2657]: <-- data [1]
Feb 18 01:46:18.92: [ 2657]: --> [2:  ]
Feb 18 01:46:18.92: [ 2657]: --> [2:OK]
Feb 18 01:46:18.92: [ 2657]: SEND send MPS (more pages, same document)
Feb 18 01:46:18.92: [ 2657]: <-- [9:AT+FTH=3\r]
Feb 18 01:46:19.84: [ 2657]: --> [7:CONNECT]
Feb 18 01:46:19.84: [ 2657]: <-- data [3]
Feb 18 01:46:19.84: [ 2657]: <-- data [2]
Feb 18 01:46:20.17: [ 2657]: --> [2:OK]
Feb 18 01:46:20.17: [ 2657]: <-- [9:AT+FRH=3\r]
Feb 18 01:46:23.27: [ 2657]: --> [0:]
Feb 18 01:46:23.27: [ 2657]: MODEM <Empty line>
Feb 18 01:46:23.27: [ 2657]: MODEM TIMEOUT: waiting for v.21 carrier
Feb 18 01:46:23.27: [ 2657]: <-- data [1]
Feb 18 01:46:23.28: [ 2657]: --> [2:  ]
Feb 18 01:46:23.28: [ 2657]: --> [2:OK]
Feb 18 01:46:23.28: [ 2657]: SEND send MPS (more pages, same document)
Feb 18 01:46:23.28: [ 2657]: <-- [9:AT+FTH=3\r]
Feb 18 01:46:24.20: [ 2657]: --> [7:CONNECT]
Feb 18 01:46:24.20: [ 2657]: <-- data [3]
Feb 18 01:46:24.20: [ 2657]: <-- data [2]
Feb 18 01:46:24.52: [ 2657]: --> [2:OK]
Feb 18 01:46:24.52: [ 2657]: <-- [9:AT+FRH=3\r]
Feb 18 01:46:27.62: [ 2657]: --> [0:]
Feb 18 01:46:27.62: [ 2657]: MODEM <Empty line>
Feb 18 01:46:27.62: [ 2657]: MODEM TIMEOUT: waiting for v.21 carrier
Feb 18 01:46:27.62: [ 2657]: <-- data [1]
Feb 18 01:46:27.63: [ 2657]: --> [2:  ]
Feb 18 01:46:27.63: [ 2657]: --> [2:OK]
Feb 18 01:46:27.63: [ 2657]: <-- [9:AT+FTH=3\r]
Feb 18 01:46:28.55: [ 2657]: --> [7:CONNECT]
Feb 18 01:46:28.55: [ 2657]: <-- data [3]
Feb 18 01:46:28.55: [ 2657]: <-- data [2]
Feb 18 01:46:28.88: [ 2657]: --> [2:OK]
Feb 18 01:46:28.88: [ 2657]: MODEM input buffering enabled
Feb 18 01:46:28.88: [ 2657]: <-- [5:ATH0\r]
Feb 18 01:46:30.03: [ 2657]: --> [2:OK]
Feb 18 01:46:30.03: [ 2657]: MODEM set DTR OFF
Feb 18 01:46:30.03: [ 2657]: SESSION END

-- My personal
website http://www.firstnethou.com/gz/welcome.htm




Project hosted by iFAX Solutions