En esta segunda sección está dedicada específicamente para el diagnostico en la sincronización. Para detalles de la sinronización sin problemas ver la sección adsl-sync1.
Con el comando show dsl interface INTERFACE ayuda a conocer los parámetros de potencias, SNR y atenuación. Valores muy útiles para el diagnostico de la línea.
Claro está que estos valores solo aparecen cuando están sincronizados. ¿Qué pasa si el router no puede sincronizar?. En este caso estos valores no aparecen y no es posible saber si la tarjeta tiene un problema (aparte del estado de los LED) o si la línea presenta algún problema.
Esta vez utilizaremos el comando dsl enable-training-log. En los router de la version 12.3 solo permite activarlo, pero sin mucho detalles.
CLIENTE-A(config-if)#dsl ?
enable-training-log enable the Alcatel fw training log
lom Loss Of Margin watch counter for line retrain
operating-mode auto or full rate or G.lite mode
CLIENTE-A(config-if)#dsl enable-training-log ?
<cr>
CLIENTE-A(config-if)#dsl enable-training-log
CLIENTE-A(config-if)#
En la version 12.4T en adelante permite dividir las funciones:
dsl enable-training-log {delay [nseconds] | failure | ondemand | showtime}
Si utilizan la version 12.4T activar el modo failure.
Lo bueno y lo malo de la activación es que envía mucha información. Esta puede ser vista después del comando show dsl interface
Este comando puede darnos idea si hay problemas en la línea.
Caso1: Alta atenuación.
comando debug atm event.
Veamos un caso donde la línea está bien atenuada (a propósito,fue accidental :p )
*Aug 26 20:33:55.303: DSL: Send ADSL_OPEN command.
*Aug 26 20:33:55.303: DSL(ATM0/1/0): Sent command 0xF0
*Aug 26 20:33:55.303: DSL: Received response: 0xF0
*Aug 26 20:33:55.303: DSL(ATM0/1/0): Using subfunction 0xA1
*Aug 26 20:33:55.303: DSL(ATM0/1/0): Sent command 0x3
*Aug 26 20:33:57.803: DSL(ATM0/1/0): 1: Modem state = 0x8
*Aug 26 20:34:00.303: DSL(ATM0/1/0): 2: Modem state = 0x8
*Aug 26 20:34:02.803: DSL(ATM0/1/0): 3: Modem state = 0x9
*Aug 26 20:34:04.811: DSL: Received response: 0x22
*Aug 26 20:34:04.811: DSL: Open failed: Protocol error received — retrying
*Aug 26 20:34:04.811: sleep 5 seconds
*Aug 26 20:34:09.811: DSL: Send ADSL_OPEN command.
*Aug 26 20:34:09.811: DSL(ATM0/1/0): Sent command 0xF0
*Aug 26 20:34:09.811: DSL: Received response: 0xF0
*Aug 26 20:34:09.811: DSL(ATM0/1/0): Using subfunction 0xA1
*Aug 26 20:34:09.811: DSL(ATM0/1/0): Sent command 0x3
*Aug 26 20:34:12.311: DSL(ATM0/1/0): 1: Modem state = 0x8
*Aug 26 20:34:14.811: DSL(ATM0/1/0): 2: Modem state = 0x8
*Aug 26 20:34:17.311: DSL(ATM0/1/0): 3: Modem state = 0x9
*Aug 26 20:34:17.619: DSL: Received response: 0x22
*Aug 26 20:34:17.619: DSL: Open failed: Error in early non-CRC message or in first CRC-covered message — retrying
*Aug 26 20:34:17.623: sleep 5 seconds
Se puede observar que recibe un mensaje de error de DSLAM 0x22. Hasta este punto se desconoce el mótivo.
Ahora activaremos el comando dsl enable-training-log en la interfaz ATM.
CLIENTE-A# show dsl interface atm 0/1/0
Alcatel 20166/20174 chipset information
Line has not yet been activated. No information available.
Log file of training sequence:
<168538> : ModemLineController outOfReset
<168538> : Destruction ModemSubsystem
<168587> : Construction ModemSubsystem
<168614> : Init AFE
<168616> : ----------->Running build is : 13_9_31
<168646> : Init AFE
...
<190516> : Detected tone 40 with power -52.698456 dBm and SNR (lin) 213.871475
<190518> : Detected tone 56 with power -57.359718 dBm and SNR (lin) 70.766991
...
<171079> : Too High, Out of range for ATT1-LNA1, set to Max=+31dB
...
<172115> : +++ ANALYSIS +++
<172130> : x = 0.000000
<172130> : y = 0.000000
<172132> : Pilot 64 Pilot scale error: x = 0.000000e+00, y = 0.000000e+00, pilotRootPower = 0.000000e+00.
<172133> : Initializer::parameterError()
<172143> : ****************************** Power Down TX Driver *********************************
<172144> : Autonomuous Message : Modem init fain Han
Habrá mucha información en este comando, pero cabe resaltar los mensajes que comienzan con "Detected tone" y "Too High". Los primeros te pueden dar un indicio de la potencia detectada en la línea. En este caso hay una potencia detectada de -57 dBm. Suficiente para que no sincronice. En el segundo mensaje, como está tan atenuado, coloca la potencia del router al máximo +31 dB.
Caso2: Línea con poca atenuación
Repitamos los comandos anteriores y podemos detectar lo siguiente.
comando debug atm event:
*Aug 26 21:12:00.651: DSL(ATM0/1/0): 4: Modem state = 0x8
*Aug 26 21:12:03.151: DSL(ATM0/1/0): 5: Modem state = 0x8
*Aug 26 21:12:05.651: DSL(ATM0/1/0): 6: Modem state = 0x8
*Aug 26 21:12:08.151: DSL(ATM0/1/0): 7: Modem state = 0x9
*Aug 26 21:12:10.651: DSL(ATM0/1/0): 8: Modem state = 0x9
*Aug 26 21:12:13.151: DSL(ATM0/1/0): 9: Modem state = 0x10
*Aug 26 21:12:15.651: DSL(ATM0/1/0): 10: Modem state = 0x10
*Aug 26 21:12:18.151: DSL(ATM0/1/0): 11: Modem state = 0x10
*Aug 26 21:12:19.379: DSL: Received response: 0x24
*Aug 26 21:12:19.379: DSL: Showtime!
*Aug 26 21:12:19.379: DSL(ATM0/1/0): Sent command 0x11
*Aug 26 21:12:19.387: DSL: Received response: 0x61
*Aug 26 21:12:19.387: DSL: Read firmware revision 0xC166
*Aug 26 21:12:19.387: DSL(ATM0/1/0): Sent command 0x31
*Aug 26 21:12:19.391: DSL: Received response: 0x12
*Aug 26 21:12:19.391: DSL(ATM0/1/0): operation mode 0x0002
*Aug 26 21:12:19.391: DSL(ATM0/1/0): Sent command 0x33
*Aug 26 21:12:19.399: DSL: Received response: 0x16
*Aug 26 21:12:19.399: DSL(ATM0/1/0): Far End ITU Country Code 0xB5
*Aug 26 21:12:19.399: DSL: Far End ITU Vendor ID BDCM
*Aug 26 21:12:19.399: DSL: Far End ITU Vendor ID Specific 0x6193
*Aug 26 21:12:19.399: DSL: Far End ITU Vendor STD Number 0x0001
*Aug 26 21:12:19.399: DSL(ATM0/1/0): Sent command 0x32
*Aug 26 21:12:19.403: DSL: Received response: 0x14
*Aug 26 21:12:19.403: DSL(ATM0/1/0): Near End ITU Country Code 0x00
*Aug 26 21:12:19.403: DSL: Near End ITU Vendor ID ALCB
*Aug 26 21:12:19.403: DSL: Near End ITU Vendor ID Specific 0x0000
*Aug 26 21:12:19.403: DSL: Near End ITU Vendor STD Number 0x0001
*Aug 26 21:12:19.403: ATM0/1/0 dslsar_MatchSARTxToLineSpeed(): usbw 992, clkPerCell 15100 prev_clkPerCell 18724
*Aug 26 21:12:19.403: ATM0/1/0 dslsar_update_us_bandwidth(): upstream bw =992 Kbps
*Aug 26 21:12:19.403: DSL: SM: [DMTDSL_DO_OPEN -> DMTDSL_SHOWTIME]
Si activamos el comando dsl enable-training-log en la interfaz ATM, se observa de la siguiente manera:
CLIENTE-A#show dsl int atm0/1/0
Alcatel 20166/20174 chipset information
Line not activated: displaying cached data from last activation
ATU-R (DS) ATU-C (US)
Modem Status: Showtime (DMTDSL_DO_OPEN)
DSL Mode: ITU G.992.1 (G.DMT) Annex A
ITU STD NUM: 0x01 0x1
Vendor ID: 'ALCB' 'BDCM'
Vendor Specific: 0x0000 0x6193
Vendor Country: 0x00 0xB5
Capacity Used: 28% 93%
Noise Margin: -9.5 dB 7.0 dB
Output Power: 8.0 dBm 12.0 dBm
Attenuation: 4.0 dB 0.0 dB
Defect Status: LOS LCDf LOM LOS LOF LCDf
Last Fail Code: None
Selftest Result: 0x00
Subfunction: 0xA1
Interrupts: 3176 (0 spurious)
PHY Access Err: 0
Activations: 1
LED Status: OFF
LED On Time: 100
LED Off Time: 100
Init FW: embedded
Operation FW: embedded
SW Version: 13.931
FW Version: 0xC166
Interleave Fast Interleave Fast
Speed (kbps): 0 2048 0 992
Cells: 0 0 0 501594
Reed-Solomon EC: 0 0 184 199
CRC Errors: 0 360 189 312
Header Errors: 0 1 162 176
Bit Errors: 0 0
BER Valid sec: 0 0
BER Invalid sec: 0 0
LOM Monitoring : Enabled
LOM watch configured for 3 times
LOM appeared continuously for 0 times
DMT Bits Per Bin
00: 0 0 0 0 0 0 0 7 9 A B B C C C C
10: C C D D D D D D D D C C C C 0 0
20: 0 0 0 0 0 0 0 0 0 0 0 0 0 2 2 2
30: 2 2 2 2 2 3 3 3 3 4 4 4 4 4 4 4
40: 4 4 4 4 4 4 4 4 4 2 4 4 4 4 4 4
50: 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4
60: 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4
70: 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4
80: 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4
90: 3 4 4 4 4 4 4 3 4 4 4 3 4 3 3 3
A0: 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3
B0: 3 3 3 3 3 3 3 3 2 3 3 3 2 2 2 2
C0: 2 2 2 2 2 2 2 2 2 2 3 2 2 2 3 2
D0: 2 2 2 2 2 2 2 2 3 2 2 2 2 2 2 2
E0: 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 0
F0: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
Log file of training sequence:
<767> : ModemLineController outOfReset
<767> : Destruction ModemSubsystem
<815> : Construction ModemSubsystem
<842> : Init AFE
<842> : ----------->Running build is : 13_9_31
<872> : Init AFE
<872> : Transparent TEQ
<877> : FTG Init: Tone 7 to 18 boosted
<878> : Modem State Change : 3
<878> : Init AFE
...
<18809> : Detected tone 40 with power -10.665969 dBm and SNR (lin) 16392.625000
<18811> : Detected tone 56 with power -7.523239 dBm and SNR (lin) 8353.550781
...
<22536> : +++ ANALYSIS +++
<22551> : x = 3277.687500
<22552> : y = -2087.187500
<22553> : scale = 2.981415
<23255> : Initializer::scheduleTimerEvent
<23439> : Initializer::scheduleTimerEvent
<23459> : Long GuardBand Fix Active
<23590> : Channel measurement done
<23623> : P_Rx = 1.692105e+09
<23635> : ReceiveChannel::calculateTeqFeqFirst()
<23806> : Frequency error 1.203247 ppm
<23807> : Transmitted DCXO code 127
<24003> : Initializer::scheduleTimerEvent
<24014> : +++ start transmitSequence +++
<24015> : Ftg::prepareForMedley
<24418> : C_MESSAGES1 received completely
<24423> : DS option 0 BF=64 BI=0 RSF=0 RSI=0 D=1 2*S=2
<24424> : US option 0 BF=29 BI=85 RSF=0 RSI=0 D=1 2*S=2
<24425> : DS option 1 BF=44 BI=0 RSF=0 RSI=0 D=1 2*S=2
<24426> : US option 1 BF=22 BI=85 RSF=0 RSI=0 D=1 2*S=2
<24427> : DS option 2 BF=23 BI=0 RSF=0 RSI=0 D=1 2*S=2
<24427> : US option 2 BF=12 BI=85 RSF=0 RSI=0 D=1 2*S=2
<24428> : DS option 3 BF=2 BI=0 RSF=0 RSI=0 D=1 2*S=2
<24429> : US option 3 BF=2 BI=85 RSF=0 RSI=0 D=1 2*S=2
<24429> : ---------------------------------------------------------------
<24430> : MODEM INITIALIZING IN OPERATION MODE <24430> : G_DMT POTS !!!!!
<24430> : INITIALIZATION SPECIFICATIONS : STANDARD COMPLIANT INITIATIZATION !!!!!
<24431> : INITIALIZATION SPECIFICATIONS : MINUMUM OVERHEAD FRAMING !!!!!
<24431> : ---------------------------------------------------------------
<24433> : build R_MESSAGES1
<24447> : Calculate final TEQ
<26257> : Initializer::scheduleTimerEvent
<27042> : SNR measurement ...
<27067> : # active tones = 208
<27070> : maxPower = 231.9
<27156> : Max capacity Execution time = 91
<27156> : Total_number_of_bits_supported = 2235
<27157> : Performance_Margin = 6.0 dB
<27158> : Coding Gain (in units of 0.5 dB) : 7
<27158> : Build R_MESSAGES_RA
<27158> : maxNrBits=2235
<27159> : NM returned in RMessagesRA is: 6
<27159> : # payload bytes: 264
<28689> : decode C_MESSAGES_RA
Se puede observar claramente que la potencia esta entre -10 dBm y -7dBm. Y aparecen ciertos datos extras que no aparecen cuando no sincroniza.
Una manera rápida de verificar estos valores es utilizando filtros. A continuación un ejemplo:
CLIENTE-A#show dsl int atm0/1/0 | inc Detected tone
<18809> : Detected tone 40 with power -10.665969 dBm and SNR (lin) 16392.625000
<18811> : Detected tone 56 with power -7.523239 dBm and SNR (lin) 8353.550781
<18865> : Detected tone 40 with power -10.663657 dBm and SNR (lin) 16325.849609
<18867> : Detected tone 56 with power -7.523986 dBm and SNR (lin) 8251.559570
<21857> : Detected tone 64 with power -7.840766 dBm and SNR (lin) 6.116461
CLIENTE-A#
Referencias:
http://www.cisco.com/en/US/prod/collateral/routers/ps221/prod_bulletin0900aecd801d5c4c.html
http://www.cisco.com/en/US/docs/ios/12_4t/12_4t11/annexm.html#wp1129546