HylaFAX The world's most advanced open source fax server

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

Cover page transmitted 12+ times!



We have a number of Multi-Tech and Telebit T3000 modems connected to
Ultra-2s running 4.0pl2 under Solaris 2.6.  Though our Multi-Techs
work fine, the Telebit modems run into reproducible problems sending
to certain fax machines.

We often get into a situation where the cover page is retransmitted
3 times within the same call, then the call is terminated.  Our
clients see over a dozen cover pages with no content sitting on their
fax machines, and needless to say, they're not pleased.

Here's a comm log of such a transfer:
>
> Jul 08 11:06:50.54: [25708]: SESSION BEGIN 00019326 12128167766
> Jul 08 11:06:50.54: [25708]: SEND FAX: JOB 12038 DEST 12128167766 COMMID 00019326
> Jul 08 11:06:50.58: [25708]: DELAY 2600 ms
> Jul 08 11:06:55.34: [25708]: <-- [15:ATE0V1Q0S0=0H0\r]
> Jul 08 11:06:55.42: [25708]: --> [14:ATE0V1Q0S0=0H0]
> Jul 08 11:06:55.42: [25708]: --> [2:OK]
> Jul 08 11:06:55.42: [25708]: <-- [23:ATS8=2S7=90S58=2&D3&C1\r]
> Jul 08 11:06:55.50: [25708]: --> [2:OK]
> Jul 08 11:06:55.50: [25708]: <-- [12:AT+FCLASS=2\r]
> Jul 08 11:06:55.58: [25708]: --> [2:OK]
> Jul 08 11:06:55.58: [25708]: <-- [10:AT+FTBC=0\r]
> Jul 08 11:06:55.64: [25708]: --> [2:OK]
> Jul 08 11:06:55.64: [25708]: <-- [10:AT+FBOR=0\r]
> Jul 08 11:06:55.70: [25708]: --> [2:OK]
> Jul 08 11:06:55.70: [25708]: <-- [13:AT+FPHCTO=30\r]
> Jul 08 11:06:55.78: [25708]: --> [2:OK]
> Jul 08 11:06:55.78: [25708]: <-- [24:AT+FDCC=1,3,2,2,0,0,0,0\r]
> Jul 08 11:06:55.86: [25708]: --> [2:OK]
> Jul 08 11:06:55.86: [25708]: <-- [5:ATM0\r]
> Jul 08 11:06:55.92: [25708]: --> [2:OK]
> Jul 08 11:06:55.93: [25708]: <-- [12:AT+FCLASS=2\r]
> Jul 08 11:06:56.08: [25708]: --> [2:OK]
> Jul 08 11:06:56.08: [25708]: <-- [10:AT+FTBC=0\r]
> Jul 08 11:06:56.22: [25708]: --> [2:OK]
> Jul 08 11:06:56.22: [25708]: <-- [10:AT+FBOR=0\r]
> Jul 08 11:06:56.38: [25708]: --> [2:OK]
> Jul 08 11:06:56.38: [25708]: <-- [13:AT+FPHCTO=30\r]
> Jul 08 11:06:56.54: [25708]: --> [2:OK]
> Jul 08 11:06:56.54: [25708]: <-- [24:AT+FDCC=1,3,2,2,0,0,0,0\r]
> Jul 08 11:06:56.70: [25708]: --> [2:OK]
> Jul 08 11:06:56.70: [25708]: <-- [31:AT+FLID="Salomon Smith Barney"\r]
> Jul 08 11:06:56.86: [25708]: --> [2:OK]
> Jul 08 11:06:56.86: [25708]: <-- [24:AT+FDIS=0,3,0,2,0,0,0,0\r]
> Jul 08 11:06:57.02: [25708]: --> [2:OK]
> Jul 08 11:06:57.02: [25708]: DIAL 12128167766
> Jul 08 11:06:57.02: [25708]: <-- [20:ATX4DT9W12128167766\r]
> Jul 08 11:07:12.48: [25708]: --> [5:+FCON]
> Jul 08 11:07:15.74: [25708]: --> [78:+FNSF:00 00 A4 80 9A A2 72 4A 82 42 04 12 2A 92 B2 CA 04 72 F2 B2 F2 32 82 CA ]
> Jul 08 11:07:15.74: [25708]: REMOTE NSF "00 00 A4 80 9A A2 72 4A 82 42 04 12 2A 92 B2 CA 04 72 F2 B2 F2 32 82 CA"
> Jul 08 11:07:15.74: [25708]: --> [28:+FCSI:"        212 816 7766"]
> Jul 08 11:07:15.75: [25708]: REMOTE CSI "212 816 7766"
> Jul 08 11:07:15.75: [25708]: --> [21:+FDIS:1,3,0,2,3,0,0,2]
> Jul 08 11:07:15.75: [25708]: --> [2:OK]
> Jul 08 11:07:15.75: [25708]: REMOTE best rate 9600 bit/s
> Jul 08 11:07:15.75: [25708]: REMOTE max page width 1728 pixels in 215 mm
> Jul 08 11:07:15.75: [25708]: REMOTE max unlimited page length 
> Jul 08 11:07:15.75: [25708]: REMOTE best vres 7.7 line/mm
> Jul 08 11:07:15.75: [25708]: REMOTE best format 2-D MMR
> Jul 08 11:07:15.75: [25708]: REMOTE best 10 ms, 5 ms/scanline
> Jul 08 11:07:15.75: [25708]: USE 9600 bit/s
> Jul 08 11:07:15.75: [25708]: USE 10 ms, 5 ms/scanline
> Jul 08 11:07:15.75: [25708]: SEND file "docq/doc12038.cover;30"
> Jul 08 11:07:15.75: [25708]: USE page width 1728 pixels in 215 mm
> Jul 08 11:07:15.75: [25708]: USE unlimited page length 
> Jul 08 11:07:15.75: [25708]: USE 3.85 line/mm
> Jul 08 11:07:15.75: [25708]: USE 1-D MR
> Jul 08 11:07:15.75: [25708]: <-- [7:AT+FDT\r]
> Jul 08 11:07:21.78: [25708]: --> [21:+FDCS:0,3,0,2,0,0,0,3]
> Jul 08 11:07:21.94: [25708]: --> [7:CONNECT]
> Jul 08 11:07:21.94: [25708]: SEND wait for XON
> Jul 08 11:07:21.94: [25708]: --> [1:]
> Jul 08 11:07:21.94: [25708]: SEND begin page
> Jul 08 11:07:21.94: [25708]: <-- data [1030]
> Jul 08 11:07:21.94: [25708]: <-- data [1024]
> Jul 08 11:07:21.94: [25708]: <-- data [1037]
> Jul 08 11:07:21.96: [25708]: <-- data [1032]
> Jul 08 11:07:22.20: [25708]: <-- data [1024]
> Jul 08 11:07:23.48: [25708]: <-- data [1025]
> Jul 08 11:07:23.70: [25708]: <-- data [1027]
> Jul 08 11:07:25.02: [25708]: <-- data [1024]
> Jul 08 11:07:26.24: [25708]: <-- data [1027]
> Jul 08 11:07:27.74: [25708]: <-- data [1026]
> Jul 08 11:07:29.40: [25708]: <-- data [1024]
> Jul 08 11:07:31.52: [25708]: <-- data [1029]
> Jul 08 11:07:31.74: [25708]: <-- data [1026]
> Jul 08 11:07:34.10: [25708]: <-- data [1025]
> Jul 08 11:07:34.30: [25708]: <-- data [1024]
> Jul 08 11:07:35.30: [25708]: <-- data [1024]
> Jul 08 11:07:36.42: [25708]: <-- data [1028]
> Jul 08 11:07:37.36: [25708]: <-- data [1026]
> Jul 08 11:07:37.58: [25708]: <-- data [1024]
> Jul 08 11:07:39.54: [25708]: <-- data [1024]
> Jul 08 11:07:39.78: [25708]: <-- data [1024]
> Jul 08 11:07:40.74: [25708]: <-- data [1024]
> Jul 08 11:07:41.86: [25708]: <-- data [518]
> Jul 08 11:07:42.80: [25708]: SENT 23046 bytes of data
> Jul 08 11:07:42.80: [25708]: <-- data [2]
> Jul 08 11:07:43.02: [25708]: SEND end page
> Jul 08 11:07:46.50: [25708]: --> [2:OK]
> Jul 08 11:07:46.50: [25708]: SEND send MPS (more pages, same document)
> Jul 08 11:07:46.50: [25708]: <-- [9:AT+FET=0\r]
> Jul 08 11:07:49.64: [25708]: --> [7:+FPTS:2]
> Jul 08 11:07:49.64: [25708]: --> [2:OK]
> Jul 08 11:07:49.64: [25708]: SEND recv RTN (retrain negative)
> Jul 08 11:07:49.64: [25708]: <-- [7:AT+FDT\r]
> Jul 08 11:07:55.42: [25708]: --> [21:+FDCS:0,3,0,2,0,0,0,3]
> Jul 08 11:07:55.60: [25708]: --> [7:CONNECT]
> Jul 08 11:07:55.60: [25708]: SEND wait for XON
> Jul 08 11:07:55.60: [25708]: --> [1:]
> Jul 08 11:07:55.60: [25708]: SEND begin page
> Jul 08 11:07:55.60: [25708]: <-- data [1030]
> Jul 08 11:07:55.60: [25708]: <-- data [1024]
> Jul 08 11:07:55.60: [25708]: <-- data [1037]
> Jul 08 11:07:55.62: [25708]: <-- data [1032]
> Jul 08 11:07:55.86: [25708]: <-- data [1024]
> Jul 08 11:07:57.12: [25708]: <-- data [1025]
> Jul 08 11:07:57.34: [25708]: <-- data [1027]
> Jul 08 11:07:58.66: [25708]: <-- data [1024]
> Jul 08 11:07:59.88: [25708]: <-- data [1027]
> Jul 08 11:08:01.38: [25708]: <-- data [1026]
> Jul 08 11:08:03.04: [25708]: <-- data [1024]
> Jul 08 11:08:05.14: [25708]: <-- data [1029]
> Jul 08 11:08:05.36: [25708]: <-- data [1026]
> Jul 08 11:08:07.74: [25708]: <-- data [1025]
> Jul 08 11:08:07.94: [25708]: <-- data [1024]
> Jul 08 11:08:08.94: [25708]: <-- data [1024]
> Jul 08 11:08:10.06: [25708]: <-- data [1028]
> Jul 08 11:08:11.00: [25708]: <-- data [1026]
> Jul 08 11:08:11.22: [25708]: <-- data [1024]
> Jul 08 11:08:13.18: [25708]: <-- data [1024]
> Jul 08 11:08:13.38: [25708]: <-- data [1024]
> Jul 08 11:08:14.38: [25708]: <-- data [1024]
> Jul 08 11:08:15.50: [25708]: <-- data [518]
> Jul 08 11:08:16.44: [25708]: SENT 23046 bytes of data
> Jul 08 11:08:16.44: [25708]: <-- data [2]
> Jul 08 11:08:16.66: [25708]: SEND end page
> Jul 08 11:08:20.14: [25708]: --> [2:OK]
> Jul 08 11:08:20.14: [25708]: SEND send MPS (more pages, same document)
> Jul 08 11:08:20.14: [25708]: <-- [9:AT+FET=0\r]
> Jul 08 11:08:23.30: [25708]: --> [7:+FPTS:2]
> Jul 08 11:08:23.30: [25708]: --> [2:OK]
> Jul 08 11:08:23.30: [25708]: SEND recv RTN (retrain negative)
> Jul 08 11:08:23.30: [25708]: <-- [7:AT+FDT\r]
> Jul 08 11:08:29.12: [25708]: --> [21:+FDCS:0,3,0,2,0,0,0,3]
> Jul 08 11:08:29.28: [25708]: --> [7:CONNECT]
> Jul 08 11:08:29.28: [25708]: SEND wait for XON
> Jul 08 11:08:29.28: [25708]: --> [1:]
> Jul 08 11:08:29.28: [25708]: SEND begin page
> Jul 08 11:08:29.28: [25708]: <-- data [1030]
> Jul 08 11:08:29.28: [25708]: <-- data [1024]
> Jul 08 11:08:29.28: [25708]: <-- data [1037]
> Jul 08 11:08:29.30: [25708]: <-- data [1032]
> Jul 08 11:08:29.54: [25708]: <-- data [1024]
> Jul 08 11:08:30.82: [25708]: <-- data [1025]
> Jul 08 11:08:31.04: [25708]: <-- data [1027]
> Jul 08 11:08:32.34: [25708]: <-- data [1024]
> Jul 08 11:08:33.58: [25708]: <-- data [1027]
> Jul 08 11:08:35.08: [25708]: <-- data [1026]
> Jul 08 11:08:36.72: [25708]: <-- data [1024]
> Jul 08 11:08:38.84: [25708]: <-- data [1029]
> Jul 08 11:08:39.06: [25708]: <-- data [1026]
> Jul 08 11:08:41.44: [25708]: <-- data [1025]
> Jul 08 11:08:41.64: [25708]: <-- data [1024]
> Jul 08 11:08:42.62: [25708]: <-- data [1024]
> Jul 08 11:08:43.74: [25708]: <-- data [1028]
> Jul 08 11:08:44.67: [25708]: <-- data [1026]
> Jul 08 11:08:44.88: [25708]: <-- data [1024]
> Jul 08 11:08:46.87: [25708]: <-- data [1024]
> Jul 08 11:08:47.07: [25708]: <-- data [1024]
> Jul 08 11:08:48.05: [25708]: <-- data [1024]
> Jul 08 11:08:49.18: [25708]: <-- data [518]
> Jul 08 11:08:50.14: [25708]: SENT 23046 bytes of data
> Jul 08 11:08:50.15: [25708]: <-- data [2]
> Jul 08 11:08:50.36: [25708]: SEND end page
> Jul 08 11:08:53.82: [25708]: --> [2:OK]
> Jul 08 11:08:53.82: [25708]: SEND send MPS (more pages, same document)
> Jul 08 11:08:53.82: [25708]: <-- [9:AT+FET=0\r]
> Jul 08 11:08:57.08: [25708]: --> [7:+FPTS:2]
> Jul 08 11:08:57.08: [25708]: --> [2:OK]
> Jul 08 11:08:57.08: [25708]: SEND recv RTN (retrain negative)
> Jul 08 11:08:57.08: [25708]: <-- [6:AT+FK\r]
> Jul 08 11:08:57.24: [25708]: --> [1:1]
> Jul 08 11:08:58.54: [25708]: --> [7:+FHNG:2]
> Jul 08 11:08:58.54: [25708]: REMOTE HANGUP: Call aborted,  from +FK or <CAN> (code 2)
> Jul 08 11:08:58.64: [25708]: --> [2:OK]
> Jul 08 11:08:58.64: [25708]: <-- [5:ATH0\r]
> Jul 08 11:08:58.78: [25708]: --> [2:OK]
> Jul 08 11:08:58.89: [25708]: SESSION END

I suspect the 'SEND recv RTN' is a clue.  I've tried adding
'Class2DDISCmd: AT+FDIS' to the config file for these modems to
no avail.  Any ideas?
------------------------------------------------------------------
Dave Bloom	               "But meanwhile, I'm still thinking..."
Neversoft Corp   
dave@andromeda.rutgers.edu




Project hosted by iFAX Solutions