Conexión CPE - DSLAM. Fases de Sincronización. Parte I

Actualmente en el trabajo de un proveedor donde su principal forma de acceso es utilizando el medio de cobre telefónico para llegar a los clientes, lo más obvio es utilizar la tecnología DSL.
Lo mínimo que necesitas para poder brindar utilizar este medio es un DSLAM y un CPE con tecnología DSL. En este caso nos enfocaremos en el ADSL.

La configuración al principio puede ser difícil sino se tiene una base, pero generalmente el patrón se mantiene, solo variando las direcciones IP. Ya en el caso de investigar falla generalmente es tan sencillo como llegar al punto remoto, conectar un CPE del otro lado y esperar a que tengas conectividad a Internet, y los términos manejados son "¿Sincronizó o no sincronizó?. Por suerte las tarjetas ADSL Cisco tienen un LED llamado CD que nos indica esto. Si parpadea no ha sincronizado y si está encendido estáticamente esta ok!. Básicamente la tarjeta sería como la que sigue:

95231.jpg

En la página de cisco indica:
CD: Carrier Detected
LP: Loopback
OK: si el router detecta la tarjeta ok.
El problema es cuando el CD no deja de parpadear y no tienes comunicación. Facilmente se pensaría en la línea, y ¡generalmente asi lo es!, pero hay casos que personal de cable indica que la línea está todo ok, pero no se ve comunicación desde el router Cisco. ¿Que más se puede verificar?

Tenía esa interrogante y como generalmente me encontraba del lado remoto (detrás del DSLAM), simplemente no podía hacer nada.

Lo más curioso de todo esto es que paso un caso con los siguientes síntomas:

  • Tarjeta ADSL con CD parpadeando, no hay comunicación.
  • Personal de cableado verifica la línea y no tiene cortes y llega hasta el DSLAM
  • Conecta modem ADSL de prueba (no cisco) y sincroniza
  • El mismo modem ADSL se le configura dirección IP WAN y LAN y queda en funcionamiento
  • Se conecta el router Cisco ADSL y no levanta.

Pues el diagnostico del LED CD ya no es suficiente. Me queda la gran interrogante de ¿como funciona las fases ADSL por lo menos con un Router Cisco?.

Bueno se toma un router una línea ADSL ok! conectado hacia un DSLAM. Un diagrama similar a este:

wicadsl_rfc_irb_nat_1.gif

El método de comunicación a utilizar será IRB. Existen otros métodos explicados por varios libros de cisco como el PPPoA y el PPPoE. Se realizarán varias pruebas con el comando debug atm event activado.
Las fases que debe tomar el router hasta sincronizarse con el medio son las siguientes:

[DMTDSL_INIT] -> Estado que inicial de la sincronización. Transisición entre OPEN y LOAD1.
[DMTDSL_DLOAD_1] -> Carga del firmware inicial de la tarjeta ADSL con el chipset.
[DMTDSL_DLOAD_2] -> Carga del firmware completo de la tarjeta ADSL con el chipset. Es posible reemplazar el firmware con uno de la flash.
[DMTDSL_DO_OPEN] -> Intenta conectarse con el DSLAM. negocia la velocidad a utilizar
[DMTDSL_SHOWTIME] -> Sincronización completa. Descarga información de QoS, circuitos de mantenimiento OAM, VCD, VPI y otros datos requeridos con el DSLAM.

A continuación se coloca el comportamiento de la tarjeta según el escenario.

Caso 1:

Línea desconectada del router o de la cajilla remota. Activado con el comando debug atm event.

CPE_ADSL_IRB#debug atm event
ATM events debugging is on
CPE_ADSL_IRB#
*Aug 19 15:30:43.535: DSL(ATM0/1/0): Received response: 0x80
*Aug 19 15:30:43.535: DSL: SM: [DMTDSL_DLOAD_1 -> DMTDSL_DLOAD_2]
*Aug 19 15:30:43.807: DSL(ATM0/1/0): Downloading AMR-3.0.014.bin
*Aug 19 15:30:43.855: DSL(ATM0/1/0): Downloaded 100 blocks
*Aug 19 15:30:43.903: DSL(ATM0/1/0): Downloaded 200 blocks
*Aug 19 15:30:43.955: DSL(ATM0/1/0): Downloaded 300 blocks
*Aug 19 15:30:44.003: DSL(ATM0/1/0): Downloaded 400 blocks
*Aug 19 15:30:44.051: DSL(ATM0/1/0): Downloaded 500 blocks
*Aug 19 15:30:44.103: DSL(ATM0/1/0): Downloaded 600 blocks
*Aug 19 15:30:44.151: DSL(ATM0/1/0): Downloaded 700 blocks
*Aug 19 15:30:44.199: DSL(ATM0/1/0): Downloaded 800 blocks
CPE_ADSL_IRB#
*Aug 19 15:30:44.247: DSL(ATM0/1/0): Downloaded 900 blocks
*Aug 19 15:30:44.299: DSL(ATM0/1/0): Downloaded 1000 blocks
*Aug 19 15:30:44.347: DSL(ATM0/1/0): Downloaded 1100 blocks
*Aug 19 15:30:44.399: DSL(ATM0/1/0): Downloaded 1200 blocks
*Aug 19 15:30:44.447: DSL(ATM0/1/0): Downloaded 1300 blocks
*Aug 19 15:30:44.495: DSL(ATM0/1/0): Downloaded 1400 blocks
*Aug 19 15:30:44.547: DSL(ATM0/1/0): Downloaded 1500 blocks
*Aug 19 15:30:44.595: DSL(ATM0/1/0): Downloaded 1600 blocks
*Aug 19 15:30:44.643: DSL(ATM0/1/0): Downloaded 1700 blocks
*Aug 19 15:30:44.691: DSL(ATM0/1/0): Downloaded 1800 blocks
*Aug 19 15:30:44.743: DSL(ATM0/1/0): Downloaded 1900 blocks
CPE_ADSL_IRB#
*Aug 19 15:30:44.791: DSL(ATM0/1/0): Downloaded 2000 blocks
*Aug 19 15:30:44.839: DSL(ATM0/1/0): Downloaded 2100 blocks
*Aug 19 15:30:44.891: DSL(ATM0/1/0): Downloaded 2200 blocks
*Aug 19 15:30:44.939: DSL(ATM0/1/0): Downloaded 2300 blocks
*Aug 19 15:30:44.987: DSL(ATM0/1/0): Downloaded 2400 blocks
*Aug 19 15:30:45.039: DSL(ATM0/1/0): Downloaded 2500 blocks
*Aug 19 15:30:45.087: DSL(ATM0/1/0): Downloaded 2600 blocks
*Aug 19 15:30:45.135: DSL(ATM0/1/0): Downloaded 2700 blocks
*Aug 19 15:30:45.187: DSL(ATM0/1/0): Downloaded 2800 blocks
*Aug 19 15:30:45.235: DSL(ATM0/1/0): Downloaded 2900 blocks
*Aug 19 15:30:45.283: DSL(ATM0/1/0): Downloaded 3000 blocks
*Aug 19 15:30:45.335: DSL(ATM0/1/0): Downloaded 3100 blocks
*Aug 19 15:30:45.383: DSL(ATM0/1/0): Downloaded 3200 blocks
*Aug 19 15:30:45.431: DSL(ATM0/1/0): Downloaded 3300 blocks
*Aug 19 15:30:45.483: DSL(ATM0/1/0): Downloaded 3400 blocks
*Aug 19 15:30:45.531: DSL(ATM0/1/0): Downloaded 3500 blocks
*Aug 19 15:30:45.579: DSL(ATM0/1/0): Downloaded 3600 blocks
*Aug 19 15:30:45.631: DSL(ATM0/1/0): Downloaded 3700 blocks
*Aug 19 15:30:45.679: DSL(ATM0/1/0): Downloaded 3800 blocks
*Aug 19 15:30:45.727: DSL(ATM0/1/0): Downloaded 3900 blocks
*Aug 19 15:30:45.779: DSL(ATM0/1/0): Downloaded 4000 blocks
*Aug 19 15:30:45.827: DSL(ATM0/1/0): Downloaded 4100 blocks
CPE_ADSL_IRB#
*Aug 19 15:30:45.835: DSL:(ATM0/1/0) Downloaded 4121 blocks… Finished!
*Aug 19 15:30:45.835: DSL(ATM0/1/0): Sent command 0x14
CPE_ADSL_IRB#
*Aug 19 15:30:47.843: set tx_gain_offset to 0
*Aug 19 15:30:47.843: set rx_gain_offset to 0
*Aug 19 15:30:47.843: set target_noise_margin_offset to 0
*Aug 19 15:30:47.843: set max_bits_tone_limit to 15
*Aug 19 15:30:47.843: changed current state to do open!!
*Aug 19 15:30:47.843: DSL: SM: [DMTDSL_DLOAD_2 -> DMTDSL_DO_OPEN]
*Aug 19 15:30:47.843: DSL(ATM0/1/0): Send ADSL_OPEN command.
*Aug 19 15:30:47.843: dslsar enable ATM0/1/0

*Aug 19 15:30:47.843: DSL(ATM0/1/0): Using subfunction 0x0
*Aug 19 15:30:47.843: LOCAL:Max noise margin for power cutoff 31
*Aug 19 15:30:47.843: DSL(ATM0/1/0): Sent command 0x5
CPE_ADSL_IRB#
*Aug 19 15:30:50.343: DSL(ATM0/1/0): 1: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:30:52.843: DSL(ATM0/1/0): 2: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:30:55.343: DSL(ATM0/1/0): 3: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:30:57.843: DSL(ATM0/1/0): 4: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:00.343: DSL(ATM0/1/0): 5: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:02.843: DSL(ATM0/1/0): 6: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:05.343: DSL(ATM0/1/0): 7: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:07.843: DSL(ATM0/1/0): 8: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:10.343: DSL(ATM0/1/0): 9: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:12.843: DSL(ATM0/1/0): 10: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:15.343: DSL(ATM0/1/0): 11: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:17.843: DSL(ATM0/1/0): 12: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:20.343: DSL(ATM0/1/0): 13: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:22.843: DSL(ATM0/1/0): 14: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:25.343: DSL(ATM0/1/0): 15: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:27.843: DSL(ATM0/1/0): 16: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:30.343: DSL(ATM0/1/0): 17: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:32.843: DSL(ATM0/1/0): 18: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:35.343: DSL(ATM0/1/0): 19: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:37.843: DSL(ATM0/1/0): 20: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:40.343: DSL(ATM0/1/0): 21: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:42.843: DSL(ATM0/1/0): 22: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:45.343: DSL(ATM0/1/0): 23: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:47.843: DSL(ATM0/1/0): 24: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:50.343: DSL(ATM0/1/0): 25: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:52.843: DSL(ATM0/1/0): 26: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:55.343: DSL(ATM0/1/0): 27: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:31:57.843: DSL(ATM0/1/0): 28: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:32:00.343: DSL(ATM0/1/0): 29: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:32:02.843: DSL(ATM0/1/0): 30: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:32:05.343: DSL(ATM0/1/0): Could not establish connection event=1
*Aug 19 15:32:05.343: DSL: SM: [DMTDSL_DO_OPEN -> DMTDSL_INIT]

En esta fase la interfaz del atm debe verse de la siguiente manera si es el primer intento de sincronizar:

CPE_ADSL_IRB#show ip int brief
Interface                  IP-Address      OK? Method Status                Protocol
ATM0/1/0                   unassigned      YES NVRAM  initializing          down

O verse de la siguiente manera si alguna vez sincronizó y falla la línea:
CPE_ADSL_IRB#show ip int brief
Interface                  IP-Address      OK? Method Status                Protocol
ATM0/1/0                   unassigned      YES NVRAM  down                  down

Nota: si el enlace ADSL está sincronizado con el DSLAM, realizar shutdown y no shutdown, no lo regresa a la fase inicial. Se debe desconectar el cable para lograr esto.
En este caso, bajo el comando show dsl interface se debe de la siguiente manera:
CPE_ADSL_IRB#show dsl int
ATM0/1/0
Alcatel 20190 chipset information

Line has not yet been activated.

Modem Status:    Down (DMTDSL_DO_OPEN)
DSL Mode:        Unknown
Interrupts:      8248 (0 spurious)
PHY Access Err:  0
Activations:     0
LED Status:      OFF
LED On Time:     0
LED Off Time:    0
Init FW:         init_AMR-3.0.014_no_bist.bin
Operation FW:    AMR-3.0.014.bin
FW Source:       embedded

DSL: Training log buffer capability is not enabled
CPE_ADSL_IRB#

Caso 2:

Se conecta el cable a la cajilla. Conectividad con el DSLAM.

CPE_ADSL_IRB#debug atm event
ATM events debugging is on
CPE_ADSL_IRB#
*Aug 19 15:36:11.919: DSL(ATM0/1/0): 29: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:36:14.419: DSL(ATM0/1/0): 30: Modem state = 0x8
CPE_ADSL_IRB#
*Aug 19 15:36:16.919: DSL(ATM0/1/0): Could not establish connection event=1
*Aug 19 15:36:16.919: DSL: SM: [DMTDSL_DO_OPEN -> DMTDSL_INIT]
*Aug 19 15:36:16.947: DSL: SM: [DMTDSL_INIT -> DMTDSL_DLOAD_1]
*Aug 19 15:36:16.951: DSL(ATM0/1/0): Downloading init_AMR-3.0.014_no_bist.bin
*Aug 19 15:36:16.951: DSL:(ATM0/1/0) Downloaded 2 blocks… Finished!
*Aug 19 15:36:16.951: DSL(ATM0/1/0): Sent command 0x14
CPE_ADSL_IRB#
*Aug 19 15:36:18.951: DSL(ATM0/1/0): Received response: 0x80
*Aug 19 15:36:18.951: DSL: SM: [DMTDSL_DLOAD_1 -> DMTDSL_DLOAD_2]
*Aug 19 15:36:19.223: DSL(ATM0/1/0): Downloading AMR-3.0.014.bin
*Aug 19 15:36:19.271: DSL(ATM0/1/0): Downloaded 100 blocks
*Aug 19 15:36:19.323: DSL(ATM0/1/0): Downloaded 200 blocks
*Aug 19 15:36:19.371: DSL(ATM0/1/0): Downloaded 300 blocks
*Aug 19 15:36:19.419: DSL(ATM0/1/0): Downloaded 400 blocks
*Aug 19 15:36:19.471: DSL(ATM0/1/0): Downloaded 500 blocks
*Aug 19 15:36:19.519: DSL(ATM0/1/0): Downloaded 600 blocks
*Aug 19 15:36:19.567: DSL(ATM0/1/0): Downloaded 700 blocks
*Aug 19 15:36:19.615: DSL(ATM0/1/0): Downloaded 800 blocks
*Aug 19 15:36:19.667: DSL(ATM0/1/0): Downloaded 900 blocks
*Aug 19 15:36:19.715: DSL(ATM0/1/0): Downloaded 1000 blocks
*Aug 19 15:36:19.763: DSL(ATM0/1/0): Downloaded 1100 blocks
*Aug 19 15:36:19.815: DSL(ATM0/1/0): Downloaded 1200 blocks
*Aug 19 15:36:19.863: DSL(ATM0/1/0): Downloaded 1300 blocks
*Aug 19 15:36:19.911: DSL(ATM0/1/0): Downloaded 1400 blocks
*Aug 19 15:36:19.963: DSL(ATM0/1/0): Downloaded 1500 blocks
*Aug 19 15:36:20.011: DSL(ATM0/1/0): Downloaded 1600 blocks
*Aug 19 15:36:20.059: DSL(ATM0/1/0): Downloaded 1700 blocks
*Aug 19 15:36:20.111: DSL(ATM0/1/0): Downloaded 1800 blocks
*Aug 19 15:36:20.159: DSL(ATM0/1/0): Downloaded 1900 blocks
CPE_ADSL_IRB#
*Aug 19 15:36:20.207: DSL(ATM0/1/0): Downloaded 2000 blocks
*Aug 19 15:36:20.255: DSL(ATM0/1/0): Downloaded 2100 blocks
*Aug 19 15:36:20.307: DSL(ATM0/1/0): Downloaded 2200 blocks
*Aug 19 15:36:20.355: DSL(ATM0/1/0): Downloaded 2300 blocks
*Aug 19 15:36:20.403: DSL(ATM0/1/0): Downloaded 2400 blocks
*Aug 19 15:36:20.455: DSL(ATM0/1/0): Downloaded 2500 blocks
*Aug 19 15:36:20.503: DSL(ATM0/1/0): Downloaded 2600 blocks
*Aug 19 15:36:20.555: DSL(ATM0/1/0): Downloaded 2700 blocks
*Aug 19 15:36:20.603: DSL(ATM0/1/0): Downloaded 2800 blocks
*Aug 19 15:36:20.655: DSL(ATM0/1/0): Downloaded 2900 blocks
*Aug 19 15:36:20.703: DSL(ATM0/1/0): Downloaded 3000 blocks
*Aug 19 15:36:20.751: DSL(ATM0/1/0): Downloaded 3100 blocks
*Aug 19 15:36:20.799: DSL(ATM0/1/0): Downloaded 3200 blocks
*Aug 19 15:36:20.851: DSL(ATM0/1/0): Downloaded 3300 blocks
*Aug 19 15:36:20.899: DSL(ATM0/1/0): Downloaded 3400 blocks
*Aug 19 15:36:20.947: DSL(ATM0/1/0): Downloaded 3500 blocks
*Aug 19 15:36:20.999: DSL(ATM0/1/0): Downloaded 3600 blocks
*Aug 19 15:36:21.047: DSL(ATM0/1/0): Downloaded 3700 blocks
*Aug 19 15:36:21.095: DSL(ATM0/1/0): Downloaded 3800 blocks
*Aug 19 15:36:21.147: DSL(ATM0/1/0): Downloaded 3900 blocks
*Aug 19 15:36:21.195: DSL(ATM0/1/0): Downloaded 4000 blocks
*Aug 19 15:36:21.243: DSL(ATM0/1/0): Downloaded 4100 blocks
CPE_ADSL_IRB#
*Aug 19 15:36:21.255: DSL:(ATM0/1/0) Downloaded 4121 blocks… Finished!
*Aug 19 15:36:21.255: DSL(ATM0/1/0): Sent command 0x14
CPE_ADSL_IRB#
*Aug 19 15:36:23.263: set tx_gain_offset to 0
*Aug 19 15:36:23.263: set rx_gain_offset to 0
*Aug 19 15:36:23.263: set target_noise_margin_offset to 0
*Aug 19 15:36:23.263: set max_bits_tone_limit to 15
*Aug 19 15:36:23.263: changed current state to do open!!
*Aug 19 15:36:23.263: DSL: SM: [DMTDSL_DLOAD_2 -> DMTDSL_DO_OPEN]
*Aug 19 15:36:23.263: DSL(ATM0/1/0): Send ADSL_OPEN command.
*Aug 19 15:36:23.263: dslsar enable ATM0/1/0

*Aug 19 15:36:23.263: DSL(ATM0/1/0): Using subfunction 0x0
*Aug 19 15:36:23.263: LOCAL:Max noise margin for power cutoff 31
*Aug 19 15:36:23.263: DSL(ATM0/1/0): Sent command 0x5
CPE_ADSL_IRB#
*Aug 19 15:36:25.763: DSL(ATM0/1/0): 1: Modem state = 0x10
CPE_ADSL_IRB#
*Aug 19 15:36:28.263: DSL(ATM0/1/0): 2: Modem state = 0x10
CPE_ADSL_IRB#
*Aug 19 15:36:30.763: DSL(ATM0/1/0): 3: Modem state = 0x10
CPE_ADSL_IRB#
*Aug 19 15:36:32.819: DSL(ATM0/1/0): Received response: 0x24
*Aug 19 15:36:32.819: DSL(ATM0/1/0): Showtime!
*Aug 19 15:36:32.819: DSL(ATM0/1/0): Sent command 0x31
*Aug 19 15:36:32.823: DSL(ATM0/1/0): Received response: 0x12
*Aug 19 15:36:32.823: DSL(ATM0/1/0): operation mode 0x0001
*Aug 19 15:36:32.823: ATM0/1/0 dslsar_ng_MatchSARTxToLineSpeed(): usbw 544, clkPerCell 19486 prev_clkPerCell 13731
*Aug 19 15:36:32.827: ATM0/1/0 dslsar_ng_MatchSARTxToLineSpeed(): Changing line speed from fast to slow
*Aug 19 15:36:32.831: ATM0/1/0: Posting event 0 to the QoS Process
*Aug 19 15:36:32.831: ATM0/1/0 dslsar_ng_update_us_bandwidth(): upstream bw =544 Kbps
CPE_ADSL_IRB#
*Aug 19 15:36:32.831: ATM0/1/0: Posting event 4 to the QoS Process
*Aug 19 15:36:32.831: DSL: SM: [DMTDSL_DO_OPEN -> DMTDSL_SHOWTIME]
*Aug 19 15:36:32.831: ATM0/1/0:Processing RECONFIG ALL TST EVENT..
*Aug 19 15:36:32.831: ATM0/1/0: Writing bit info 0
*Aug 19 15:36:32.831: ATM(ATM0/1/0) Rcvd jump complete interrupt event, vcd bit info 0
*Aug 19 15:36:32.831: ATM0/1/0: dslsar_ng_update_replace_tst() tst_num: 1
*Aug 19 15:36:32.835: ATM0/1/0: Posting event 4 to the QoS Process
*Aug 19 15:36:32.835: ATM(ATM0/1/0) Rcvd jump complete interrupt event, vcd bit info 0
CPE_ADSL_IRB#
*Aug 19 15:36:36.171: dslsar_ng_periodic: ENABLING DSLSAR

*Aug 19 15:36:36.171: (ATM0/1/0)1a_enable: delay activation of vcd=1, vc=0x647E293C
*Aug 19 15:36:36.171: dslsar_ng_setup_cos(ATM0/1/0): vc:0 wred_name: max_q:0
*Aug 19 15:36:36.171: ATM(ATM0/1/0): cos queue for oam is created,adb->cos_queue = 0x6475D93C oqueue_dequeue_vc = 0x60457420, oqueue_vc = 0x6078BE4C
*Aug 19 15:36:36.171: ATM0/1/0 dslsar_create_oam_queue_out_of_band_vc: Out-of-band OAM VC created successfully.
*Aug 19 15:36:36.171: dslsar enable ATM0/1/0

*Aug 19 15:36:36.171: (ATM0/1/0)dslsar_ng_init_tcq: Initializing TCQ for VCD 0
*Aug 19 15:36:36.171: dslsar_init_txring_params: default tx-ring setting is selected for vpi/vci = 0/0
*Aug 19 15:36:36.171: ATM0/1/0dslsar_init_txring_params: For VCD: 0, low priority tx-ring size is 10. High priority tx-ring size is 30
*Aug 19 15:36:36.171: ATM0/1/0:dslsar_ng_init_tx_chan_desc_per_vc, vcd = 0, addr = 0x00010000, size = 64
*Aug 19 15:36:36.171: (ATM0/1/0)dslsar_ng_init_tcq: Initializing TCQ for VCD 0
*Aug 19 15:36:36.171: dslsar_init_txring_params: default tx-ring setting is selected for vpi/vci = 0/0
*Aug 19 15:36:36.171: ATM0/1/0dslsar_init_txring_params: For VCD: 0, low priority tx-ring size is 10. High priority tx-ring size is 30
CPE_ADSL_IRB#
*Aug 19 15:36:36.171: ATM0/1/0:dslsar_ng_init_tx_chan_desc_per_vc, vcd = 0, addr = 0x00010000, size = 64
*Aug 19 15:36:36.171: ATM0/1/0:dslsar_oam_vc_init_tcq_and_enable: OAM VC has been setup successfully
*Aug 19 15:36:36.171: ATM0/1/0: dslsar_ng_bringup_interface: Interface, atm_db flags and OAM VC are UP
*Aug 19 15:36:36.171: ATM0/1/0: Posting event 4 to the QoS Process
*Aug 19 15:36:36.171: ATM(ATM0/1/0) Rcvd jump complete interrupt event, vcd bit info 0
*Aug 19 15:36:36.171: ATM0/1/0: vcd = 0, max_tx_time = 67050
CPE_ADSL_IRB#
*Aug 19 10:36:38.171: %LINK-3-UPDOWN: Interface ATM0/1/0, changed state to up
CPE_ADSL_IRB#
*Aug 19 15:36:38.171: dslsar_atm_lineaction(ATM0/1/0): state=4
*Aug 19 15:36:38.171: dslsar_atm_lineaction: REG_INVOKE: line up

*Aug 19 15:36:38.171: ATM: Repapulate, ATM0/1/0
*Aug 19 15:36:38.171: ATM: PVC activated, ATM0/1/0 VCD 1 (1/32)
*Aug 19 15:36:38.171: ATM: Repapulate, BVI1
*Aug 19 10:36:39.171: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0/1/0, changed state to up
CPE_ADSL_IRB#
*Aug 19 10:36:40.171: %LINK-3-UPDOWN: Interface BVI1, changed state to up
*Aug 19 10:36:41.171: %LINEPROTO-5-UPDOWN: Line protocol on Interface BVI1, changed state to up
CPE_ADSL_IRB#
*Aug 19 15:36:43.975: (ATM0/1/0)dslsar_ng_init_tcq: Initializing TCQ for VCD 1
*Aug 19 15:36:43.975: dslsar_init_txring_params: default tx-ring setting is selected for vpi/vci = 1/32
*Aug 19 15:36:43.975: ATM0/1/0dslsar_init_txring_params: For VCD: 1, low priority tx-ring size is 10. High priority tx-ring size is 30
*Aug 19 15:36:43.975: ATM0/1/0:dslsar_ng_init_tx_chan_desc_per_vc, vcd = 1, addr = 0x00010040, size = 64
*Aug 19 15:36:43.975: (ATM0/1/0)dslsar_ng_init_tcq: Initializing TCQ for VCD 1
*Aug 19 15:36:43.975: dslsar_init_txring_params: default tx-ring setting is selected for vpi/vci = 1/32
*Aug 19 15:36:43.975: ATM0/1/0dslsar_init_txring_params: For VCD: 1, low priority tx-ring size is 10. High priority tx-ring size is 30
*Aug 19 15:36:43.975: ATM0/1/0:dslsar_ng_init_tx_chan_desc_per_vc, vcd = 1, addr = 0x00010040, size = 64
*Aug 19 15:36:43.975: ATM0/1/0 dslsar_ng_vc_setup: vcd 1
*Aug 19 15:36:43.975: ATM0/1/0: Posting event 2 to the QoS Process
*Aug 19 15:36:43.975: (ATM0/1/0)Forcing Peakrate and Avgrate to: 544
*Aug 19 15:36:43.975: (ATM0/1/0)Requested QoS: Peakrate = 544, Avgrate = 544, Burstsize =0
*Aug 19 15:36:43.975: ATM0/1/0: Posting event 0 to the QoS Process
*Aug 19 15:36:43.975: dslsar_ng_setup_cos(ATM0/1/0): vc:1 wred_name:- max_q:0
*Aug 19 15:36:43.975: ATM: PVC activated, ATM0/1/0 VCD 1 (1/32)
*Aug 19 15:36:43.975: ATM0/1/0:Processing RECONFIG ALL TST EVENT..ATM0/1/0 dslsar_fill_oam_vc_info(): Bandwidth available for OAM Tx : 8 kbps
*Aug 19 15:36:43.975: Poll table not created for qos 1
*Aug 19 15:36:43.975: vcd 1,vpi 1, vpi 32, band_width 544, calss 5
*Aug 19 15:36:43.979: vcd 0,vpi 0, vpi 0, band_width 544, calss 0 0 1 3 5 7 9 11 13 15 17 19 21 23 25 27 29 31 33 35 37 39 41 43 45 47 49 51 53 55 57 59 61 63 65 67 69 71 73 75 77 79 81 83 85 87 89 91 93 95 97 99 101 103 105 107 109 111 113 115 117 119 121 123 125 127 129 131 133 2 4 6 8 10 12 14 16 18 20 22 24 26 28 30 32 34 36 3
CPE_ADSL_IRB#8 40 42 44 46 48 50 52 54 56 58 60 62 64 66 68 70 72 74 76 78 80 82 84 86 88 90 92 94 96 98 100 102 104 106 108 110 112 114 116 118 120 122 124 126 128 130 132 134
*Aug 19 15:36:43.979: ATM0/1/0: Writing bit info 3
*Aug 19 15:36:43.979: ATM0/1/0: dslsar_ng_update_replace_tst() tst_num: 0
*Aug 19 15:36:43.987: ATM0/1/0: Posting event 4 to the QoS Process
*Aug 19 15:36:43.987: ATM(ATM0/1/0) Rcvd jump complete interrupt event, vcd bit info 3
*Aug 19 15:36:43.987: ATM0/1/0: Setting ds state to IN_USE in interrupt event vcd 1
*Aug 19 15:36:43.987: ATM0/1/0: vcd = 0, max_tx_time = 268200
*Aug 19 15:36:43.987: ATM0/1/0: vcd = 1, max_tx_time = 4002
CPE_ADSL_IRB#
CPE_ADSL_IRB#
*Aug 19 15:36:58.335: dslsar_ng_transmit_pak_wrapper: deq(63D765C0). ATM vcd=1 Transmit VCD=1
*Aug 19 15:36:58.335: dslsar_ng_transmit_pak_wrapper : encap = Data
*Aug 19 15:36:58.335: ATM0/1/0 TX(63D765C0), ATM VCD 1 Transmit VCD 1 size 47 [AA AA 3 0 80 C2 0 E 0 0

Luego de la sincronización se debe ver de la siguiente manera:

CPE_ADSL_IRB#show dsl interface
ATM0/1/0
Alcatel 20190 chipset information
                ATU-R (DS)                      ATU-C (US)
Modem Status:    Showtime (DMTDSL_SHOWTIME)
DSL Mode:        ANSI T1.413
ANSI Issue/rev:  2/1                             2/1
Vendor ID:       0x003A                          0x0054
Chip ID:         C196P (1)
DFE BOM:         DFE3.0 Annex A (1)
Capacity Used:   40%                             90%
Noise Margin:    21.5 dB                          6.0 dB
Output Power:    19.0 dBm                        12.0 dBm
Attenuation:     61.0 dB                         31.5 dB
FEC ES Errors:    0                               0
ES Errors:        0                               2
SES Errors:       0                               0
LOSES Errors:     0                               0
UES Errors:       0                               0
Defect Status:   None                            None                        
Last Fail Code:  None
Watchdog Counter: 0x9E
Watchdog Resets: 0
Selftest Result: 0x00
Subfunction:     0x00 
Interrupts:      28872 (0 spurious)
PHY Access Err:  0
Activations:     1
LED Status:      OFF
LED On Time:     0
LED Off Time:    0
Init FW:         init_AMR-3.0.014_no_bist.bin
Operation FW:    AMR-3.0.014.bin
FW Source:       embedded
FW Version:      3.0.14

                 Interleave             Fast    Interleave              Fast
Speed (kbps):             0             2048             0               544
Cells:                    0                0             0            189684
Reed-Solomon EC:          0                0             0                 0
CRC Errors:               0                0             0                 2
Header Errors:            0                0             0                 0
Total BER:                0E-0           0E-0
Leakage Average BER:      0E-0           0E-0
                        ATU-R (DS)      ATU-C (US)
Bitswap:               enabled            enabled
Bitswap success:          0                   0
Bitswap failure:          0                   0

LOM Monitoring : Disabled

DMT Bits Per Bin
000: 0 0 0 0 0 0 0 4 6 7 8 8 8 8 8 7
010: 0 8 7 7 7 7 7 7 6 6 6 6 6 6 5 5
020: 0 0 0 0 0 2 0 0 3 2 3 3 3 4 3 4
030: 4 2 3 4 2 2 2 4 2 3 4 4 5 4 4 4
040: 0 4 4 4 4 4 4 4 4 4 4 2 4 4 4 4
050: 4 4 2 3 3 3 4 4 5 5 5 5 5 5 5 5
060: 5 4 2 2 3 5 5 5 5 5 5 5 5 5 5 5
070: 5 5 4 2 5 5 5 5 5 5 5 5 5 5 5 5
080: 5 4 4 4 5 4 4 4 4 4 4 4 4 4 4 4
090: 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4
0A0: 4 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3
0B0: 3 3 3 2 2 2 2 2 2 2 2 2 2 2 2 2
0C0: 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2
0D0: 2 2 2 2 2 2 2 0 0 0 0 0 0 0 0 0
0E0: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
0F0: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0

DSL: Training log buffer capability is not enabled
> CPE_ADSL_IRB#

Referencias:

Páginas de Cisco de referencias:

http://www.cisco.com/en/US/tech/tk175/tk15/technologies_configuration_example09186a0080093e6b.shtml
http://www.cisco.com/en/US/tech/tk175/tk15/technologies_configuration_example09186a008071a5be.shtml
http://www.cisco.com/en/US/tech/tk175/tk15/technologies_tech_note09186a0080093e62.shtml
http://www.cisco.com/en/US/tech/tk175/tk15/technologies_configuration_example09186a008071a8da.shtml#l2

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