gbs26talavera#debug atm ev gbs26talavera#debug atm events ATM events debugging is on gbs26talavera# *Mar 1 00:50:29.671: DSL(ATM0): 8: Modem state = 0x10 *Mar 1 00:50:32.171: DSL(ATM0): 9: Modem state = 0x10 *Mar 1 00:50:34.671: DSL(ATM0): 10: Modem state = 0x10 *Mar 1 00:50:37.171: DSL(ATM0): 11: Modem state = 0x10 *Mar 1 00:50:39.671: DSL(ATM0): 12: Modem state = 0x10 *Mar 1 00:50:40.279: DSL(ATM0): Received response: 0x22 *Mar 1 00:50:40.279: DSL(ATM0): Open failed: Protocol error received -- retrying *Mar 1 00:50:40.279: DSL(ATM0): sleep 5 seconds *Mar 1 00:50:45.279: DSL(ATM0): Send ADSL_OPEN command. *Mar 1 00:50:45.279: DSL(ATM0): Using preferred open mode *Mar 1 00:50:45.279: DSL(ATM0): Using ITU sync first for 5 secs, then ANSI/ITU sync alternatively for 2 secs *Mar 1 00:50:45.279: DSL(ATM0): Using subfunction 0x0 *Mar 1 00:50:45.279: LOCAL:Max noise margin for power cutoff 31 *Mar 1 00:50:45.279: DSL(ATM0): GPCI[0] 0x5 *Mar 1 00:50:45.279: DSL(ATM0): GPCI[1] 0x9 *Mar 1 00:50:45.279: DSL(ATM0): GPCI[2] 0x1 *Mar 1 00:50:45.279: DSL(ATM0): GPCI[3] 0x0 *Mar 1 00:50:45.279: DSL(ATM0): Sent extended command 0x3 *Mar 1 00:50:47.779: DSL(ATM0): 1: Modem state = 0x9 *Mar 1 00:50:50.279: DSL(ATM0): 2: Modem state = 0x9 *Mar 1 00:50:52.779: DSL(ATM0): 3: Modem state = 0x9 *Mar 1 00:50:55.279: DSL(ATM0): 4: Modem state = 0x10 *Mar 1 00:50:57.779: DSL(ATM0): 5: Modem state = 0x10 *Mar 1 00:51:00.279: DSL(ATM0): 6: Modem state = 0x10 *Mar 1 00:51:02.779: DSL(ATM0): 7: Modem state = 0x10 *Mar 1 00:51:05.279: DSL(ATM0): 8: Modem state = 0x10 *Mar 1 00:51:06.019: DSL(ATM0): Received response: 0x24 *Mar 1 00:51:06.019: DSL(ATM0): Showtime! *Mar 1 00:51:06.019: DSL(ATM0): Sent command 0x31 *Mar 1 00:51:06.031: DSL(ATM0): Received response: 0x12 *Mar 1 00:51:06.031: DSL(ATM0): operation mode 0x40000 *Mar 1 00:51:06.031: DSL(ATM0): Sent command 0x33 *Mar 1 00:51:06.035: DSL(ATM0): Received response: 0x16 *Mar 1 00:51:06.035: DSL(ATM0): Far End Chip Country Code 0xB5 *Mar 1 00:51:06.035: DSL: Far End Chip Vendor ID BDCM *Mar 1 00:51:06.035: DSL: Far End Chip Vendor ID Specific 0x619F *Mar 1 00:51:06.035: DSL: Far End ITU Vendor STD Number 0x0002 *Mar 1 00:51:06.035: DSL(ATM0): Sent command 0x32 *Mar 1 00:51:06.043: DSL(ATM0): Received response: 0x14 *Mar 1 00:51:06.043: DSL(ATM0): Near End Chip Country Code 0x0F *Mar 1 00:51:06.043: DSL: Near End Chip Vendor ID STMI *Mar 1 00:51:06.043: DSL: Near End Chip Vendor ID Specific 0x0000 *Mar 1 00:51:06.043: DSL: Near End ITU Vendor STD Number 0x0003 *Mar 1 00:51:06.043: DSL(ATM0): Sent extended command 0x5 *Mar 1 00:51:06.047: DSL(ATM0): Near End Modem Country Code 0xB5 *Mar 1 00:51:06.047: DSL: Near End Modem Vendor ID CSCO *Mar 1 00:51:06.051: DSL: Near End Modem Vendor ID Specific 0x0000 *Mar 1 00:51:06.051: DSL: Near End Modem Version 12.4(15)T5 *Mar 1 00:51:06.051: DSL: Near End Modem Serial Number FHK121929U7 *Mar 1 00:51:06.051: DSL(ATM0): get Near End extended ITU ID succeeded *Mar 1 00:51:06.051: DSL(ATM0): Sent extended command 0x5 *Mar 1 00:51:17.335: DSL(ATM0): ADSL_ID_NACK_EXT, error code 0x80 at Far End *Mar 1 00:51:17.335: DSL(ATM0): Get FAR End Modem Vendor ID Failed *Mar 1 00:51:17.335: Matching SAR to Line speed: Attempt number 1 cps 1 slots 35 APL Level 0 slots 35 APL Level 1 slots 35 *Mar 1 00:51:17.335: ATM0: atmsar_get_plim_info *Mar 1 00:51:17.335: ATM0:atmsar_update_plimtype *Mar 1 00:51:17.335: ATM0 atmsar_update_us_bandwidth(): upstream bw =1072 Kbps *Mar 1 00:51:17.335: DSL: SM: [DMTDSL_DO_OPEN -> DMTDSL_SHOWTIME] *Mar 1 00:51:18.643: ATM0: atmsar_vc_dlcx *Mar 1 00:51:18.643: (ATM0)1a_enable: delay activation of vcd=1, vc=0x82F09FB0 *Mar 1 00:51:18.643: atmsar enable ATM0 *Mar 1 00:51:18.643: ATM0: atmsar_bringup_interface: Interface and atm_db flags are UP *Mar 1 00:51:19.835: DSL(ATM0): Defect: LOM *Mar 1 00:51:20.643: %LINK-3-UPDOWN: Interface ATM0, changed state to up *Mar 1 00:51:20.643: atmsar_atm_lineaction(ATM0): state=4 *Mar 1 00:51:21.643: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to up *Mar 1 00:51:27.311: ATM0: atmsar_1a_setup_vc, vcinfo = 0x82F09FB0 *Mar 1 00:51:27.311: ATM0 atmsar_vc_setup: vcd 1 *Mar 1 00:51:27.311: Fill up paramaters for PVC 8/35[1] - Request for CoS 0 PCR 0 SCR 0 MCR 0 Available 1072 *Mar 1 00:51:27.311: Scheduler parameters for 8/35. Type UBR. PCR 1072 *Mar 1 00:51:27.311: Total scheduled bandwidth after configuringPVC 8/35 will be 0 *Mar 1 00:51:27.311: atmsar_setup_cos(ATM0): vc:1 wred_name:- max_q:0 *Mar 1 00:51:27.311: ATM0: VC setup successful (vcd = 1) *Mar 1 00:51:27.311: ATM0: atmsar_atm_get_stats,vcinfo = 0x0 *Mar 1 00:51:29.835: DSL(ATM0): Defect: LCDf: retraining *Mar 1 00:51:32.335: atmsar_atm_lineaction(ATM0): state=0 *Mar 1 00:51:32.335: ATM0: atmsar_1a_teardown_vc,vcinfo = 0x82F09FB0 *Mar 1 00:51:32.335: atmsar_1a_teardown_vc(ATM0): vc:1 vpi:8 vci:35 *Mar 1 00:51:32.335: ATM(): IP multicast cache invalidated for ATM0.1 *Mar 1 00:51:32.335: DSL: SM: [DMTDSL_SHOWTIME -> DMTDSL_RE_OPEN] *Mar 1 00:51:32.335: DSL(ATM0): Send ADSL_CLOSE command. *Mar 1 00:51:32.335: DSL(ATM0): Sent command 0x4 *Mar 1 00:51:33.255: DSL(ATM0): Received response: 0x25 *Mar 1 00:51:33.255: DSL(ATM0): Connection closed *Mar 1 00:51:33.259: DSL: SM: [DMTDSL_RE_OPEN -> DMTDSL_DO_OPEN] *Mar 1 00:51:33.259: DSL(ATM0): Send ADSL_OPEN command. *Mar 1 00:51:33.259: DSL(ATM0): Using preferred open mode *Mar 1 00:51:33.259: DSL(ATM0): Using ITU sync first for 5 secs, then ANSI/ITU sync alternatively for 2 secs *Mar 1 00:51:33.259: DSL(ATM0): Using subfunction 0x0 *Mar 1 00:51:33.259: LOCAL:Max noise margin for power cutoff 31 *Mar 1 00:51:33.259: DSL(ATM0): GPCI[0] 0x5 *Mar 1 00:51:33.259: DSL(ATM0): GPCI[1] 0x9 *Mar 1 00:51:33.259: DSL(ATM0): GPCI[2] 0x1 *Mar 1 00:51:33.259: DSL(ATM0): GPCI[3] 0x0 *Mar 1 00:51:33.259: DSL(ATM0): Sent extended command 0x3 *Mar 1 00:51:34.335: %LINK-3-UPDOWN: Interface ATM0, changed state to down *Mar 1 00:51:34.335: atmsar_atm_lineaction(ATM0): state=0 *Mar 1 00:51:35.335: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to down *Mar 1 00:51:35.759: DSL(ATM0): 1: Modem state = 0x8 *Mar 1 00:51:38.259: DSL(ATM0): 2: Modem state = 0x8 *Mar 1 00:51:38.643: ATM0: atmsar_vc_dlcx *Mar 1 00:51:40.759: DSL(ATM0): 3: Modem state = 0x8 *Mar 1 00:51:43.259: DSL(ATM0): 4: Modem state = 0x8 *Mar 1 00:51:45.759: DSL(ATM0): 5: Modem state = 0x9 *Mar 1 00:51:48.259: DSL(ATM0): 6: Modem state = 0x9 *Mar 1 00:51:50.759: DSL(ATM0): 7: Modem state = 0x9 *Mar 1 00:51:53.259: DSL(ATM0): 8: Modem state = 0x10 *Mar 1 00:51:55.759: DSL(ATM0): 9: Modem state = 0x10 *Mar 1 00:51:58.259: DSL(ATM0): 10: Modem state = 0x10 *Mar 1 00:52:00.759: DSL(ATM0): 11: Modem state = 0x10 *Mar 1 00:52:03.259: DSL(ATM0): 12: Modem state = 0x10 *Mar 1 00:52:03.903: DSL(ATM0): Received response: 0x22 *Mar 1 00:52:03.903: DSL(ATM0): Open failed: Protocol error received -- retrying *Mar 1 00:52:03.903: DSL(ATM0): sleep 5 seconds *Mar 1 00:52:08.903: DSL(ATM0): Send ADSL_OPEN command. *Mar 1 00:52:08.903: DSL(ATM0): Using preferred open mode *Mar 1 00:52:08.903: DSL(ATM0): Using ITU sync first for 5 secs, then ANSI/ITU sync alternatively for 2 secs *Mar 1 00:52:08.903: DSL(ATM0): Using subfunction 0x0 *Mar 1 00:52:08.903: LOCAL:Max noise margin for power cutoff 31 *Mar 1 00:52:08.903: DSL(ATM0): GPCI[0] 0x5 *Mar 1 00:52:08.903: DSL(ATM0): GPCI[1] 0x9 *Mar 1 00:52:08.903: DSL(ATM0): GPCI[2] 0x1 *Mar 1 00:52:08.903: DSL(ATM0): GPCI[3] 0x0 *Mar 1 00:52:08.903: DSL(ATM0): Sent extended command 0x3 *Mar 1 00:52:11.403: DSL(ATM0): 1: Modem state = 0x9 *Mar 1 00:52:13.903: DSL(ATM0): 2: Modem state = 0x9 *Mar 1 00:52:16.403: DSL(ATM0): 3: Modem state = 0x9 *Mar 1 00:52:18.903: DSL(ATM0): 4: Modem state = 0x10 *Mar 1 00:52:21.403: DSL(ATM0): 5: Modem state = 0x10 gbs26talavera# *Mar 1 00:52:23.903: DSL(ATM0): 6: Modem state = 0x10sh int atm *Mar 1 00:52:26.403: DSL(ATM0): 7: Modem state = 0x100. *Mar 1 00:52:28.903: DSL(ATM0): 8: Modem state = 0x10 *Mar 1 00:52:29.203: DSL(ATM0): Received response: 0x24 *Mar 1 00:52:29.203: DSL(ATM0): Showtime! *Mar 1 00:52:29.203: DSL(ATM0): Sent command 0x31 *Mar 1 00:52:29.211: DSL(ATM0): Received response: 0x12 *Mar 1 00:52:29.211: DSL(ATM0): operation mode 0x40000 *Mar 1 00:52:29.211: DSL(ATM0): Sent command 0x33 *Mar 1 00:52:29.215: DSL(ATM0): Received response: 0x16 *Mar 1 00:52:29.215: DSL(ATM0): Far End Chip Country Code 0xB5 *Mar 1 01 ATM0.1 is down, line protocol is down Hardware is MPC ATMSAR (with Alcatel ADSL Module) MTU 4470 bytes, BW 1072 Kbit, DLY 340 usec, reliability 255/255, txload 1/255, rxload 1/255 Encapsulation ATM 0 packets input, 0 bytes 3 packets output, 222 bytes 0 OAM cells input, 0 OAM cells output AAL5 CRC errors : 0 AAL5 SAR Timeouts : 0 AAL5 Oversized SDUs : 0 Last clearing of "show interface" counters never gbs26talavera#0:52:29.215: DSL: Far End Chip Vendor ID BDCM *Mar 1 00:52:29.215: DSL: Far End Chip Vendor ID Specific 0x619F *Mar 1 00:52:29.215: DSL: Far End ITU Vendor STD Number 0x0002 *Mar 1 00:52:29.215: DSL(ATM0): Sent command 0x32 *Mar 1 00:52:29.223: DSL(ATM0): Received response: 0x14 *Mar 1 00:52:29.223: DSL(ATM0): Near End Chip Country Code 0x0F *Mar 1 00:52:29.223: DSL: Near End Chip Vendor ID STMI *Mar 1 00:52:29.223: DSL: Near End Chip Vendor ID Specific 0x0000 *Mar 1 00:52:29.223: DSL: Near End ITU Vendor STD Number 0x0003 *Mar 1 00:52:29.223: DSL(ATM0): Sent extended command 0x5 *Mar 1 00:52:29.227: DSL(ATM0): Near End Modem Country Code 0xB5 *Mar 1 00:52:29.227: DSL: Near End Modem Vendor ID CSCO *Mar 1 00:52:29.227: DSL: Near End Modem Vendor ID Specific 0x0000 *Mar 1 00:52:29.227: DSL: Near End Modem Version 12.4(15)T5 *Mar 1 00:52:29.227: DSL: Near End Modem Serial Number FHK121929U7 *Mar 1 00:52:29.227: DSL(ATM0): get Near End extended ITU ID succeeded *Mar 1 00:52:29.227: DSL(ATM0): Sent extended command 0x5