On a cisco 876 w i have a problem with the dsl-connect.
Every 5 to 15 Minutes the dsl-connection is lost ( .Nov 11 05:17:09.895 )
here is a debug of
ATM events debugging is on
ATM errors debugging is on
Is this a hardware or a provider problem ?
some ideas ?
Cisco IOS Software, C870 Software (C870-ADVSECURITYK9-M), Version 12.4(24)T3,RELEASE SOFTWARE (fc2)
.Nov 11 05:12:44.309: ATM0: AAL5 rx errors (status = 28130000)
.Nov 11 05:12:44.313: ATM0: AAL5 rx errors (status = 08130000)
.Nov 11 05:12:44.317: ATM0: AAL5 rx errors (status = 08130000)
.Nov 11 05:12:44.321: ATM0: AAL5 rx errors (status = 08130000)
.Nov 11 05:12:44.325: ATM0: AAL5 rx errors (status = 0C130000)
.Nov 11 05:12:44.329: ATM0: AAL5 rx errors (status = 08130000)
.Nov 11 05:12:44.329: ATM0: AAL5 rx errors (status = 08130000)
.Nov 11 05:12:44.329: ATM0: AAL5 rx errors (status = 08130000)
.Nov 11 05:13:46.539: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:13:46.543: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:13:46.547: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.
.
.
.
.
.Nov 11 05:14:50.072: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:14:50.076: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:14:50.076: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:14:50.084: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:14:50.096: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:14:50.100: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:14:50.104: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:14:50.104: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:14:50.108: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:14:50.112: atmsar_fastsend(ATM0): QUEUE_PER_VC enqueue fails!, vcd = 1
.Nov 11 05:17:05.394: DSL(ATM0): Defect: LOS LOF: retraining
.Nov 11 05:17:05.394: DSL(ATM0): Received response: 0x41
.Nov 11 05:17:07.894: atmsar_atm_lineaction(ATM0): state=0
.Nov 11 05:17:07.894: atmsar_1a_teardown_vc(ATM0): vc:1 vpi:1 vci:32
.Nov 11 05:17:07.894: ATM(): IP multicast cache invalidated for ATM0.1
.Nov 11 05:17:07.894: ATM: PVC removed, ATM0.1 VCD 1 (1/32)
.Nov 11 05:17:07.894: DSL(ATM0): Sent command 0x4
.Nov 11 05:17:08.418: DSL(ATM0): Received response: 0x25
.Nov 11 05:17:08.418: DSL(ATM0): Connection closed
.Nov 11 05:17:08.518: DSL(ATM0): Sent command 0x16
.Nov 11 05:17:08.518: DSL(ATM0): Received response: 0x6A
.Nov 11 05:17:08.518: DSL(ATM0): training log start address 0x27A4D0
.Nov 11 05:17:08.518: DSL(ATM0): Sent command 0x16
.Nov 11 05:17:08.522: DSL(ATM0): Received response: 0x6A
.Nov 11 05:17:08.522: DSL(ATM0): training log end address 0x2935E7
.Nov 11 05:17:08.522: DSL(ATM0): Training log collection started (max log size 400000)...
.Nov 11 05:17:08.522: DSL(ATM0): Sent extended command 0x13
.Nov 11 05:17:09.895: %LINK-3-UPDOWN: Interface ATM0, changed state to down
.Nov 11 05:17:09.895: atmsar_atm_lineaction(ATM0): state=0
.Nov 11 05:17:10.895: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to down
.Nov 11 05:17:36.840: DSL(ATM0): ext response 0x21 received, data size 298262
.Nov 11 05:17:36.864: DSL(ATM0): valid log data size 298262
.Nov 11 05:17:36.864: DSL(ATM0): Training log collection completed
.Nov 11 05:17:36.864: DSL: SM: [DMTDSL_SHOWTIME -> DMTDSL_RE_OPEN]
.Nov 11 05:17:36.864: DSL(ATM0): Send ADSL_CLOSE command.
.Nov 11 05:17:36.864: DSL: SM: [DMTDSL_RE_OPEN -> DMTDSL_DO_OPEN]
.Nov 11 05:17:36.864: DSL(ATM0): Send ADSL_OPEN command.
.Nov 11 05:17:36.864: DSL(ATM0): Using preferred open mode
.Nov 11 05:17:36.864: DSL(ATM0): Using ITU sync first for 5 secs, then ANSI/ITU sync alternatively for 2 secs
.Nov 11 05:17:36.864: DSL(ATM0): Using subfunction 0x0
.Nov 11 05:17:36.864: LOCAL:Max noise margin for power cutoff 31
.Nov 11 05:17:36.864: DSL(ATM0): GPCI[0] 0xA .Nov 11 05:17:36.864: DSL(ATM0): GPCI[1] 0x2
.Nov 11 05:17:36.864: DSL(ATM0): GPCI[2] 0x2 .Nov 11 05:17:36.868: DSL(ATM0): GPCI[3] 0x0
.Nov 11 05:17:36.868: DSL(ATM0): Sent extended command 0x3
.Nov 11 05:17:39.369: DSL(ATM0): 1: Modem state = 0x9
.Nov 11 05:17:41.870: DSL(ATM0): 2: Modem state = 0x9
.Nov 11 05:17:44.370: DSL(ATM0): 3: Modem state = 0x10
.Nov 11 05:17:45.726: ATM0: atmsar_vc_dlcx
.Nov 11 05:17:46.871: DSL(ATM0): 4: Modem state = 0x10
.Nov 11 05:17:49.371: DSL(ATM0): 5: Modem state = 0x10
.Nov 11 05:17:51.872: DSL(ATM0): 6: Modem state = 0x10
.Nov 11 05:17:54.372: DSL(ATM0): 7: Modem state = 0x10
.Nov 11 05:17:56.597: DSL(ATM0): Received response: 0x22
.Nov 11 05:17:56.597: DSL(ATM0): Open failed: Protocol error received -- retrying
.Nov 11 05:17:56.597: DSL(ATM0): Sent command 0x16
.Nov 11 05:17:56.609: DSL(ATM0): Received response: 0x6A
.Nov 11 05:17:56.609: DSL(ATM0): training log start address 0x27A4D0
.Nov 11 05:17:56.609: DSL(ATM0): Sent command 0x16
.Nov 11 05:17:56.613: DSL(ATM0): Received response: 0x6A
.Nov 11 05:17:56.613: DSL(ATM0): training log end address 0x292B86
.Nov 11 05:17:56.613: DSL(ATM0): Training log collection started (max log size 400000)...
.Nov 11 05:17:56.613: DSL(ATM0): Sent extended command 0x13
.Nov 11 05:17:59.013: %DIALER-6-UNBIND: Interface Vi1 unbound from profile Di0
.Nov 11 05:17:59.025: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state to down
.Nov 11 05:18:00.013: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access1, changed state to down
.Nov 11 05:18:06.023: DSL(ATM0): ext response 0x21 received, data size 100022
.Nov 11 05:18:06.031: DSL(ATM0): valid log data size 100022
.Nov 11 05:18:06.031: DSL(ATM0): Training log collection completed
.Nov 11 05:18:06.031: DSL(ATM0): sleep 5 seconds
.Nov 11 05:18:11.032: DSL(ATM0): Send ADSL_OPEN command.
.Nov 11 05:18:11.032: DSL(ATM0): Using preferred open mode
.Nov 11 05:18:11.032: DSL(ATM0): Using ITU sync first for 5 secs, then ANSI/ITU sync alternatively for 2 secs
.Nov 11 05:18:11.032: DSL(ATM0): Using subfunction 0x0
.Nov 11 05:18:11.032: LOCAL:Max noise margin for power cutoff 31
.Nov 11 05:18:11.032: DSL(ATM0): GPCI[0] 0xA
.Nov 11 05:18:11.032: DSL(ATM0): GPCI[1] 0x2
.Nov 11 05:18:11.032: DSL(ATM0): GPCI[2] 0x2
.Nov 11 05:18:11.032: DSL(ATM0): GPCI[3] 0x0
.Nov 11 05:18:11.032: DSL(ATM0): Sent extended command 0x3
.Nov 11 05:18:13.532: DSL(ATM0): 1: Modem state = 0x9
.Nov 11 05:18:16.033: DSL(ATM0): 2: Modem state = 0x9
.Nov 11 05:18:18.533: DSL(ATM0): 3: Modem state = 0x10
.Nov 11 05:18:21.034: DSL(ATM0): 4: Modem state = 0x10
.Nov 11 05:18:23.535: DSL(ATM0): 5: Modem state = 0x10
.Nov 11 05:18:26.035: DSL(ATM0): 6: Modem state = 0x10
.Nov 11 05:18:28.536: DSL(ATM0): 7: Modem state = 0x10
.Nov 11 05:18:31.036: DSL(ATM0): 8: Modem state = 0x10
.Nov 11 05:18:31.220: DSL(ATM0): Received response: 0x24
.Nov 11 05:18:31.220: DSL(ATM0): Showtime!
.Nov 11 05:18:31.220: DSL(ATM0): Sent command 0x31
.Nov 11 05:18:31.224: DSL(ATM0): Received response: 0x12
.Nov 11 05:18:31.224: DSL(ATM0): operation mode 0x2000000
.Nov 11 05:18:31.224: DSL(ATM0): Sent extended command 0xC
.Nov 11 05:18:31.360: DSL(ATM0): ext response 0xF received, data size 49
.Nov 11 05:18:31.360: DSL(ATM0): ADSL_GET_MANAGEMENT_COUNTERS_EXT - data length = 49
.Nov 11 05:18:31.364: DSL(ATM0): 0x01
.Nov 11 05:18:31.364: DSL(ATM0): Get FAR End parameter failed
.Nov 11 05:18:31.364: DSL(ATM0): Sent extended command 0xC
.Nov 11 05:18:31.364: DSL(ATM0): ext response 0xF received, data size 49
.Nov 11 05:18:31.364: DSL(ATM0): ADSL_GET_MANAGEMENT_COUNTERS_EXT - data length = 49
.Nov 11 05:18:31.364: DSL(ATM0): 0x00
.Nov 11 05:18:31.364: DSL(ATM0): Get NEAR End parameter failed
.Nov 11 05:18:31.364: DSL(ATM0): Sent command 0x33
.Nov 11 05:18:31.368: DSL(ATM0): Received response: 0x16
.Nov 11 05:18:31.372: DSL(ATM0): Far End Chip Country Code 0xB5
.Nov 11 05:18:31.372: DSL: Far End Chip Vendor ID IFTN
.Nov 11 05:18:31.372: DSL: Far End Chip Vendor ID Specific 0x7178
.Nov 11 05:18:31.372: DSL: Far End ITU Vendor STD Number 0x0002
.Nov 11 05:18:31.372: DSL(ATM0): Sent command 0x32
.Nov 11 05:18:31.376: DSL(ATM0): Received response: 0x14
.Nov 11 05:18:31.376: DSL(ATM0): Near End Chip Country Code 0x0F
.Nov 11 05:18:31.376: DSL: Near End Chip Vendor ID STMI
.Nov 11 05:18:31.376: DSL: Near End Chip Vendor ID Specific 0x0000
.Nov 11 05:18:31.376: DSL: Near End ITU Vendor STD Number 0x0003
.Nov 11 05:18:31.376: DSL(ATM0): Sent extended command 0x5
.Nov 11 05:18:31.384: DSL(ATM0): Near End Modem Country Code 0xB5
.Nov 11 05:18:31.384: DSL: Near End Modem Vendor ID CSCO
.Nov 11 05:18:31.384: DSL: Near End Modem Vendor ID Specific 0x0000
.Nov 11 05:18:31.384: DSL: Near End Modem Version 12.4(24)T3
.Nov 11 05:18:31.384: DSL: Near End Modem Serial Number FCZ130892KT
.Nov 11 05:18:31.384: DSL(ATM0): get Near End extended ITU ID succeeded
.Nov 11 05:18:31.384: DSL(ATM0): Sent extended command 0x5
.Nov 11 05:18:31.540: DSL(ATM0): Far End Modem Country Code 0x00
.Nov 11 05:18:31.540: DSL: Far End Modem Vendor ID
.Nov 11 05:18:31.540: DSL: Far End Modem Vendor ID Specific 0x0000
.Nov 11 05:18:31.540: DSL: Far End Modem Version
.Nov 11 05:18:31.540: DSL: Far End Modem Serial Number
.Nov 11 05:18:31.540: DSL(ATM0): get Far End extended ITU ID succeeded
.Nov 11 05:18:31.540: Matching SAR to Line speed: Attempt number 1 cps 1 slots 24 APL Level 0 slots 24 APL Level 1 slots 24 APL Level 2 slots 24
.Nov 11 05:18:31.540: ATM0: atmsar_get_plim_info
.Nov 11 05:18:31.540: ATM0:atmsar_update_plimtype
.Nov 11 05:18:31.540: ATM0 atmsar_update_us_bandwidth(): upstream bw =728 Kbps
.Nov 11 05:18:31.540: DSL: SM: [DMTDSL_DO_OPEN -> DMTDSL_SHOWTIME]
.Nov 11 05:18:31.540: DSL(ATM0): Sent command 0x16
.Nov 11 05:18:31.548: DSL(ATM0): Received response: 0x6A
.Nov 11 05:18:31.548: DSL(ATM0): training log start address 0x27A4D0
.Nov 11 05:18:31.548: DSL(ATM0): Sent command 0x16
.Nov 11 05:18:31.548: DSL(ATM0): Received response: 0x6A
.Nov 11 05:18:31.548: DSL(ATM0): training log end address 0x2938A9
.Nov 11 05:18:31.548: DSL(ATM0): Training log collection started (max log size 400000)...
.Nov 11 05:18:31.548: DSL(ATM0): Sent extended command 0x13
.Nov 11 05:18:35.737: ATM0: atmsar_vc_dlcx
.Nov 11 05:18:35.737: (ATM0)1a_enable: delay activation of vcd=1, vc=0x8466F934
.Nov 11 05:18:35.737: atmsar enable ATM0
.Nov 11 05:18:35.737: ATM0: atmsar_bringup_interface: Interface and atm_db flags are UP
.Nov 11 05:18:37.702: Reserved bw for 1/32 Available bw = 728
.Nov 11 05:18:37.702: ATM0: atmsar_1a_setup_vc, vcinfo = 0x8466F934
.Nov 11 05:18:37.702: ATM0 atmsar_vc_setup: vcd 1
.Nov 11 05:18:37.702: Fill up paramaters for PVC 1/32[1] - Request for CoS 0 PCR 0 SCR 0 MCR 0 Available 728
.Nov 11 05:18:37.702: Scheduler parameters for 1/32. Type UBR. PCR 728
.Nov 11 05:18:37.702: Total scheduled bandwidth after configuringPVC 1/32 will be 0
.Nov 11 05:18:37.702: atmsar_setup_cos(ATM0): vc:1 wred_name:- max_q:0
.Nov 11 05:18:37.702: ATM0: VC setup successful (vcd = 1)
.Nov 11 05:18:37.702: ATM: PVC activated, ATM0.1 VCD 1 (1/32)
.Nov 11 05:18:37.738: %LINK-3-UPDOWN: Interface ATM0, changed state to up
.Nov 11 05:18:37.738: atmsar_atm_lineaction(ATM0): state=4
.Nov 11 05:18:37.738: ATM: Repapulate, ATM0
.Nov 11 05:18:37.738: ATM: Repapulate, ATM0.1
.Nov 11 05:18:37.738: ATM: PVC activated, ATM0.1 VCD 1 (1/32)
.Nov 11 05:18:38.738: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to up
.Nov 11 05:18:41.983: %DIALER-6-BIND: Interface Vi1 bound to profile Di0
.Nov 11 05:18:41.987: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state to up
.Nov 11 05:18:42.639: ATM: Repapulate, Dialer0
.Nov 11 05:18:43.551: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access1, changed state to up
.Nov 11 05:18:43.551: ATM: Repapulate, Virtual-Access1
.Nov 11 05:18:44.415: DSL(ATM0): ext response 0x21 received, data size 103385
.Nov 11 05:18:44.423: DSL(ATM0): valid log data size 103385
.Nov 11 05:18:44.423: DSL(ATM0): Training log collection completed
