HylaFAX The world's
most advanced open source fax server
|
|
[
Date Prev][
Date Next][
Thread Prev][
Thread Next]
[
Date Index]
[
Thread Index]
[hylafax-users] No response to MPS again
Haho,
I sent a fax to myself, with the newest CVS.
The sender is a Class1 Rockwell.
The receiver is a ZyXEL Omni288S Class2.0.
It can be reproduce any time.
Imre.
The sender.conf:
ModemType: Class1 # use this to supply a hint
ModemRate: 38400 # rate for DCE-DTE communication
ModemFlowControl: rtscts # default
ModemNoFlowCmd: AT&K0 # setup no flow control
ModemHardFlowCmd: AT&K3 # setup hardware flow control
ModemSoftFlowCmd: AT&K4 # setup software flow control
ModemSetupDTRCmd: AT&D2 # setup so DTR drop resets modem
ModemSetupDCDCmd: AT&C1 # setup so DCD reflects carrier (or
not)
ModemMfrQueryCmd: !Rockwell
ModemModelQueryCmd: !RC288DPi
ModemRevQueryCmd: ATI3 # product information
ModemDialCmd: ATDT%s # T for tone dialing
The sendlog:
Mar 25 14:57:25.14: [11077]: SESSION BEGIN 00030921 36103472020
Mar 25 14:57:25.14: [11077]: SEND FAX: JOB 13738 DEST 03472020 COMMID
00030921
Mar 25 14:57:25.14: [11077]: MODEM set DTR OFF
Mar 25 14:57:25.14: [11077]: DELAY 2600 ms
Mar 25 14:57:27.74: [11077]: MODEM set DTR ON
Mar 25 14:57:27.74: [11077]: MODEM set baud rate: 38400 baud, input flow
RTS/CTS, output flow RTS/CTS
Mar 25 14:57:27.74: [11077]: MODEM flush i/o
Mar 25 14:57:27.74: [11077]: <-- [4:ATZ\r]
Mar 25 14:57:27.87: [11077]: --> [2:OK]
Mar 25 14:57:27.87: [11077]: <-- [15:ATE0V1Q0S0=0H0\r]
Mar 25 14:57:27.88: [11077]: --> [14:ATE0V1Q0S0=0H0]
Mar 25 14:57:27.88: [11077]: --> [2:OK]
Mar 25 14:57:27.88: [11077]: <-- [21:ATS8=2S7=60&K3&D2&C1\r]
Mar 25 14:57:27.90: [11077]: --> [2:OK]
Mar 25 14:57:27.90: [11077]: <-- [12:AT+FCLASS=1\r]
Mar 25 14:57:27.91: [11077]: --> [2:OK]
Mar 25 14:57:27.91: [11077]: <-- [5:ATM0\r]
Mar 25 14:57:27.92: [11077]: --> [2:OK]
Mar 25 14:57:27.92: [11077]: STATE CHANGE: RUNNING -> SENDING
Mar 25 14:57:27.92: [11077]: MODEM input buffering enabled
Mar 25 14:57:27.92: [11077]: Apply DialString rules to "03472020"
Mar 25 14:57:27.92: [11077]: --> return result "03472020"
Mar 25 14:57:27.92: [11077]: <-- [12:AT+FCLASS=1\r]
Mar 25 14:57:28.03: [11077]: --> [2:OK]
Mar 25 14:57:28.06: [11077]: DIAL 03472020
Mar 25 14:57:28.06: [11077]: <-- [13:ATDT03472020\r]
Mar 25 14:57:45.66: [11077]: --> [7:CONNECT]
Mar 25 14:57:45.66: [11077]: MODEM input buffering disabled
Mar 25 14:57:47.02: [11077]: --> HDLC<25:FF C0 02 04 04 04 04 04 04 04 04 04
0C 4C 0C 4C EC 2C CC 8C 6C CC D4 43 F9>
Mar 25 14:57:47.04: [11077]: --> [2:OK]
Mar 25 14:57:47.04: [11077]: REMOTE CSI "+3613472020"
Mar 25 14:57:47.04: [11077]: <-- [9:AT+FRH=3\r]
Mar 25 14:57:47.05: [11077]: --> [7:CONNECT]
Mar 25 14:57:47.29: [11077]: --> HDLC<9:FF C8 01 00 77 5F 00 F1 7A>
Mar 25 14:57:47.30: [11077]: --> [2:OK]
Mar 25 14:57:47.30: [11077]: REMOTE best rate 14400 bit/s
Mar 25 14:57:47.30: [11077]: REMOTE max page width 2432 pixels in 303 mm
Mar 25 14:57:47.30: [11077]: REMOTE max unlimited page length
Mar 25 14:57:47.30: [11077]: REMOTE best vres 7.7 line/mm
Mar 25 14:57:47.30: [11077]: REMOTE best format 2-D MR
Mar 25 14:57:47.30: [11077]: REMOTE best 0 ms/scanline
Mar 25 14:57:47.30: [11077]: USE 14400 bit/s
Mar 25 14:57:47.30: [11077]: USE 0 ms/scanline
Mar 25 14:57:47.30: [11077]: SEND file "docq/doc13740.ps;70"
Mar 25 14:57:47.30: [11077]: USE page width 1728 pixels in 215 mm
Mar 25 14:57:47.30: [11077]: USE unlimited page length
Mar 25 14:57:47.30: [11077]: USE 3.85 line/mm
Mar 25 14:57:47.30: [11077]: USE 2-D MR
Mar 25 14:57:47.30: [11077]: SEND training at v.17 14400 bit/s
Mar 25 14:57:47.30: [11077]: <-- [9:AT+FTH=3\r]
Mar 25 14:57:47.35: [11077]: --> [7:CONNECT]
Mar 25 14:57:47.35: [11077]: <-- HDLC<23:FF C0 C2 0C 4C 0C 4C EC 2C CC 8C 6C
CC D4 04 04 04 04 04 04 04 04 04>
Mar 25 14:57:47.35: [11077]: <-- data [23]
Mar 25 14:57:47.35: [11077]: <-- data [2]
Mar 25 14:57:49.14: [11077]: --> [7:CONNECT]
Mar 25 14:57:49.14: [11077]: <-- HDLC<6:FF C8 C1 00 45 1E>
Mar 25 14:57:49.14: [11077]: <-- data [6]
Mar 25 14:57:49.14: [11077]: <-- data [2]
Mar 25 14:57:49.57: [11077]: --> [2:OK]
Mar 25 14:57:49.57: [11077]: DELAY 75 ms
Mar 25 14:57:49.65: [11077]: <-- [11:AT+FTM=145\r]
Mar 25 14:57:49.68: [11077]: --> [7:CONNECT]
Mar 25 14:57:49.68: [11077]: <-- data [1024]
Mar 25 14:57:49.68: [11077]: <-- data [1024]
Mar 25 14:57:49.68: [11077]: <-- data [652]
Mar 25 14:57:49.68: [11077]: <-- data [2]
Mar 25 14:57:52.80: [11077]: --> [2:OK]
Mar 25 14:57:52.80: [11077]: <-- [9:AT+FRH=3\r]
Mar 25 14:57:54.22: [11077]: --> [7:CONNECT]
Mar 25 14:57:55.11: [11077]: --> HDLC<5:FF C8 21 57 BE>
Mar 25 14:57:55.12: [11077]: --> [2:OK]
Mar 25 14:57:55.12: [11077]: TRAINING succeeded
Mar 25 14:57:55.12: [11077]: <-- [11:AT+FTM=146\r]
Mar 25 14:57:55.15: [11077]: --> [7:CONNECT]
Mar 25 14:57:55.15: [11077]: SEND begin page
Mar 25 14:57:55.15: [11077]: <-- data [953]
Mar 25 14:57:55.15: [11077]: SENT 948 bytes of data
Mar 25 14:57:55.15: [11077]: SEND 2D RTC
Mar 25 14:57:55.15: [11077]: <-- data [30]
Mar 25 14:57:55.16: [11077]: <-- data [2]
Mar 25 14:57:55.16: [11077]: SEND end page
Mar 25 14:57:56.06: [11077]: --> [2:OK]
Mar 25 14:57:56.06: [11077]: DELAY 95 ms
Mar 25 14:57:56.16: [11077]: SEND send EOP (no more pages or documents)
Mar 25 14:57:56.16: [11077]: <-- [9:AT+FTH=3\r]
Mar 25 14:57:56.21: [11077]: --> [7:CONNECT]
Mar 25 14:57:56.21: [11077]: <-- HDLC<3:FF C8 F4>
Mar 25 14:57:56.21: [11077]: <-- data [3]
Mar 25 14:57:56.21: [11077]: <-- data [2]
Mar 25 14:57:57.53: [11077]: --> [2:OK]
Mar 25 14:57:57.53: [11077]: <-- [9:AT+FRH=3\r]
Mar 25 14:58:00.63: [11077]: --> [0:]
Mar 25 14:58:00.63: [11077]: MODEM <Empty line>
Mar 25 14:58:00.63: [11077]: MODEM TIMEOUT: waiting for v.21 carrier
Mar 25 14:58:00.63: [11077]: <-- data [1]
Mar 25 14:58:00.69: [11077]: --> [2:OK]
Mar 25 14:58:00.69: [11077]: SEND send EOP (no more pages or documents)
Mar 25 14:58:00.69: [11077]: <-- [9:AT+FTH=3\r]
Mar 25 14:58:00.74: [11077]: --> [7:CONNECT]
Mar 25 14:58:00.74: [11077]: <-- HDLC<3:FF C8 F4>
Mar 25 14:58:00.74: [11077]: <-- data [3]
Mar 25 14:58:00.74: [11077]: <-- data [2]
Mar 25 14:58:02.06: [11077]: --> [2:OK]
Mar 25 14:58:02.06: [11077]: <-- [9:AT+FRH=3\r]
Mar 25 14:58:05.16: [11077]: --> [0:]
Mar 25 14:58:05.16: [11077]: MODEM <Empty line>
Mar 25 14:58:05.16: [11077]: MODEM TIMEOUT: waiting for v.21 carrier
Mar 25 14:58:05.16: [11077]: <-- data [1]
Mar 25 14:58:05.22: [11077]: --> [2:OK]
Mar 25 14:58:05.22: [11077]: SEND send EOP (no more pages or documents)
Mar 25 14:58:05.22: [11077]: <-- [9:AT+FTH=3\r]
Mar 25 14:58:05.27: [11077]: --> [7:CONNECT]
Mar 25 14:58:05.27: [11077]: <-- HDLC<3:FF C8 F4>
Mar 25 14:58:05.27: [11077]: <-- data [3]
Mar 25 14:58:05.27: [11077]: <-- data [2]
Mar 25 14:58:06.59: [11077]: --> [2:OK]
Mar 25 14:58:06.59: [11077]: <-- [9:AT+FRH=3\r]
Mar 25 14:58:09.69: [11077]: --> [0:]
Mar 25 14:58:09.69: [11077]: MODEM <Empty line>
Mar 25 14:58:09.69: [11077]: MODEM TIMEOUT: waiting for v.21 carrier
Mar 25 14:58:09.69: [11077]: <-- data [1]
Mar 25 14:58:09.75: [11077]: --> [2:OK]
Mar 25 14:58:09.75: [11077]: No response to MPS or EOP repeated 3 tries
Mar 25 14:58:09.75: [11077]: <-- [9:AT+FTH=3\r]
Mar 25 14:58:09.80: [11077]: --> [7:CONNECT]
Mar 25 14:58:09.80: [11077]: <-- HDLC<3:FF C8 DF>
Mar 25 14:58:09.80: [11077]: <-- data [3]
Mar 25 14:58:09.80: [11077]: <-- data [2]
Mar 25 14:58:11.12: [11077]: --> [2:OK]
Mar 25 14:58:11.12: [11077]: MODEM input buffering enabled
Mar 25 14:58:11.12: [11077]: <-- [5:ATH0\r]
Mar 25 14:58:13.05: [11077]: --> [2:OK]
Mar 25 14:58:13.05: [11077]: MODEM set DTR OFF
Mar 25 14:58:13.05: [11077]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Mar 25 14:58:13.05: [11077]: SESSION END
The Receiver.conf:
ModemType: Class2.0 # use class 2.0 interface
ModemRate: 38400 # 38.4 works fine
ModemFlowControl: rtscts # default
ModemNoFlowCmd: AT&H0 # disable flow control
ModemHardFlowCmd: AT&H3 # hardware flow control
ModemSoftFlowCmd: AT&H4 # software flow control
ModemSetupDTRCmd: AT&D2 # DTR off causes modem to hang up
ModemSetupDCDCmd: AT&C1 # DCD tracks carrier
ModemSetupAACmd: AT+FAA=1 # enable in current class
# Additional reset commands:
# &B1 DTE-DCE rate is fixed at DTE setting
# &N0 Auto-negotiate highest possible DCE-DCE link rate
# &S0 DSR always on
# *F0 Deny remote configuration
# (actually *F0 doesn't work in the firmware -- sigh)
# S18=4 Receive at 38400
# S38.3=1 DCD on/off sequence follows UNIX standard; also
# fix receiving baud rate at S18 value
#
ModemResetCmds: AT&B1&N0&S0S18=4S38.3=1
ModemDialCmd: ATDT%s # no '@' 'cuz then busy not
recognized
NoCarrierRetrys: 3 # retry 3 times on no carrier
ModemSetVolumeCmd: "ATM0 ATM1L1 ATM1L3 ATM1L5 ATM1L7"
#
# Caller ID configuration setup.
#
#QualifyCID: etc/cid # CID access control list file
#CIDNumber: "CALLER NUMBER: " # pattern string for phone number
info
#CIDName: "CALLER NAME: " # pattern string for identity info
PagerSetupCmds: AT&K0&N15 # use V.22 at 1200 bps (PageNet)
The Receive log:
Mar 25 14:57:33.81: [10212]: SESSION BEGIN 00030922 3613472020
Mar 25 14:57:33.81: [10212]: <-- [4:ATA\r]
Mar 25 14:57:49.63: [10212]: --> [4:+FCO]
Mar 25 14:57:49.65: [10212]: ANSWER: FAX CONNECTION
Mar 25 14:57:49.65: [10212]: STATE CHANGE: ANSWERING -> RECEIVING
Mar 25 14:57:49.66: [10212]: MODEM input buffering enabled
Mar 25 14:57:49.66: [10212]: RECV FAX: begin
Mar 25 14:57:53.63: [10212]: --> [28:+FTI:" +3613472020" ]
Mar 25 14:57:53.63: [10212]: REMOTE TSI "+3613472020"
Mar 25 14:57:53.63: [10212]: --> [20:+FCS:0,5,0,2,1,0,0,0]
Mar 25 14:57:53.63: [10212]: REMOTE wants 14400 bit/s
Mar 25 14:57:53.63: [10212]: REMOTE wants page width 1728 pixels in 215 mm
Mar 25 14:57:53.63: [10212]: REMOTE wants unlimited page length
Mar 25 14:57:53.63: [10212]: REMOTE wants 3.85 line/mm
Mar 25 14:57:53.63: [10212]: REMOTE wants 2-D MR
Mar 25 14:57:53.63: [10212]: --> [2:OK]
Mar 25 14:57:53.66: [10212]: <-- [7:AT+FDR\r]
Mar 25 14:57:55.22: [10212]: --> [20:+FCS:0,5,0,2,1,0,0,0]
Mar 25 14:57:55.22: [10212]: REMOTE wants 14400 bit/s
Mar 25 14:57:55.22: [10212]: REMOTE wants page width 1728 pixels in 215 mm
Mar 25 14:57:55.22: [10212]: REMOTE wants unlimited page length
Mar 25 14:57:55.22: [10212]: REMOTE wants 3.85 line/mm
Mar 25 14:57:55.22: [10212]: REMOTE wants 2-D MR
Mar 25 14:57:55.62: [10212]: --> [7:CONNECT]
Mar 25 14:57:55.62: [10212]: RECV: begin page
Mar 25 14:57:55.62: [10212]: RECV: send trigger 022
Mar 25 14:57:55.62: [10212]: <-- data [1]
Mar 25 14:57:55.79: [10212]: RECV/CQ: Bad 1D pixel count, row 10, got 1737,
expected 1728
Mar 25 14:57:56.05: [10212]: RECV/CQ: Bad 1D pixel count, row 99, got 0,
expected 1728
Mar 25 14:57:56.05: [10212]: RECV/CQ: Bad 1D pixel count, row 100, got 0,
expected 1728
Mar 25 14:57:56.05: [10212]: RECV/CQ: Bad 1D pixel count, row 101, got 0,
expected 1728
Mar 25 14:57:56.05: [10212]: RECV/CQ: Bad 1D pixel count, row 102, got 0,
expected 1728
Mar 25 14:57:56.16: [10212]: RECV/CQ: Bad 1D pixel count, row 103, got 0,
expected 1728
Mar 25 14:57:56.16: [10212]: RECV: 1039 bytes of data, 104 total lines
Mar 25 14:57:56.16: [10212]: --> [15:+FPS:2,63,0,0,0]
Mar 25 14:57:56.16: [10212]: --> [7:+FHS:91]
Mar 25 14:57:56.18: [10212]: REMOTE HANGUP: Missing EOL after 5 seconds
(section 3.2/T.4) (code 91)
Mar 25 14:57:56.18: [10212]: --> [5:ERROR]
Mar 25 14:57:56.19: [10212]: RECV FAX (00030922): recvq/fax09692.tif from
+3613472020, route to <unspecified>, 0 pages in 0:07
Mar 25 14:57:56.19: [10212]: RECV FAX: Missing EOL after 5 seconds (section
3.2/T.4)
Mar 25 14:57:56.19: [10212]: RECV FAX (00030922): session with +3613472020
terminated abnormally: Missing EOL after 5 seconds
Mar 25 14:57:56.19: [10212]: RECV FAX: bin/faxrcvd "recvq/fax09692.tif"
"ttyM2" "00030922" "Missing EOL after 5 seconds (secti
Mar 25 14:57:56.62: [10212]: RECV FAX: end
Mar 25 14:57:56.63: [10212]: SESSION END
____________________ HylaFAX(tm) Users Mailing List _______________________
To unsub: mail -s unsubscribe hylafax-users-request@hylafax.org < /dev/null