HylaFAX The world's most advanced open source fax server

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

[hylafax-users] Help with log interpretation



Can someone help me to interpret what I'm seeing here?  My untrained eye
says the receiving end just hung up for some reason.  Am I reading too much
into "REMOTE HANGUP"?  The recipient says they got some of the pages but not
all.

HylaFAX 4.0pl1 binary1.0 on Solaris 2.6 and old USR modems.

I know how old our hylaFAX is and about the compatibility issues with the
USR's.  We are about to upgrade both.  

Thanks for any help you may be able to provide and feel free to slap me
around if this is a FAQ and I should have found it on my own.  Please tell
me where I should have looked while beating me though.  ;-)

Thanks,
Chris


plum:/var/spool/fax/log $ cat c03645191
Oct 07 08:00:05.97: [ 1117]: SESSION BEGIN 03645191 14049954056
Oct 07 08:00:05.97: [ 1117]: SEND FAX: JOB 24901 DEST +14049954056 COMMID
03645191
Oct 07 08:00:05.97: [ 1117]: MODEM set DTR OFF
Oct 07 08:00:06.00: [ 1117]: DELAY 2600 ms
Oct 07 08:00:08.60: [ 1117]: MODEM set DTR ON
Oct 07 08:00:08.75: [ 1117]: MODEM set baud rate: 38400 baud, input flow
RTS/CTS, output flow RTS/CTS
Oct 07 08:00:08.75: [ 1117]: MODEM flush i/o
Oct 07 08:00:08.75: [ 1117]: <-- [17:ATE0V1Q0X4S0=0H0\r]
Oct 07 08:00:09.08: [ 1117]: --> [2:OK]
Oct 07 08:00:09.09: [ 1117]: <-- [32:ATS8=2S7=60&H1&I0&R2S13=1&D2&C1\r]
Oct 07 08:00:09.30: [ 1117]: --> [2:OK]
Oct 07 08:00:09.31: [ 1117]: <-- [14:AT+FCLASS=2.0\r]
Oct 07 08:00:09.40: [ 1117]: --> [2:OK]
Oct 07 08:00:09.41: [ 1117]: <-- [9:AT+FLO=2\r]
Oct 07 08:00:09.50: [ 1117]: --> [2:OK]
Oct 07 08:00:09.51: [ 1117]: <-- [9:AT+FPP=0\r]
Oct 07 08:00:09.60: [ 1117]: --> [2:OK]
Oct 07 08:00:09.61: [ 1117]: <-- [9:AT+FBO=0\r]
Oct 07 08:00:09.70: [ 1117]: --> [2:OK]
Oct 07 08:00:09.71: [ 1117]: <-- [10:AT+FCT=30\r]
Oct 07 08:00:09.80: [ 1117]: --> [2:OK]
Oct 07 08:00:09.81: [ 1117]: <-- [15:AT+FNR=1,1,1,1\r]
Oct 07 08:00:09.90: [ 1117]: --> [2:OK]
Oct 07 08:00:09.91: [ 1117]: <-- [9:AT+FIE=0\r]
Oct 07 08:00:10.00: [ 1117]: --> [2:OK]
Oct 07 08:00:10.01: [ 1117]: <-- [9:AT+FBU=0\r]
Oct 07 08:00:10.11: [ 1117]: --> [2:OK]
Oct 07 08:00:10.11: [ 1117]: <-- [23:AT+FCC=1,5,2,2,0,0,0,0\r]
Oct 07 08:00:10.20: [ 1117]: --> [2:OK]
Oct 07 08:00:10.21: [ 1117]: <-- [5:ATM0\r]
Oct 07 08:00:10.42: [ 1117]: --> [2:OK]
Oct 07 08:00:10.43: [ 1117]: STATE CHANGE: RUNNING -> SENDING
Oct 07 08:00:10.43: [ 1117]: MODEM input buffering enabled
Oct 07 08:00:10.43: [ 1117]: <-- [14:AT+FCLASS=2.0\r]
Oct 07 08:00:10.62: [ 1117]: --> [2:OK]
Oct 07 08:00:10.62: [ 1117]: <-- [9:AT+FLO=2\r]
Oct 07 08:00:10.80: [ 1117]: --> [2:OK]
Oct 07 08:00:10.80: [ 1117]: <-- [9:AT+FPP=0\r]
Oct 07 08:00:10.98: [ 1117]: --> [2:OK]
Oct 07 08:00:10.98: [ 1117]: <-- [9:AT+FBO=0\r]
Oct 07 08:00:11.16: [ 1117]: --> [2:OK]
Oct 07 08:00:11.16: [ 1117]: <-- [10:AT+FCT=30\r]
Oct 07 08:00:11.34: [ 1117]: --> [2:OK]
Oct 07 08:00:11.34: [ 1117]: <-- [15:AT+FNR=1,1,1,1\r]
Oct 07 08:00:11.52: [ 1117]: --> [2:OK]
Oct 07 08:00:11.52: [ 1117]: <-- [9:AT+FIE=0\r]
Oct 07 08:00:11.70: [ 1117]: --> [2:OK]
Oct 07 08:00:11.70: [ 1117]: <-- [9:AT+FBU=0\r]
Oct 07 08:00:11.88: [ 1117]: --> [2:OK]
Oct 07 08:00:11.88: [ 1117]: <-- [23:AT+FCC=1,5,2,2,0,0,0,0\r]
Oct 07 08:00:12.06: [ 1117]: --> [2:OK]
Oct 07 08:00:12.06: [ 1117]: <-- [20:AT+FLI="DG Systems"\r]
Oct 07 08:00:12.24: [ 1117]: --> [2:OK]
Oct 07 08:00:12.24: [ 1117]: DIAL 14049954056
Oct 07 08:00:12.24: [ 1117]: <-- [16:ATDT14049954056\r]
Oct 07 08:00:30.16: [ 1117]: --> [4:+FCO]
Oct 07 08:00:31.78: [ 1117]: -->
[45:+FNF:000049EEA088CBB903670C1B640A010100000102]
Oct 07 08:00:31.78: [ 1117]: REMOTE NSF
"000049EEA088CBB903670C1B640A010100000102"
Oct 07 08:00:32.28: [ 1117]: --> [20:+FIS:1,5,0,2,1,1,0,3]
Oct 07 08:00:32.28: [ 1117]: --> [2:OK]
Oct 07 08:00:32.28: [ 1117]: REMOTE best rate 14400 bit/s
Oct 07 08:00:32.28: [ 1117]: REMOTE max page width 1728 pixels in 215 mm
Oct 07 08:00:32.28: [ 1117]: REMOTE max unlimited page length
Oct 07 08:00:32.28: [ 1117]: REMOTE best vres 7.7 line/mm
Oct 07 08:00:32.28: [ 1117]: REMOTE best format 2-D MR
Oct 07 08:00:32.28: [ 1117]: REMOTE supports T.30 Annex A, ECM
Oct 07 08:00:32.28: [ 1117]: REMOTE best 10 ms/scanline
Oct 07 08:00:32.28: [ 1117]: USE 14400 bit/s
Oct 07 08:00:32.29: [ 1117]: USE 10 ms/scanline
Oct 07 08:00:32.29: [ 1117]: SEND file "docq/doc21116.ps;30"
Oct 07 08:00:32.29: [ 1117]: USE page width 1728 pixels in 215 mm
Oct 07 08:00:32.29: [ 1117]: USE unlimited page length
Oct 07 08:00:32.29: [ 1117]: USE 3.85 line/mm
Oct 07 08:00:32.29: [ 1117]: USE 1-D MR
Oct 07 08:00:32.29: [ 1117]: <-- [23:AT+FIS=0,5,0,2,0,0,0,3\r]
Oct 07 08:00:32.48: [ 1117]: --> [2:OK]
Oct 07 08:00:32.48: [ 1117]: <-- [7:AT+FDT\r]
Oct 07 08:00:39.50: [ 1117]: --> [20:+FCS:0,5,0,2,0,0,0,3]
Oct 07 08:00:40.06: [ 1117]: --> [7:CONNECT]
Oct 07 08:00:40.06: [ 1117]: SEND begin page
Oct 07 08:00:40.06: [ 1117]: <-- data [1031]
Oct 07 08:00:40.07: [ 1117]: <-- data [1047]
Oct 07 08:00:40.07: [ 1117]: <-- data [1051]
Oct 07 08:00:40.10: [ 1117]: <-- data [1043]
Oct 07 08:00:40.34: [ 1117]: <-- data [1031]
Oct 07 08:00:40.54: [ 1117]: <-- data [1028]
Oct 07 08:00:40.76: [ 1117]: <-- data [1024]
Oct 07 08:00:41.14: [ 1117]: <-- data [1031]
Oct 07 08:00:41.34: [ 1117]: <-- data [1027]
Oct 07 08:00:41.56: [ 1117]: <-- data [1025]
Oct 07 08:00:41.94: [ 1117]: <-- data [1024]
Oct 07 08:00:42.14: [ 1117]: <-- data [1024]
Oct 07 08:00:42.36: [ 1117]: <-- data [1024]
Oct 07 08:00:43.02: [ 1117]: <-- data [1024]
Oct 07 08:00:43.54: [ 1117]: <-- data [1024]
Oct 07 08:00:44.10: [ 1117]: <-- data [1024]
Oct 07 08:00:44.83: [ 1117]: <-- data [1028]
Oct 07 08:00:45.37: [ 1117]: <-- data [1027]
Oct 07 08:00:45.92: [ 1117]: <-- data [1024]
Oct 07 08:00:46.97: [ 1117]: <-- data [1026]
Oct 07 08:00:47.17: [ 1117]: <-- data [1025]
Oct 07 08:00:47.70: [ 1117]: <-- data [790]
Oct 07 08:00:48.74: [ 1117]: SENT 22294 bytes of data
Oct 07 08:00:48.75: [ 1117]: SEND 1D RTC
Oct 07 08:00:48.75: [ 1117]: <-- data [9]
Oct 07 08:00:49.26: [ 1117]: SEND end page
Oct 07 08:00:49.27: [ 1117]: SEND send MPS (more pages, same document)
Oct 07 08:00:49.27: [ 1117]: <-- data [2]
Oct 07 08:00:59.28: [ 1117]: --> [2:OK]
Oct 07 08:00:59.28: [ 1117]: SEND recv MCF (message confirmation)
Oct 07 08:00:59.28: [ 1117]: SEND FAX (03645191): FROM swu@plum TO
+14049954056 (page 1 of 3 sent in 0:27)
Oct 07 08:00:59.28: [ 1117]: SEND FAX (03645191): FROM swu@plum TO
+14049954056 (docq/doc21116.ps;30 sent in 0:27)
Oct 07 08:00:59.34: [ 1117]: SEND file "docq/doc21117.tif;30"
Oct 07 08:00:59.34: [ 1117]: USE page width 1728 pixels in 215 mm
Oct 07 08:00:59.34: [ 1117]: USE unlimited page length
Oct 07 08:00:59.34: [ 1117]: USE 3.85 line/mm
Oct 07 08:00:59.34: [ 1117]: USE 1-D MR
Oct 07 08:00:59.34: [ 1117]: <-- [7:AT+FDT\r]
Oct 07 08:01:00.06: [ 1117]: --> [7:CONNECT]
Oct 07 08:01:00.06: [ 1117]: SEND begin page
Oct 07 08:01:00.07: [ 1117]: <-- data [1031]
Oct 07 08:01:00.07: [ 1117]: <-- data [1031]
Oct 07 08:01:00.07: [ 1117]: <-- data [1027]
Oct 07 08:01:00.11: [ 1117]: <-- data [1024]
Oct 07 08:01:00.34: [ 1117]: <-- data [1031]
Oct 07 08:01:00.54: [ 1117]: <-- data [1029]
Oct 07 08:01:00.76: [ 1117]: <-- data [1028]
Oct 07 08:01:01.14: [ 1117]: <-- data [1026]
Oct 07 08:01:01.35: [ 1117]: <-- data [1031]
Oct 07 08:01:01.56: [ 1117]: <-- data [1033]
Oct 07 08:01:01.95: [ 1117]: <-- data [1035]
Oct 07 08:01:02.14: [ 1117]: <-- data [1033]
Oct 07 08:01:02.37: [ 1117]: <-- data [1031]
Oct 07 08:01:03.59: [ 1117]: <-- data [1026]
Oct 07 08:01:04.30: [ 1117]: <-- data [1028]
Oct 07 08:01:04.52: [ 1117]: <-- data [1028]
Oct 07 08:01:05.62: [ 1117]: <-- data [1028]
Oct 07 08:01:06.31: [ 1117]: <-- data [1030]
Oct 07 08:01:06.80: [ 1117]: <-- data [1035]
Oct 07 08:01:07.84: [ 1117]: <-- data [1028]
Oct 07 08:01:08.05: [ 1117]: <-- data [1036]
Oct 07 08:01:08.90: [ 1117]: <-- data [1031]
Oct 07 08:01:09.61: [ 1117]: <-- data [1034]
Oct 07 08:01:10.11: [ 1117]: <-- data [1035]
Oct 07 08:01:10.62: [ 1117]: <-- data [1030]
Oct 07 08:01:11.63: [ 1117]: <-- data [1028]
Oct 07 08:01:12.14: [ 1117]: <-- data [1030]
Oct 07 08:01:12.66: [ 1117]: <-- data [1031]
Oct 07 08:01:13.83: [ 1117]: <-- data [1030]
Oct 07 08:01:14.05: [ 1117]: <-- data [1026]
Oct 07 08:01:14.56: [ 1117]: <-- data [511]
Oct 07 08:01:15.54: [ 1117]: SENT 31231 bytes of data
Oct 07 08:01:15.55: [ 1117]: SEND 1D RTC
Oct 07 08:01:15.55: [ 1117]: <-- data [9]
Oct 07 08:01:16.04: [ 1117]: SEND end page
Oct 07 08:01:16.05: [ 1117]: SEND send MPS (more pages, same document)
Oct 07 08:01:16.05: [ 1117]: <-- data [2]
Oct 07 08:01:37.08: [ 1117]: --> [7:+FHS:A0]
Oct 07 08:01:37.08: [ 1117]: REMOTE HANGUP: Unspecified Phase D error (code
A0)
Oct 07 08:01:37.09: [ 1117]: <-- [5:ATH0\r]
Oct 07 08:01:37.09: [ 1117]: --> [2:OK]
Oct 07 08:01:37.09: [ 1117]: MODEM set DTR OFF
Oct 07 08:01:37.13: [ 1117]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Oct 07 08:01:37.13: [ 1117]: SESSION END

____________________ 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@hylafax.org < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@hylafax.org.*




Project hosted by iFAX Solutions