Diagnóstico. Sincronización ADSL a detalle. Parte II

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

Unless otherwise stated, the content of this page is licensed under Creative Commons Attribution-ShareAlike 3.0 License