cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1945
Views
0
Helpful
3
Replies

dsl-connection lost every 5 to 15 Minutes

Carsten Radke
Level 1
Level 1

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

3 Replies 3

paolo bevilacqua
Hall of Fame
Hall of Fame

Try updating DSL firmware.

I've tried 3 different firmwareversions, but its always the same issue.

The dsl-connection lost the carrier only in the afternoon .

any idea ?

Try with the ISP-provided modem and if troubles open a ticket with telco.

Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community:

Innovations in Cisco Full Stack Observability - A new webinar from Cisco