[hcflinux] (proper) logs from smartdaa disconnection

Sun Apr 28 15:50:03 EDT 2002

Hi again,

Okay, having learned how to get at my system logs (sorry, I'm pretty new to 
linux), here's what I've got.  Two different disconnections, one from a 
connection that lasted over an hour, another from a connection under 10 
minutes.  

Termination cause was "Retrain failed" in the first, "n400 exceeded" in the 
second.  Oddly, it appears that one connection began with V.90, and the other 
with V.34.  I do have a minor amount of noise on my line from time to time, 
fyi.  This was with an Aztech MDP modem, 14F1:1056.

Incidentally, I noticed that my opengl apps (nvidia drivers) stopped working 
after installing hcf drivers (although this wasn't immediately after 
installation).  The nvidia docs explained how to fix - apparently it's 
something to do with PAM authentication - the fix was an edit to 
/etc/security/console.perms - could this have anything to do with the hcf 
installation process?

The log entries from the moments of disconnection : 

Apr 28 14:18:55 localhost kernel: HCF: <#UG VERSION=1>
Apr 28 14:18:55 localhost kernel: HCF: <UNIMODEM VERSION=10>
Apr 28 14:18:55 localhost kernel: HCF: <CALL SETUP RESULT=Detected Data 
Answer Tone>
Apr 28 14:18:55 localhost kernel: HCF: <MULTIMEDIA MODE=Data Only>
Apr 28 14:18:55 localhost kernel: HCF: <DTE-DCE MODE=Async data>
Apr 28 14:18:55 localhost kernel: HCF: <V.8 CM="">
Apr 28 14:18:55 localhost kernel: HCF: <V.8 JM="">
Apr 28 14:18:55 localhost kernel: HCF: <RX SIG POWER DB=22, TX SIG POWER 
DB=15, S/N RATIO DB=35>
Apr 28 14:18:55 localhost kernel: HCF: <TX MOD=V.90, TX SYMBOL RATE=3200, TX 
CARRIER FREQ=1829>
Apr 28 14:18:55 localhost kernel: HCF: <RX MOD=V.90, RX SYMBOL RATE=3200, RX 
CARRIER FREQ=0>
Apr 28 14:18:55 localhost kernel: HCF: <TX FIRST RATE=31200, RX FIRST 
RATE=44000>
Apr 28 14:18:55 localhost kernel: HCF: <CARRIER LOSS=0, RATE RENEG=2>
Apr 28 14:18:55 localhost kernel: HCF: <RETRAINS REQ=0, RETRAINS DET=1>
Apr 28 14:18:56 localhost kernel: HCF: <TX FINAL RATE=28800, RX FINAL 
RATE=42667>
Apr 28 14:18:56 localhost kernel: HCF: <PROTOCOL=LAPM>
Apr 28 14:18:56 localhost kernel: HCF: <EC FRAME SIZE=80>
Apr 28 14:18:56 localhost kernel: HCF: <EC LINK TIMEOUTS=0>
Apr 28 14:18:56 localhost kernel: HCF: <EC LINK NAKS=0>
Apr 28 14:18:56 localhost kernel: HCF: <COMPRESSION=V42BIS>
Apr 28 14:18:56 localhost kernel: HCF: <COMPRESSION DICTIONARY SIZE=800>
Apr 28 14:18:56 localhost kernel: HCF: <TX FLOW CONTROL=2>
Apr 28 14:18:56 localhost kernel: HCF: <RX FLOW CONTROL=2>
Apr 28 14:18:56 localhost pppd[2833]: Hangup (SIGHUP)
Apr 28 14:18:56 localhost kernel: HCF: <TX CHARS SENT FROM DTE=4DE34B>
Apr 28 14:18:56 localhost pppd[2833]: Modem hangup
Apr 28 14:18:56 localhost kernel: HCF: <RX CHARS SENT TO DTE=BCE8CF>
Apr 28 14:18:56 localhost pppd[2833]: Connection terminated.
Apr 28 14:18:56 localhost kernel: HCF: <TX CHARS LOST=0>
Apr 28 14:18:56 localhost pppd[2833]: Connect time 69.1 minutes.
Apr 28 14:18:56 localhost kernel: HCF: <RX CHARS LOST=0>
Apr 28 14:18:56 localhost kernel: HCF: <TX FRAME COUNT=96E7>
Apr 28 14:18:56 localhost pppd[2833]: Sent 4960591 bytes, received 12210847 
bytes.
Apr 28 14:18:56 localhost kernel: HCF: <RX FRAME COUNT=162FC>
Apr 28 14:18:56 localhost kernel: HCF: <TX FRAME ERROR COUNT=9D>
Apr 28 14:18:56 localhost pppd[2833]: Exit.
Apr 28 14:18:56 localhost kernel: HCF: <RX FRAME ERROR COUNT=D3>
Apr 28 14:18:56 localhost kernel: HCF: <TERMINATION CAUSE=Retrain failed>
Apr 28 14:18:56 localhost kernel: HCF: <LSD Vdd=0, Power Bits=0>
Apr 28 14:18:56 localhost kernel: HCF: <V8BIS DSP VER=0>
Apr 28 14:18:56 localhost kernel: HCF: <RATE RENEG REQ=1, RATE RENEG DET=1>
Apr 28 14:18:56 localhost kernel: HCF: <HIGH PASS FILTER=1>
Apr 28 14:18:56 localhost kernel: HCF: <RBS=0, AltRBS=0>
Apr 28 14:18:56 localhost kernel: HCF: <DIG PAD DET=0, DIG LOSS EST=2000, IMD 
Ratio=2, SPEED DROP=0>
Apr 28 14:18:56 localhost kernel: HCF: <Cnt1=0, Det1=0, Res1=0, LAPM=5>
Apr 28 14:18:56 localhost kernel: HCF: <EQM=244C, TRAINING EQM=E6F1>
Apr 28 14:18:56 localhost kernel: HCF: <HandShake Time=15.893>
Apr 28 14:18:56 localhost kernel: HCF: 
Apr 28 14:18:56 localhost kernel: HCF: <DIB ERROR COUNTERS=0, 0, 0, 0, 0, 0, 
0, 0>
Apr 28 14:18:56 localhost kernel: hcf_put_char: ComCtrlWrite returned 0
Apr 28 14:18:56 localhost modprobe: modprobe: Can't locate module ppp0
Apr 28 14:18:56 localhost kernel: hcf_put_char: ComCtrlWrite returned 0
Apr 28 14:18:56 localhost last message repeated 4 times
Apr 28 14:18:56 localhost modprobe: modprobe: Can't locate module ppp0
Apr 28 14:18:56 localhost kernel: hcf_put_char: ComCtrlWrite returned 0

----------------------

Apr 28 14:29:58 localhost kernel: HCF: <#UG VERSION=1>
Apr 28 14:29:58 localhost kernel: HCF: <UNIMODEM VERSION=10>
Apr 28 14:29:58 localhost kernel: HCF: <CALL SETUP RESULT=Detected Data 
Answer Tone>
Apr 28 14:29:58 localhost kernel: HCF: <MULTIMEDIA MODE=Data Only>
Apr 28 14:29:58 localhost kernel: HCF: <DTE-DCE MODE=Async data>
Apr 28 14:29:58 localhost kernel: HCF: <V.8 CM="">
Apr 28 14:29:58 localhost kernel: HCF: <V.8 JM="">
Apr 28 14:29:58 localhost kernel: HCF: <RX SIG POWER DB=22, TX SIG POWER 
DB=15, S/N RATIO DB=27>
Apr 28 14:29:58 localhost kernel: HCF: <TX MOD=V.34, TX SYMBOL RATE=3429, TX 
CARRIER FREQ=1959>
Apr 28 14:29:58 localhost kernel: HCF: <RX MOD=V.34, RX SYMBOL RATE=3429, RX 
CARRIER FREQ=1959>
Apr 28 14:29:58 localhost kernel: HCF: <TX FIRST RATE=28800, RX FIRST 
RATE=44000>
Apr 28 14:29:58 localhost kernel: HCF: <CARRIER LOSS=0, RATE RENEG=60>
Apr 28 14:29:58 localhost kernel: HCF: <RETRAINS REQ=0, RETRAINS DET=1>
Apr 28 14:29:58 localhost pppd[3147]: Hangup (SIGHUP)
Apr 28 14:29:58 localhost kernel: HCF: <TX FINAL RATE=28800, RX FINAL 
RATE=28800>
Apr 28 14:29:58 localhost pppd[3147]: Modem hangup
Apr 28 14:29:58 localhost kernel: HCF: <PROTOCOL=LAPM>
Apr 28 14:29:58 localhost pppd[3147]: Connection terminated.
Apr 28 14:29:58 localhost kernel: HCF: <EC FRAME SIZE=80>
Apr 28 14:29:58 localhost kernel: HCF: <EC LINK TIMEOUTS=0>
Apr 28 14:29:58 localhost kernel: HCF: <EC LINK NAKS=0>
Apr 28 14:29:58 localhost kernel: HCF: <COMPRESSION=V42BIS>
Apr 28 14:29:58 localhost kernel: HCF: <COMPRESSION DICTIONARY SIZE=800>
Apr 28 14:29:58 localhost pppd[3147]: Connect time 7.4 minutes.
Apr 28 14:29:58 localhost kernel: HCF: <TX FLOW CONTROL=2>
Apr 28 14:29:58 localhost pppd[3147]: Sent 34700 bytes, received 115703 bytes.
Apr 28 14:29:58 localhost kernel: HCF: <RX FLOW CONTROL=2>
Apr 28 14:29:58 localhost kernel: HCF: <TX CHARS SENT FROM DTE=9188>
Apr 28 14:29:58 localhost kernel: HCF: <RX CHARS SENT TO DTE=1CFEA>
Apr 28 14:29:58 localhost kernel: HCF: <TX CHARS LOST=0>
Apr 28 14:29:58 localhost kernel: HCF: <RX CHARS LOST=0>
Apr 28 14:29:58 localhost kernel: HCF: <TX FRAME COUNT=16E>
Apr 28 14:29:58 localhost kernel: HCF: <RX FRAME COUNT=3D7>
Apr 28 14:29:58 localhost kernel: HCF: <TX FRAME ERROR COUNT=4>
Apr 28 14:29:58 localhost kernel: HCF: <RX FRAME ERROR COUNT=4>
Apr 28 14:29:58 localhost kernel: HCF: <TERMINATION CAUSE=n400 exceeded>
Apr 28 14:29:58 localhost kernel: HCF: <LSD Vdd=0, Power Bits=0>
Apr 28 14:29:58 localhost kernel: HCF: <V8BIS DSP VER=0>
Apr 28 14:29:58 localhost modprobe: modprobe: Can't locate module ppp0
Apr 28 14:29:58 localhost kernel: HCF: <RATE RENEG REQ=1, RATE RENEG DET=59>
Apr 28 14:29:58 localhost kernel: HCF: <HIGH PASS FILTER=1>
Apr 28 14:29:58 localhost kernel: HCF: <RBS=0, AltRBS=0>
Apr 28 14:29:58 localhost kernel: HCF: <DIG PAD DET=0, DIG LOSS EST=2000, IMD 
Ratio=2, SPEED DROP=0>
Apr 28 14:29:58 localhost kernel: HCF: <Cnt1=0, Det1=0, Res1=0, LAPM=5>
Apr 28 14:29:58 localhost kernel: HCF: <EQM=2561, TRAINING EQM=DF2E>
Apr 28 14:29:58 localhost kernel: HCF: <HandShake Time=15.504>
Apr 28 14:29:58 localhost pppd[3147]: Exit.
Apr 28 14:29:58 localhost kernel: HCF: 
Apr 28 14:29:58 localhost kernel: HCF: <DIB ERROR COUNTERS=0, 0, 0, 0, 0, 0, 
0, 0>