Re: flexfax: "No response to MPS or EOP" problem? - more info - logs

["Philip Rhoades" <philr@mail.austasia.net>]

Hereunder the two logs from efax and hylafax.

Regards,

Phil.

Philip Rhoades

Pricom Pty Limited   (ACN  003 252 275)
GPO Box 3411
Sydney NSW 2001
Australia
Mobile:  +61:0411-185-652
Fax:  +61:2:9959-4909
E-mail:  philr@mail.austasia.net


>>I tried lowering the transmission rate with no success so I converted the
>>postscript file to a tiff file and sent it with exactly the same ("No
>>response to MPS or EOP") result but when I use efax to send to that very
>>same fax machine it works no problem - so the problem is with Hylafax.
>>
>>efax is a simple little program but it works! - why is a sophisticated
>>program like Hylafax having problems?
>
>Could you provide a trace log of efaxes successful session and hylafaxes
>failed session to the same destination.  Without a log, it is almost
>impossible to determine what went wrong.
>
>- Robert


From: Robert Colquhoun <rjc@trump.net.au>


efax: 58:19 opened /dev/cua1
efax: 58:19 dialing 08-8232-2924
efax: 58:40 connected
efax: 58:41 received NSF - answering features
efax: 58:42 received CSI - answering ID
efax: 58:42 remote ID ->          61882322924
efax: 58:43 received DIS - answering capabilities
efax: 58:43 remote has no document(s) to send, and can receive
efax: 58:43 local   196lpi  9600bps 8.5"/215mm  any   1D    -     -  0ms
efax: 58:43 remote  196lpi  9600bps 8.5"/215mm  any   2D ECM-64   -  20/10ms
efax: 58:43 session 196lpi  9600bps 8.5"/215mm  any   1D    -     -  10ms
efax: 58:43 sent TSI - caller ID
efax: 58:44 sent DCS - session format
efax: 58:47 sent TCF - channel check of 1800 bytes
efax: 58:49 received CFR - channel OK
efax: 58:49 header:[Sun May 16 19:58:19 1999                        P. 1]
efax: 59:28 sent 20+2292 lines, 33357+13692 bytes, 39 s  9651 bps
efax: 59:29 sent EOP - done
efax: 59:32 received MCF - page OK
efax: 59:32 sent -> /home/phil/tmp/fax.001
efax: 59:32 sent DCN - disconnect
efax: 59:36 done, returning 0


May 08 01:48:14.10: [ 7680]: SESSION BEGIN 00000075 6120882322924
May 08 01:48:14.10: [ 7680]: SEND FAX: JOB 44 DEST 08-8232-2924 COMMID
00000075
May 08 01:48:14.10: [ 7680]: DELAY 2600 ms
May 08 01:48:16.71: [ 7680]: <-- [15:ATE0V1Q0S0=0H0\r]
May 08 01:48:16.73: [ 7680]: --> [2:OK]
May 08 01:48:16.73: [ 7680]: <-- [12:ATS8=2S7=60\r]
May 08 01:48:16.75: [ 7680]: --> [2:OK]
May 08 01:48:16.75: [ 7680]: <-- [12:AT+FCLASS=1\r]
May 08 01:48:16.77: [ 7680]: --> [2:OK]
May 08 01:48:16.77: [ 7680]: <-- [12:AT+FCLASS=1\r]
May 08 01:48:16.89: [ 7680]: --> [2:OK]
May 08 01:48:16.89: [ 7680]: DIAL 0882322924
May 08 01:48:16.89: [ 7680]: <-- [15:ATDT0882322924\r]
May 08 01:48:35.60: [ 7680]: --> [7:CONNECT]
May 08 01:48:36.82: [ 7680]: --> [2:OK]
May 08 01:48:36.82: [ 7680]: <-- [9:AT+FRH=3\r]
May 08 01:48:36.84: [ 7680]: --> [7:CONNECT]
May 08 01:48:37.54: [ 7680]: --> [2:OK]
May 08 01:48:37.54: [ 7680]: REMOTE CSI "61882322924"
May 08 01:48:37.54: [ 7680]: <-- [9:AT+FRH=3\r]
May 08 01:48:37.56: [ 7680]: --> [7:CONNECT]
May 08 01:48:37.87: [ 7680]: --> [2:OK]
May 08 01:48:37.87: [ 7680]: REMOTE best rate 9600 bit/s
May 08 01:48:37.87: [ 7680]: REMOTE max page width 1728 pixels in 215 mm
May 08 01:48:37.87: [ 7680]: REMOTE max unlimited page length
May 08 01:48:37.87: [ 7680]: REMOTE best vres 7.7 line/mm
May 08 01:48:37.87: [ 7680]: REMOTE best format 2-D MMR
May 08 01:48:37.87: [ 7680]: REMOTE supports T.30 Annex A, ECM
May 08 01:48:37.87: [ 7680]: REMOTE best 20 ms, 10 ms/scanline
May 08 01:48:37.87: [ 7680]: USE 9600 bit/s
May 08 01:48:37.87: [ 7680]: USE 20 ms, 10 ms/scanline
May 08 01:48:37.87: [ 7680]: SEND file "docq/doc45.ps;31"
May 08 01:48:37.87: [ 7680]: USE page width 1728 pixels in 215 mm
May 08 01:48:37.87: [ 7680]: USE unlimited page length
May 08 01:48:37.87: [ 7680]: USE 7.7 line/mm
May 08 01:48:37.87: [ 7680]: USE 1-D MR
May 08 01:48:37.87: [ 7680]: SEND training at v.29 9600 bit/s
May 08 01:48:37.87: [ 7680]: <-- [9:AT+FTH=3\r]
May 08 01:48:37.92: [ 7680]: --> [7:CONNECT]
May 08 01:48:37.92: [ 7680]: <-- data [23]
May 08 01:48:37.92: [ 7680]: <-- data [2]
May 08 01:48:39.71: [ 7680]: --> [7:CONNECT]
May 08 01:48:39.71: [ 7680]: <-- data [6]
May 08 01:48:39.71: [ 7680]: <-- data [2]
May 08 01:48:40.14: [ 7680]: --> [2:OK]
May 08 01:48:40.14: [ 7680]: DELAY 75 ms
May 08 01:48:40.23: [ 7680]: <-- [10:AT+FTM=96\r]
May 08 01:48:40.29: [ 7680]: --> [7:CONNECT]
May 08 01:48:40.29: [ 7680]: <-- data [1024]
May 08 01:48:40.29: [ 7680]: <-- data [776]
May 08 01:48:40.29: [ 7680]: <-- data [2]
May 08 01:48:42.25: [ 7680]: --> [2:OK]
May 08 01:48:42.25: [ 7680]: <-- [9:AT+FRH=3\r]
May 08 01:48:43.11: [ 7680]: --> [7:CONNECT]
May 08 01:48:44.00: [ 7680]: --> [2:OK]
May 08 01:48:44.00: [ 7680]: TRAINING succeeded
May 08 01:48:44.00: [ 7680]: <-- [10:AT+FTM=96\r]
May 08 01:48:44.06: [ 7680]: --> [7:CONNECT]
May 08 01:48:44.06: [ 7680]: SEND begin page
May 08 01:48:44.06: [ 7680]: <-- data [1025]
May 08 01:48:44.06: [ 7680]: <-- data [1024]
May 08 01:48:44.06: [ 7680]: <-- data [1024]
May 08 01:48:44.06: [ 7680]: <-- data [1024]
May 08 01:48:44.06: [ 7680]: <-- data [1026]
May 08 01:48:46.06: [ 7680]: <-- data [1025]
May 08 01:48:46.06: [ 7680]: <-- data [1026]
May 08 01:48:46.06: [ 7680]: <-- data [1025]
May 08 01:48:46.06: [ 7680]: <-- data [1026]
May 08 01:48:46.06: [ 7680]: <-- data [1026]
May 08 01:48:48.05: [ 7680]: <-- data [1024]
May 08 01:48:48.05: [ 7680]: <-- data [1024]
May 08 01:48:48.05: [ 7680]: <-- data [1028]
May 08 01:48:48.05: [ 7680]: <-- data [1024]
May 08 01:48:50.05: [ 7680]: <-- data [1025]
May 08 01:48:50.05: [ 7680]: <-- data [1024]
May 08 01:48:50.05: [ 7680]: <-- data [1024]
May 08 01:48:50.05: [ 7680]: <-- data [1024]
May 08 01:48:52.05: [ 7680]: <-- data [1028]
May 08 01:48:52.05: [ 7680]: <-- data [1025]
May 08 01:48:52.05: [ 7680]: <-- data [1025]
May 08 01:48:54.04: [ 7680]: <-- data [1024]
May 08 01:48:54.04: [ 7680]: <-- data [1028]
May 08 01:48:54.04: [ 7680]: <-- data [1024]
May 08 01:48:54.04: [ 7680]: <-- data [1025]
May 08 01:48:56.04: [ 7680]: <-- data [1027]
May 08 01:48:56.04: [ 7680]: <-- data [1025]
May 08 01:48:56.04: [ 7680]: <-- data [271]
May 08 01:48:56.04: [ 7680]: SENT 25870 bytes of data
May 08 01:48:56.04: [ 7680]: <-- data [1025]
May 08 01:48:56.04: [ 7680]: <-- data [1025]
May 08 01:48:58.04: [ 7680]: <-- data [1024]
May 08 01:48:58.04: [ 7680]: <-- data [1025]
May 08 01:48:58.04: [ 7680]: <-- data [1026]
May 08 01:49:00.03: [ 7680]: <-- data [1025]
May 08 01:49:00.03: [ 7680]: <-- data [1024]
May 08 01:49:00.03: [ 7680]: <-- data [1024]
May 08 01:49:00.03: [ 7680]: <-- data [1028]
May 08 01:49:02.03: [ 7680]: <-- data [1025]
May 08 01:49:02.03: [ 7680]: <-- data [1024]
May 08 01:49:02.03: [ 7680]: <-- data [1026]
May 08 01:49:02.03: [ 7680]: <-- data [1024]
May 08 01:49:04.03: [ 7680]: <-- data [1025]
May 08 01:49:04.03: [ 7680]: <-- data [1024]
May 08 01:49:04.03: [ 7680]: <-- data [1024]
May 08 01:49:04.03: [ 7680]: <-- data [1024]
May 08 01:49:06.02: [ 7680]: <-- data [1024]
May 08 01:49:06.02: [ 7680]: <-- data [525]
May 08 01:49:06.02: [ 7680]: SENT 18957 bytes of data
May 08 01:49:06.02: [ 7680]: SEND 1D RTC
May 08 01:49:06.02: [ 7680]: <-- data [29]
May 08 01:49:06.02: [ 7680]: <-- data [2]
May 08 01:49:06.02: [ 7680]: SEND end page
May 08 01:49:17.68: [ 7680]: --> [2:^S^Q]
May 08 01:49:17.68: [ 7680]: --> [2:OK]
May 08 01:49:17.68: [ 7680]: DELAY 95 ms
May 08 01:49:17.79: [ 7680]: SEND send EOP (no more pages or documents)
May 08 01:49:17.79: [ 7680]: <-- [9:AT+FTH=3\r]
May 08 01:49:17.84: [ 7680]: --> [7:CONNECT]
May 08 01:49:17.84: [ 7680]: <-- data [3]
May 08 01:49:17.84: [ 7680]: <-- data [2]
May 08 01:49:19.16: [ 7680]: --> [2:OK]
May 08 01:49:19.16: [ 7680]: <-- [9:AT+FRH=3\r]
May 08 01:49:22.26: [ 7680]: --> [0:]
May 08 01:49:22.26: [ 7680]: MODEM <Empty line>
May 08 01:49:22.26: [ 7680]: <-- data [1]
May 08 01:49:22.32: [ 7680]: --> [2:OK]
May 08 01:49:22.32: [ 7680]: SEND send EOP (no more pages or documents)
May 08 01:49:22.32: [ 7680]: <-- [9:AT+FTH=3\r]
May 08 01:49:22.38: [ 7680]: --> [7:CONNECT]
May 08 01:49:22.38: [ 7680]: <-- data [3]
May 08 01:49:22.38: [ 7680]: <-- data [2]
May 08 01:49:23.69: [ 7680]: --> [2:OK]
May 08 01:49:23.69: [ 7680]: <-- [9:AT+FRH=3\r]
May 08 01:49:26.79: [ 7680]: --> [0:]
May 08 01:49:26.79: [ 7680]: MODEM <Empty line>
May 08 01:49:26.79: [ 7680]: <-- data [1]
May 08 01:49:26.85: [ 7680]: --> [2:OK]
May 08 01:49:26.85: [ 7680]: SEND send EOP (no more pages or documents)
May 08 01:49:26.85: [ 7680]: <-- [9:AT+FTH=3\r]
May 08 01:49:26.91: [ 7680]: --> [7:CONNECT]
May 08 01:49:26.91: [ 7680]: <-- data [3]
May 08 01:49:26.91: [ 7680]: <-- data [2]
May 08 01:49:28.22: [ 7680]: --> [2:OK]
May 08 01:49:28.22: [ 7680]: <-- [9:AT+FRH=3\r]
May 08 01:49:31.32: [ 7680]: --> [0:]
May 08 01:49:31.32: [ 7680]: MODEM <Empty line>
May 08 01:49:31.32: [ 7680]: <-- data [1]
May 08 01:49:31.38: [ 7680]: --> [2:OK]
May 08 01:49:31.38: [ 7680]: SEND: No response to MPS or EOP repeated 3
tries; G
iving up after 3 attempts to send same page "docq/doc45.ps;31", dirnum 0
May 08 01:49:31.38: [ 7680]: <-- [9:AT+FTH=3\r]
May 08 01:49:31.43: [ 7680]: --> [7:CONNECT]
May 08 01:49:31.43: [ 7680]: <-- data [3]
May 08 01:49:31.43: [ 7680]: <-- data [2]
May 08 01:49:32.75: [ 7680]: --> [2:OK]
May 08 01:49:32.75: [ 7680]: <-- [5:ATH0\r]
May 08 01:49:35.31: [ 7680]: --> [2:OK]
May 08 01:49:35.31: [ 7680]: SESSION END