9:44.411: DSL: Open failed: Handshake or init message invalid or had bad CRC -- retrying Jun 1 14:39:54.411: DSL: Trying to bring up DSL line using Embedded firmware version : 3.9.220 Jun 1 14:39:54.411: DSL: Send ADSL_OPEN command. Jun 1 14:39:54.411: DSL: Using subfunction 0x15 Jun 1 14:39:54.411: DSL: Using subfunction 0x15 Jun 1 14:39:54.411: LOCAL:Max noise margin for power cutoff 31 Jun 1 14:39:54.411: DSL: Sent command 0x3 Jun 1 14:39:56.911: DSL: 1: Modem state = 0x8 Jun 1 14:39:59.411: DSL: 2: Modem state = 0x10 Jun 1 14:40:01.911: DSL: 3: Modem state = 0x10 Jun 1 14:40:04.411: DSL: 4: Modem state = 0x10 Jun 1 14:40:05.839: DSL: Received response: 0x22 Jun 1 14:40:05.839: DSL: Open failed: Handshake or init message invalid or had bad CRC -- retrying Jun 1 14:40:15.839: DSL: Trying to bring up DSL line using Embedded firmware version : 3.9.220 Jun 1 14:40:15.839: DSL: Send ADSL_OPEN command. Jun 1 14:40:15.839: DSL: Using subfunction 0x15 Jun 1 14:40:15.839: DSL: Using subfunction 0x15 Jun 1 14:40:15.839: LOCAL:Max noise margin for power cutoff 31 Jun 1 14:40:15.839: DSL: Sent command 0x3 Jun 1 14:40:18.339: DSL: 1: Modem state = 0x8 Jun 1 14:40:20.839: DSL: 2: Modem state = 0x10 Jun 1 14:40:23.339: DSL: 3: Modem state = 0x10 Jun 1 14:40:25.839: DSL: 4: Modem state = 0x10 Jun 1 14:40:26.823: DSL: Received response: 0x22 Jun 1 14:40:26.823: DSL: Open failed: Handshake or init message invalid or had bad CRC -- retrying Jun 1 14:40:36.823: DSL: Trying to bring up DSL line using Embedded firmware version : 3.9.220 Jun 1 14:40:36.823: DSL: Send ADSL_OPEN command. Jun 1 14:40:36.823: DSL: Using subfunction 0x15 Jun 1 14:40:36.823: DSL: Using subfunction 0x15 Jun 1 14:40:36.823: LOCAL:Max noise margin for power cutoff 31 Jun 1 14:40:36.823: DSL: Sent command 0x3 Jun 1 14:40:39.323: DSL: 1: Modem state = 0x8 Jun 1 14:40:41.823: DSL: 2: Modem state = 0x10 Jun 1 14:40:43.043: DSL: Received response: 0x22 Jun 1 14:40:43.043: DSL: Open failed: Handshake or init message invalid or had bad CRC -- retrying Jun 1 14:40:53.043: DSL: Trying to bring up DSL line using Embedded firmware version : 3.9.220 Jun 1 14:40:53.043: DSL: Send ADSL_OPEN command. Jun 1 14:40:53.043: DSL: Using subfunction 0x15 Jun 1 14:40:53.043: DSL: Using subfunction 0x15 Jun 1 14:40:53.043: LOCAL:Max noise margin for power cutoff 31 Jun 1 14:40:53.043: DSL: Sent command 0x3 Jun 1 14:40:55.543: DSL: 1: Modem state = 0x8 Jun 1 14:40:58.043: DSL: 2: Modem state = 0x10 Jun 1 14:41:00.543: DSL: 3: Modem state = 0x10 Jun 1 14:41:03.043: DSL: 4: Modem state = 0x10 Jun 1 14:41:04.007: DSL: Received response: 0x22 Jun 1 14:41:04.007: DSL: Open failed: Handshake or init message invalid or had bad CRC -- retrying Jun 1 14:41:14.007: DSL: Trying to bring up DSL line using Embedded firmware version : 3.9.220 Jun 1 14:41:14.007: DSL: Send ADSL_OPEN command. Jun 1 14:41:14.007: DSL: Using subfunction 0x15 Jun 1 14:41:14.007: DSL: Using subfunction 0x15 Jun 1 14:41:14.007: LOCAL:Max noise margin for power cutoff 31 Jun 1 14:41:14.007: DSL: Sent command 0x3 Jun 1 14:41:16.507: DSL: 1: Modem state = 0x8 Jun 1 14:41:19.007: DSL: 2: Modem state = 0x10 Jun 1 14:41:20.659: DSL: Received response: 0x22 Jun 1 14:41:20.659: DSL: Open failed: Handshake or init message invalid or had bad CRC -- retrying Jun 1 14:41:30.659: DSL: Trying to bring up DSL line using Embedded firmware version : 3.9.220 Jun 1 14:41:30.659: DSL: Send ADSL_OPEN command. Jun 1 14:41:30.659: DSL: Using subfunction 0x15 Jun 1 14:41:30.659: DSL: Using subfunction 0x15 Jun 1 14:41:30.659: LOCAL:Max noise margin for power cutoff 31 Jun 1 14:41:30.659: DSL: Sent command 0x3 Jun 1 14:41:33.159: DSL: 1: Modem state = 0x8 Jun 1 14:41:35.659: DSL: 2: Modem state = 0x10 Jun 1 14:41:38.159: DSL: 3: Modem state = 0x10 Jun 1 14:41:40.659: DSL: 4: Modem state = 0x10 Jun 1 14:41:41.791: DSL: Received response: 0x22 Jun 1 14:41:41.791: DSL: Open failed: Handshake or init message invalid or had bad CRC -- retrying Jun 1 14:41:51.791: DSL: Trying to bring up DSL line using Embedded firmware version : 3.9.220 Jun 1 14:41:51.791: DSL: Send ADSL_OPEN command. Jun 1 14:41:51.791: DSL: Using subfunction 0x15 Jun 1 14:41:51.791: DSL: Using subfunction 0x15 Jun 1 14:41:51.791: LOCAL:Max noise margin for power cutoff 31 Jun 1 14:41:51.791: DSL: Sent command 0x3 Jun 1 14:41:54.291: DSL: 1: Modem state = 0x8 Jun 1 14:41:56.791: DSL: 2: Modem state = 0x10 Jun 1 14:41:59.291: DSL: 3: Modem state = 0x10 Jun 1 14:42:01.791: DSL: 4: Modem state = 0x10 Jun 1 14:42:02.767: DSL: Received response: 0x22 Jun 1 14:42:02.767: DSL: Open failed: Handshake or init message invalid or had bad CRC -- retrying Jun 1 14:42:12.767: DSL: Trying to bring up DSL line using Embedded firmware version : 3.9.220 Jun 1 14:42:12.767: DSL: Send ADSL_OPEN command. Jun 1 14:42:12.767: DSL: Using subfunction 0x15 Jun 1 14:42:12.767: DSL: Using subfunction 0x15 Jun 1 14:42:12.767: LOCAL:Max noise margin for power cutoff 31 Jun 1 14:42:12.767: DSL: Sent command 0x3 Jun 1 14:42:15.267: DSL: 1: Modem state = 0x8 Jun 1 14:42:17.767: DSL: 2: Modem state = 0x10 Jun 1 14:42:20.267: DSL: 3: Modem state = 0x10 Jun 1 14:42:22.767: DSL: 4: Modem state = 0x10 Jun 1 14:42:24.687: DSL: Received response: 0x24 Jun 1 14:42:24.687: DSL: DSL line trained using Embedded firmware version : 3.9.220 Jun 1 14:42:24.687: DSL: Showtime! Jun 1 14:42:24.687: DSL: Sent command 0x11 Jun 1 14:42:24.691: DSL: Received response: 0x61 Jun 1 14:42:24.691: DSL: Read firmware revision 0x1A04 Jun 1 14:42:24.695: DSL: Sent command 0x31 Jun 1 14:42:24.699: DSL: Received response: 0x12 Jun 1 14:42:24.699: DSL: operation mode 0x0002 Jun 1 14:42:24.699: DSL: Sent command 0x33 Jun 1 14:42:24.703: DSL: Received response: 0x16 Jun 1 14:42:24.703: DSL: Far End ITU Country Code 0xB5 Jun 1 14:42:24.703: DSL: Far End ITU Vendor ID TSTC Jun 1 14:42:24.703: DSL: Far End ITU Vendor ID Specific 0x0000 Jun 1 14:42:24.703: DSL: Far End ITU Vendor STD Number 0x0001 Jun 1 14:42:24.703: DSL: Sent command 0x32 Jun 1 14:42:24.707: DSL: Received response: 0x14 Jun 1 14:42:24.707: DSL: Near End ITU Country Code 0x00 Jun 1 14:42:24.707: DSL: Near End ITU Vendor ID ALCB Jun 1 14:42:24.711: DSL: Near End ITU Vendor ID Specific 0x0000 Jun 1 14:42:24.711: DSL: Near End ITU Vendor STD Number 0x0001 Jun 1 14:42:24.711: DSL: SM: [DMTDSL_DO_OPEN -> DMTDSL_SHOWTIME] Jun 1 14:42:25.023: Resetting ATM0 Jun 1 14:42:26.767: %LINK-3-UPDOWN: Interface ATM0, changed state to up Jun 1 14:42:27.767: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to up Jun 1 14:42:39.615: %DIALER-6-BIND: Interface Vi1 bound to profile Di0 Jun 1 14:42:39.619: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state to up Jun 1 14:42:41.223: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access1, changed state to up Jun 1 14:43:04.715: DSL: Defect: LOS LOF LCDi LOM: retraining Jun 1 14:43:04.715: DSL: Received response: 0x41 Jun 1 14:43:05.551: Resetting ATM0 Jun 1 14:43:07.215: DSL: SM: [DMTDSL_SHOWTIME -> DMTDSL_RE_OPEN] Jun 1 14:43:07.215: DSL: Send ADSL_CLOSE command. Jun 1 14:43:07.215: DSL: Sent command 0x4 Jun 1 14:43:07.303: %LINK-3-UPDOWN: Interface ATM0, changed state to down Jun 1 14:43:07.775: DSL: Received response: 0x25 Jun 1 14:43:07.775: DSL: Connection closed Jun 1 14:43:07.783: DSL: SM: [DMTDSL_RE_OPEN -> DMTDSL_DO_OPEN] Jun 1 14:43:07.783: DSL: Trying to bring up DSL line using Embedded firmware version : 3.9.220 Jun 1 14:43:07.783: DSL: Send ADSL_OPEN command. Jun 1 14:43:07.787: DSL: Using subfunction 0x15 Jun 1 14:43:07.787: DSL: Using subfunction 0x15 Jun 1 14:43:07.787: LOCAL:Max noise margin for power cutoff 31 Jun 1 14:43:07.787: DSL: Sent command 0x3 Jun 1 14:43:08.303: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to down Jun 1 14:43:10.287: DSL: 1: Modem state = 0x8 Jun 1 14:43:12.787: DSL: 2: Modem state = 0x10 Jun 1 14:43:15.287: DSL: 3: Modem state = 0x10 Jun 1 14:43:17.787: DSL: 4: Modem state = 0x10 Jun 1 14:43:19.699: DSL: Received response: 0x24 Jun 1 14:43:19.699: DSL: DSL line trained using Embedded firmware version : 3.9.220 Jun 1 14:43:19.699: DSL: Showtime! Jun 1 14:43:19.699: DSL: Sent command 0x11 Jun 1 14:43:19.707: DSL: Received response: 0x61 Jun 1 14:43:19.711: DSL: Read firmware revision 0x1A04 Jun 1 14:43:19.711: DSL: Sent command 0x31 Jun 1 14:43:19.715: DSL: Received response: 0x12 Jun 1 14:43:19.715: DSL: operation mode 0x0002 Jun 1 14:43:19.715: DSL: Sent command 0x33 Jun 1 14:43:19.719: DSL: Received response: 0x16 Jun 1 14:43:19.719: DSL: Far End ITU Country Code 0xB5 Jun 1 14:43:19.719: DSL: Far End ITU Vendor ID TSTC Jun 1 14:43:19.719: DSL: Far End ITU Vendor ID Specific 0x0000 Jun 1 14:43:19.719: DSL: Far End ITU Vendor STD Number 0x0001 Jun 1 14:43:19.719: DSL: Sent command 0x32 Jun 1 14:43:19.723: DSL: Received response: 0x14 Jun 1 14:43:19.723: DSL: Near End ITU Country Code 0x00 Jun 1 14:43:19.723: DSL: Near End ITU Vendor ID ALCB Jun 1 14:43:19.727: DSL: Near End ITU Vendor ID Specific 0x0000 Jun 1 14:43:19.727: DSL: Near End ITU Vendor STD Number 0x0001 Jun 1 14:43:19.727: DSL: SM: [DMTDSL_DO_OPEN -> DMTDSL_SHOWTIME] Jun 1 14:43:20.015: Resetting ATM0 Jun 1 14:43:21.763: %LINK-3-UPDOWN: Interface ATM0, changed state to up Jun 1 14:43:22.763: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to up Jun 1 14:43:39.727: DSL: Defect: LCDi: retraining Jun 1 14:43:40.527: Resetting ATM0 Jun 1 14:43:42.227: DSL: SM: [DMTDSL_SHOWTIME -> DMTDSL_RE_OPEN] Jun 1 14:43:42.227: DSL: Send ADSL_CLOSE command. Jun 1 14:43:42.227: DSL: Sent command 0x4 Jun 1 14:43:42.275: %LINK-3-UPDOWN: Interface ATM0, changed state to down Jun 1 14:43:42.747: DSL: Received response: 0x25 Jun 1 14:43:42.747: DSL: Connection closed Jun 1 14:43:42.755: DSL: SM: [DMTDSL_RE_OPEN -> DMTDSL_DO_OPEN] Jun 1 14:43:42.759: DSL: Trying to bring up DSL line using Embedded firmware version : 3.9.220 Jun 1 14:43:42.759: DSL: Send ADSL_OPEN command. Jun 1 14:43:42.759: DSL: Using subfunction 0x15 Jun 1 14:43:42.759: DSL: Using subfunction 0x15 Jun 1 14:43:42.759: LOCAL:Max noise margin for power cutoff 31 Jun 1 14:43:42.759: DSL: Sent command 0x3 Jun 1 14:43:43.275: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to down Jun 1 14:43:45.259: DSL: 1: Modem state = 0x8 Jun 1 14:43:47.759: DSL: 2: Modem state = 0x8 Jun 1 14:43:50.259: DSL: 3: Modem state = 0x10 Jun 1 14:43:52.763: DSL: 4: Modem state = 0x10 Jun 1 14:43:55.263: DSL: 5: Modem state = 0x10 Jun 1 14:43:57.763: DSL: 6: Modem state = 0x10 Jun 1 14:43:58.711: DSL: Received response: 0x24 Jun 1 14:43:58.711: DSL: DSL line trained using Embedded firmware version : 3.9.220 Jun 1 14:43:58.711: DSL: Showtime! Jun 1 14:43:58.711: DSL: Sent command 0x11 Jun 1 14:43:58.715: DSL: Received response: 0x61 Jun 1 14:43:58.715: DSL: Read firmware revision 0x1A04 Jun 1 14:43:58.715: DSL: Sent command 0x31 Jun 1 14:43:58.719: DSL: Received response: 0x12 Jun 1 14:43:58.723: DSL: operation mode 0x0002 Jun 1 14:43:58.723: DSL: Sent command 0x33 Jun 1 14:43:58.727: DSL: Received response: 0x16 Jun 1 14:43:58.727: DSL: Far End ITU Country Code 0xB5 Jun 1 14:43:58.727: DSL: Far End ITU Vendor ID TSTC Jun 1 14:43:58.727: DSL: Far End ITU Vendor ID Specific 0x0000 Jun 1 14:43:58.727: DSL: Far End ITU Vendor STD Number 0x0001 Jun 1 14:43:58.727: DSL: Sent command 0x32 Jun 1 14:43:58.731: DSL: Received response: 0x14 Jun 1 14:43:58.731: DSL: Near End ITU Country Code 0x00 Jun 1 14:43:58.731: DSL: Near End ITU Vendor ID ALCB Jun 1 14:43:58.731: DSL: Near End ITU Vendor ID Specific 0x0000 Jun 1 14:43:58.731: DSL: Near End ITU Vendor STD Number 0x0001 Jun 1 14:43:58.731: DSL: SM: [DMTDSL_DO_OPEN -> DMTDSL_SHOWTIME] Jun 1 14:43:58.987: Resetting ATM0 Jun 1 14:44:00.735: %LINK-3-UPDOWN: Interface ATM0, changed state to up Jun 1 14:44:01.735: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to up Jun 1 14:44:12.203: Data Cell received on vpi = 20 vci = 10474 Jun 1 14:44:12.203: First 14 bytes of payload: E81A D22E FB3E 7EBE B756 5670 0775 Jun 1 14:44:16.231: DSL: Defect: LCDi LOM: retraining Jun 1 14:44:16.499: Resetting ATM0 Jun 1 14:44:18.247: %LINK-3-UPDOWN: Interface ATM0, changed state to down Jun 1 14:44:18.731: DSL: SM: [DMTDSL_SHOWTIME -> DMTDSL_RE_OPEN] Jun 1 14:44:18.731: DSL: Send ADSL_CLOSE command. Jun 1 14:44:18.731: DSL: Sent command 0x4 Jun 1 14:44:19.247: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to down Jun 1 14:44:19.251: DSL: Received response: 0x25 Jun 1 14:44:19.251: DSL: Connection closed Jun 1 14:44:19.255: DSL: SM: [DMTDSL_RE_OPEN -> DMTDSL_DO_OPEN] Jun 1 14:44:19.259: DSL: Trying to bring up DSL line using Embedded firmware version : 3.9.220 Jun 1 14:44:19.259: DSL: Send ADSL_OPEN command. Jun 1 14:44:19.259: DSL: Using subfunction 0x15 Jun 1 14:44:19.259: DSL: Using subfunction 0x15 Jun 1 14:44:19.259: LOCAL:Max noise margin for power cutoff 31 Jun 1 14:44:19.259: DSL: Sent command 0x3 Jun 1 14:44:21.759: DSL: 1: Modem state = 0x8 Jun 1 14:44:23.639: %DIALER-6-UNBIND: Interface Vi1 unbound from profile Di0 Jun 1 14:44:23.643: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state to down Jun 1 14:44:24.259: DSL: 2: Modem state = 0x10 Jun 1 14:44:24.639: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access1, changed state to down Jun 1 14:44:26.759: DSL: 3: Modem state = 0x10 Jun 1 14:44:29.259: DSL: 4: Modem state = 0x10 Jun 1 14:44:31.759: DSL: 5: Modem state = 0x10 Jun 1 14:44:32.715: DSL: Received response: 0x24 Jun 1 14:44:32.715: DSL: DSL line trained using Embedded firmware version : 3.9.220 Jun 1 14:44:32.715: DSL: Showtime! Jun 1 14:44:32.715: DSL: Sent command 0x11 Jun 1 14:44:32.715: DSL: Received response: 0x61 Jun 1 14:44:32.719: DSL: Read firmware revision 0x1A04 Jun 1 14:44:32.719: DSL: Sent command 0x31 Jun 1 14:44:32.723: DSL: Received response: 0x12 Jun 1 14:44:32.723: DSL: operation mode 0x0002 Jun 1 14:44:32.723: DSL: Sent command 0x33 Jun 1 14:44:32.727: DSL: Received response: 0x16 Jun 1 14:44:32.727: DSL: Far End ITU Country Code 0xB5 Jun 1 14:44:32.727: DSL: Far End ITU Vendor ID TSTC Jun 1 14:44:32.731: DSL: Far End ITU Vendor ID Specific 0x0000 Jun 1 14:44:32.731: DSL: Far End ITU Vendor STD Number 0x0001 Jun 1 14:44:32.731: DSL: Sent command 0x32 Jun 1 14:44:32.735: DSL: Received response: 0x14 Jun 1 14:44:32.735: DSL: Near End ITU Country Code 0x00 Jun 1 14:44:32.735: DSL: Near End ITU Vendor ID ALCB Jun 1 14:44:32.735: DSL: Near End ITU Vendor ID Specific 0x0000 Jun 1 14:44:32.735: DSL: Near End ITU Vendor STD Number 0x0001 Jun 1 14:44:32.735: DSL: SM: [DMTDSL_DO_OPEN -> DMTDSL_SHOWTIME] Jun 1 14:44:33.959: Resetting ATM0 Jun 1 14:44:35.707: %LINK-3-UPDOWN: Interface ATM0, changed state to up Jun 1 14:44:36.707: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0, changed state to up