[hylafax-users] RSPREC error/got DCN

["Frederic Trudeau" <ftrudeau@xxxxxxx>]

Well, here is an little update. I got bored, and re-installed from scratch,
by rebuilding the RH9 SRPM on the hylafax FTP server.
Im using Fedora Core 2. Compiliation went well, nothing to say here. I
configured everything exactly as before, appart from the init line
for faxgetty that i added in the file /etc/inittab

ie :
mo:2345:respawn:/usr/sbin/faxgetty ttyS0

This installation from scratch seams to have resolved my problem of "waiting
for modem to come ready", but now, I cannot even send anymore :

[root@fax etc]# faxstat -s
HylaFAX scheduler on fax.cam.org: Running
Modem ttyS0 (+1.514.596.2268): Running and idle

JID  Pri S  Owner Number       Pages Dials     TTS Status
2    125 R   root 5145293300    0:1   2:12         RSPREC error/got DCN


See some output from /var/log/messages

Jun 28 18:59:47 fax FaxGetty[1320]: LOCKWAIT
Jun 28 19:00:12 fax FaxSend[1511]: MODEM USR Sportster 28800/Fax
V6.0/Configuration Profile... Product type           US/Canada External
Options                V32bis,V.FC,V.34 Fax Options            Class 1/Class
2.0 Clock Freq             20.16Mhz Eprom                  256k Ram
32k Supervisor date        04/18/95 DSP date               03/31/95
Supervisor rev         6.0.5 DSP rev                1.0.9
Jun 28 19:00:12 fax FaxSend[1511]: SEND FAX: JOB 2 DEST 5145293300 COMMID
000000006 DEVICE '/dev/ttyS0'


And debug output from the last fax attempt :

Jun 28 19:00:12.39: [ 1511]: SESSION BEGIN 000000006 15145145293300
Jun 28 19:00:12.39: [ 1511]: HylaFAX (tm) Version 4.1.8
Jun 28 19:00:12.39: [ 1511]: SEND FAX: JOB 2 DEST 5145293300 COMMID
000000006 DEVICE '/dev/ttyS0'
Jun 28 19:00:12.39: [ 1511]: DELAY 75 ms
Jun 28 19:00:12.47: [ 1511]: DELAY 2600 ms
Jun 28 19:00:16.07: [ 1511]: DELAY 10 ms
Jun 28 19:00:16.08: [ 1511]: <-- [4:ATZ\r]
Jun 28 19:00:16.39: [ 1511]: --> [3:ATZ]
Jun 28 19:00:16.40: [ 1511]: --> [2:OK]
Jun 28 19:00:16.40: [ 1511]: DELAY 3000 ms
Jun 28 19:00:19.39: [ 1511]: <-- [5:ATE0\r]
Jun 28 19:00:19.56: [ 1511]: --> [4:ATE0]
Jun 28 19:00:19.56: [ 1511]: --> [2:OK]
Jun 28 19:00:19.56: [ 1511]: <-- [5:ATV1\r]
Jun 28 19:00:19.72: [ 1511]: --> [2:OK]
Jun 28 19:00:19.72: [ 1511]: <-- [7:ATQ0X4\r]
Jun 28 19:00:19.89: [ 1511]: --> [2:OK]
Jun 28 19:00:19.89: [ 1511]: <-- [7:ATS0=0\r]
Jun 28 19:00:20.05: [ 1511]: --> [2:OK]
Jun 28 19:00:20.05: [ 1511]: <-- [7:ATS8=2\r]
Jun 28 19:00:20.22: [ 1511]: --> [2:OK]
Jun 28 19:00:20.22: [ 1511]: <-- [8:ATS7=60\r]
Jun 28 19:00:20.39: [ 1511]: --> [2:OK]
Jun 28 19:00:20.39: [ 1511]: <-- [12:AT&H2&I2&R1\r]
Jun 28 19:00:20.56: [ 1511]: --> [2:OK]
Jun 28 19:00:20.56: [ 1511]: <-- [11:ATS13=1&D2\r]
Jun 28 19:00:20.73: [ 1511]: --> [2:OK]
Jun 28 19:00:20.73: [ 1511]: <-- [6:AT&C1\r]
Jun 28 19:00:20.89: [ 1511]: --> [2:OK]
Jun 28 19:00:20.89: [ 1511]: <-- [14:AT+FCLASS=2.0\r]
Jun 28 19:00:20.94: [ 1511]: --> [2:OK]
Jun 28 19:00:20.94: [ 1511]: <-- [9:AT+FLO=1\r]
Jun 28 19:00:20.98: [ 1511]: --> [2:OK]
Jun 28 19:00:20.98: [ 1511]: <-- [9:AT+FPP=0\r]
Jun 28 19:00:21.03: [ 1511]: --> [2:OK]
Jun 28 19:00:21.03: [ 1511]: <-- [9:AT+FBO=0\r]
Jun 28 19:00:21.07: [ 1511]: --> [2:OK]
Jun 28 19:00:21.07: [ 1511]: <-- [10:AT+FCT=30\r]
Jun 28 19:00:21.12: [ 1511]: --> [2:OK]
Jun 28 19:00:21.12: [ 1511]: <-- [15:AT+FNR=1,1,1,0\r]
Jun 28 19:00:21.16: [ 1511]: --> [2:OK]
Jun 28 19:00:21.16: [ 1511]: <-- [9:AT+FIE=0\r]
Jun 28 19:00:21.21: [ 1511]: --> [2:OK]
Jun 28 19:00:21.21: [ 1511]: <-- [23:AT+FCC=1,5,2,2,0,0,0,0\r]
Jun 28 19:00:21.26: [ 1511]: --> [2:OK]
Jun 28 19:00:21.26: [ 1511]: <-- [5:ATM0\r]
Jun 28 19:00:21.42: [ 1511]: --> [2:OK]
Jun 28 19:00:21.42: [ 1511]: <-- [14:AT+FCLASS=2.0\r]
Jun 28 19:00:21.57: [ 1511]: --> [2:OK]
Jun 28 19:00:21.57: [ 1511]: <-- [9:AT+FLO=1\r]
Jun 28 19:00:21.71: [ 1511]: --> [2:OK]
Jun 28 19:00:21.71: [ 1511]: <-- [9:AT+FPP=0\r]
Jun 28 19:00:21.86: [ 1511]: --> [2:OK]
Jun 28 19:00:21.86: [ 1511]: <-- [9:AT+FBO=0\r]
Jun 28 19:00:22.00: [ 1511]: --> [2:OK]
Jun 28 19:00:22.00: [ 1511]: <-- [10:AT+FCT=30\r]
Jun 28 19:00:22.15: [ 1511]: --> [2:OK]
Jun 28 19:00:22.15: [ 1511]: <-- [15:AT+FNR=1,1,1,0\r]
Jun 28 19:00:22.29: [ 1511]: --> [2:OK]
Jun 28 19:00:22.29: [ 1511]: <-- [9:AT+FIE=0\r]
Jun 28 19:00:22.44: [ 1511]: --> [2:OK]
Jun 28 19:00:22.44: [ 1511]: <-- [23:AT+FCC=1,5,2,2,0,0,0,0\r]
Jun 28 19:00:22.59: [ 1511]: --> [2:OK]
Jun 28 19:00:22.59: [ 1511]: <-- [22:AT+FLI="NothingSetup"\r]
Jun 28 19:00:22.74: [ 1511]: --> [2:OK]
Jun 28 19:00:22.74: [ 1511]: DIAL 5145293300
Jun 28 19:00:22.74: [ 1511]: <-- [15:ATDT5145293300\r]
Jun 28 19:00:48.89: [ 1511]: --> [4:+FCO]
Jun 28 19:00:51.69: [ 1511]: --> [27:+FCI:"          5145293300"]
Jun 28 19:00:51.69: [ 1511]: REMOTE CSI "5145293300"
Jun 28 19:00:52.11: [ 1511]: --> [20:+FIS:1,5,0,2,1,0,0,4]
Jun 28 19:00:52.11: [ 1511]: --> [2:OK]
Jun 28 19:00:52.11: [ 1511]: REMOTE best rate 14400 bit/s
Jun 28 19:00:52.11: [ 1511]: REMOTE max page width 1728 pixels in 215 mm
Jun 28 19:00:52.11: [ 1511]: REMOTE max unlimited page length
Jun 28 19:00:52.11: [ 1511]: REMOTE best vres 7.7 line/mm
Jun 28 19:00:52.11: [ 1511]: REMOTE best format 2-D MR
Jun 28 19:00:52.11: [ 1511]: REMOTE best 20 ms, 10 ms/scanline
Jun 28 19:00:52.11: [ 1511]: USE 14400 bit/s
Jun 28 19:00:52.11: [ 1511]: USE 20 ms, 10 ms/scanline
Jun 28 19:00:52.11: [ 1511]: SEND file "docq/doc3.ps;01"
Jun 28 19:00:52.11: [ 1511]: USE page width 1728 pixels in 215 mm
Jun 28 19:00:52.11: [ 1511]: USE unlimited page length
Jun 28 19:00:52.11: [ 1511]: USE 7.7 line/mm
Jun 28 19:00:52.11: [ 1511]: USE 1-D MR
Jun 28 19:00:52.11: [ 1511]: <-- [23:AT+FIS=1,5,0,2,0,0,0,4\r]
Jun 28 19:00:52.26: [ 1511]: --> [2:OK]
Jun 28 19:00:52.26: [ 1511]: <-- [7:AT+FDT\r]
Jun 28 19:00:52.41: [ 1511]: --> [20:+FCS:1,5,0,2,0,0,0,5]
Jun 28 19:00:58.77: [ 1511]: --> [7:CONNECT]
Jun 28 19:00:58.77: [ 1511]: SEND begin page
Jun 28 19:00:58.77: [ 1511]: <-- data [1026]
Jun 28 19:00:59.16: [ 1511]: <-- data [1028]
Jun 28 19:00:59.56: [ 1511]: <-- data [1026]
Jun 28 19:00:59.96: [ 1511]: <-- data [1026]
Jun 28 19:01:00.76: [ 1511]: <-- data [1027]
Jun 28 19:01:01.16: [ 1511]: <-- data [1027]
Jun 28 19:01:01.56: [ 1511]: <-- data [1029]
Jun 28 19:01:02.36: [ 1511]: <-- data [1025]
Jun 28 19:01:02.76: [ 1511]: <-- data [349]
Jun 28 19:01:02.76: [ 1511]: SENT 8540 bytes of data
Jun 28 19:01:03.23: [ 1511]: SEND end page
Jun 28 19:01:03.23: [ 1511]: SEND send EOP (no more pages or documents)
Jun 28 19:01:03.23: [ 1511]: <-- data [2]
Jun 28 19:01:29.94: [ 1511]: --> [7:+FHS:51]
Jun 28 19:01:29.94: [ 1511]: REMOTE HANGUP: RSPREC error/got DCN (code 51)
Jun 28 19:01:29.94: [ 1511]: SEND: RSPREC error/got DCN; Giving up after 3
attempts to send same page "docq/doc3.ps;01", dirnum 0
Jun 28 19:01:29.94: [ 1511]: <-- [5:ATH0\r]
Jun 28 19:01:29.94: [ 1511]: --> [2:OK]
Jun 28 19:01:29.95: [ 1511]: SESSION END


Thanks again for your support.

-
Frederic Trudeau <ftrudeau@xxxxxxx>
Programmation / Departement reseau
Web: http://www.cam.org

----- Original Message ----- 
From: "Frederic Trudeau" <ftrudeau@xxxxxxx>
To: <hylafax-users@xxxxxxxxxxx>
Sent: Monday, June 28, 2004 4:41 PM
Subject: Hylafax weird behaviour with a USRobotics Sportster 28800 V.34


>
> Greetings list.
>
> I already posted this list last week, regarding the message "Waiting for
> modem to come ready" that I constantly have
> when running faxstat. I fixed part of my problem by using the right config
> (usr-xon in Class1)
>
> Hylafax now sends 3 or 4 jobs properlly, but seams to get stuck in the
> process somewhere, and I have no debug information to show you.
> Restarting hylafax does not fix this. Hence, I would need some pointers
how
> to get some. No idea if the problem is related to the modem
> config, the modem itself, or some other configuration settings.
>
> It seams the only way I can get hylafax to work properlly again (for 3 or
4
> job, still) is to re-run faxsetup once the queue seams to `stuck`, remove
> the modem config and re-run faxaddmodem. Now this is getting annoying.
>
> I send a test fax, about 5 minutes ago, and it is still queued, and i have
> no message whatsoever in any log file :
>
> [root@fax etc]# faxstat -sv
> Trying localhost (127.0.0.1) at port 4559...
> Connected to localhost.localdomain.
> 220 fax.cam.org server (HylaFAX (tm) Version 4.1.8) ready.
> -> USER root
> 230 User root logged in.
> -> PORT 127,0,0,1,14,88
> 200 PORT command successful.
> -> LIST status
> 150 Opening new data connection for "status".
> HylaFAX scheduler on fax.cam.org: Running
> Modem ttyS0 (+1.514.596.2268): Waiting for modem to come ready
> 226 Transfer complete.
> -> JOBFMT
> 200 %-4j %3i %1a %6.6o %-12.12e %5P %5D %7z %.25s
> -> PORT 127,0,0,1,14,89
> 200 PORT command successful.
> -> LIST sendq
> 150 Opening new data connection for "sendq".
>
> JID  Pri S  Owner Number       Pages Dials     TTS Status
> 446  127 W   root 5145293300    0:0   0:12
> 226 Transfer complete.
>
>
> Im not sure what to do next in order to debug this.
> Any pointers appreciated.
>
> -
> Frederic Trudeau <ftrudeau@xxxxxxx>
> Programmation / Departement reseau
> Communications Accessibles Montreal
>


____________________ HylaFAX(tm) Users Mailing List _______________________
  To subscribe/unsubscribe, click http://lists.hylafax.org/cgi-bin/lsg2.cgi
 On UNIX: mail -s unsubscribe hylafax-users-request@xxxxxxxxxxx < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@xxxxxxxxx*