Moin Orca!
Mach mal noch den PPP-Trace an.
Ciao, Georg
O2 VDSL Fehler tracen
Moderator: Lancom-Systems Moderatoren
Re: O2 VDSL Fehler tracen
Hi Georg,
Fehler war grad eben wieder da, so hatte ich gleich Gelegenheit PPP mitzutracen. Hab erst wieder Retrain versucht und dann Modem Restart. Bei letzterem hat er diesmal 3 Anläufe gebraucht, bis der Sync geklappt hat (dadurch sehr viel länger, sorry, hab keine Möglichkeit gefunden das hier im Forum kompakter darzustellen):
Kannst Du, oder jemand anders, da etwas herauslesen?
Fehler war grad eben wieder da, so hatte ich gleich Gelegenheit PPP mitzutracen. Hab erst wieder Retrain versucht und dann Modem Restart. Bei letzterem hat er diesmal 3 Anläufe gebraucht, bis der Sync geklappt hat (dadurch sehr viel länger, sorry, hab keine Möglichkeit gefunden das hier im Forum kompakter darzustellen):
Code: Alles auswählen
03/10/2015 19:42:18 System boot after manual coldboot request
DEVICE: LANCOM 1781VA (over ISDN)
HW-RELEASE: B
VERSION: 9.04.0129RU3 / 19.02.2015
[Sysinfo] 2015/03/12 14:49:24,545
Result of command: "sysinfo"
DEVICE: LANCOM 1781VA (over ISDN)
HW-RELEASE: B
SERIAL-NUMBER: 4002945218100169
MAC-ADDRESS: 00a0571e9b60
IP-ADDRESS: 192.168.11.1
IP-NETMASK: 255.255.255.0
INTRANET-ADDRESS: 0.0.0.0
INTRANETMASK: 0.0.0.0
LANCAPI-PORT: 75
VERSION: 9.04.0129RU3 / 19.02.2015
NAME: stormtrooper
CONFIG-STATUS: 1056;0;c40d36810a171a773bae7f8a96886541c923363e.08254412032015.6853
FIRMWARE-STATUS: 0;0.7;0.1;9.04.0129RU3.19022015.7;9.04.0084RU2.04122014.6
HW-MASK: 00000000000000000000000011100011
FEATUREWORD: 00000000001000000000000100011100
REGISTERED-WORD: 00000000001000000000000100011100
FEATURE-LIST: 02/F
FEATURE-LIST: 03/F
FEATURE-LIST: 04/F
FEATURE-LIST: 08/F
FEATURE-LIST: 15/F
TIME: 14492612032015
HTTP-PORT: 80
HTTPS-PORT: 443
TELNET-PORT: 23
TELNET-SSL-PORT: 992
SSH-PORT: 22
SNMP-PORT: 161
TFTP-PORT: 69
Production-Date: 2013-08-19
MOD-Level: B1
LOCATION:
COUNTRY-CODE: 0/0 (NA)
COMMENT:
MYVPN: 0
MYVPN-HOSTNAME:
EXTENDED-NAME: LANCOM 1781VA (over ISDN)
[PPP] 2015/03/12 14:49:44,344 Devicetime: 2015/03/12 14:49:45,920
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/12 14:49:45,424 Devicetime: 2015/03/12 14:49:47,001
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 34 and length 12 to peer MNETMODEM (channel 11)
[VDSL-Status] 2015/03/12 14:49:45,530 Devicetime: 2015/03/12 14:49:47,093
Retrain
[VDSL-Status] 2015/03/12 14:49:45,561 Devicetime: 2015/03/12 14:49:47,124
Auto/ISDN: Line state Down (0x0)
[VDSL-Status] 2015/03/12 14:49:45,561 Devicetime: 2015/03/12 14:49:47,124
Link is down
[PPP] 2015/03/12 14:49:45,561 Devicetime: 2015/03/12 14:49:47,125
Change phase to DEAD for O2-VDSL
Stopping LCP restart timer
Stopping IPCP restart timer
Stopping CCP restart timer
Stopping BACP restart timer
Stopping IPV6CP restart timer
[VDSL-Status] 2015/03/12 14:49:45,796 Devicetime: 2015/03/12 14:49:47,372
Auto/ISDN: Line state Idle (0xff)
[VDSL-Status] 2015/03/12 14:49:47,567 Devicetime: 2015/03/12 14:49:49,142
Auto/ISDN: Line state Idle (0x100)
[VDSL-Status] 2015/03/12 14:49:47,770 Devicetime: 2015/03/12 14:49:49,347
Auto/ISDN: Line state Idle (0x100)
[VDSL-Status] 2015/03/12 14:49:47,879 Devicetime: 2015/03/12 14:49:49,450
Auto/ISDN: Line state Handshake (0x200)
[VDSL-Status] 2015/03/12 14:49:51,876 Devicetime: 2015/03/12 14:49:53,449
Auto/ISDN: Line state Handshake (0x300)
[VDSL-Status] 2015/03/12 14:49:57,878 Devicetime: 2015/03/12 14:49:59,450
Auto/ISDN: Line state Training (0x380)
[PPP] 2015/03/12 14:50:15,445 Devicetime: 2015/03/12 14:50:17,019
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 35 and length 12 to peer MNETMODEM (channel 11)
[VDSL-Status] 2015/03/12 14:50:25,901 Devicetime: 2015/03/12 14:50:27,466
Datarate (Bit/s, Down/Up): 51392000/10048000
Interleave (ms, Down/Up): 8.00/8.25
INP (Symbols, Down/Up): 11.0/15.0
[VDSL-Status] 2015/03/12 14:50:25,901 Devicetime: 2015/03/12 14:50:27,466
SNR margin (dB, Down/Up): 6.4/-64.1
[VDSL-Status] 2015/03/12 14:50:25,901 Devicetime: 2015/03/12 14:50:27,467
Auto/ISDN: Line state Showtime (0x801)
[VDSL-Status] 2015/03/12 14:50:25,901 Devicetime: 2015/03/12 14:50:27,467
Link is up
[VDSL-Status] 2015/03/12 14:50:26,026 Devicetime: 2015/03/12 14:50:27,592
Standard VDSL (0x28), Profile 17a
[VDSL-Modem] 2015/03/12 14:50:26,026 Devicetime: 2015/03/12 14:50:27,592
Outcome: 00 00 00 00 00 00 00 02
[PPP] 2015/03/12 14:50:27,761 Devicetime: 2015/03/12 14:50:29,330
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 20 c0 60 07 fd d8 b3 23
[PPP] 2015/03/12 14:50:28,261 Devicetime: 2015/03/12 14:50:29,830
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 20 c0 60 07 fd d8 b3 23
[PPP] 2015/03/12 14:50:29,261 Devicetime: 2015/03/12 14:50:30,830
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 20 c0 60 07 fd d8 b3 23
[PPP] 2015/03/12 14:50:29,519 Devicetime: 2015/03/12 14:50:31,092
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:50:31,257 Devicetime: 2015/03/12 14:50:32,830
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 20 c0 60 07 fd d8 b3 23
[PPP] 2015/03/12 14:50:32,940 Devicetime: 2015/03/12 14:50:34,506
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:50:34,355 Devicetime: 2015/03/12 14:50:35,920
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/12 14:50:35,257 Devicetime: 2015/03/12 14:50:36,830
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 20 c0 60 07 fd d8 b3 23
[VDSL-Status] 2015/03/12 14:50:36,101 Devicetime: 2015/03/12 14:50:37,663
SNR margin (dB, Down/Up): 6.5/12.5
[PPP] 2015/03/12 14:50:39,528 Devicetime: 2015/03/12 14:50:41,093
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:50:42,654 Devicetime: 2015/03/12 14:50:44,223
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:50:45,467 Devicetime: 2015/03/12 14:50:47,036
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 36 and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/12 14:50:46,459 Devicetime: 2015/03/12 14:50:48,030
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: a4 12 ee 78 52 09 77 3c
[PPP] 2015/03/12 14:50:46,965 Devicetime: 2015/03/12 14:50:48,530
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: a4 12 ee 78 52 09 77 3c
[PPP] 2015/03/12 14:50:47,965 Devicetime: 2015/03/12 14:50:49,530
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: a4 12 ee 78 52 09 77 3c
[PPP] 2015/03/12 14:50:49,528 Devicetime: 2015/03/12 14:50:51,093
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:50:49,972 Devicetime: 2015/03/12 14:50:51,530
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: a4 12 ee 78 52 09 77 3c
[PPP] 2015/03/12 14:50:53,437 Devicetime: 2015/03/12 14:50:55,008
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:50:53,971 Devicetime: 2015/03/12 14:50:55,530
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: a4 12 ee 78 52 09 77 3c
[PPP] 2015/03/12 14:50:54,159 Devicetime: 2015/03/12 14:50:55,720
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:51:00,995 Devicetime: 2015/03/12 14:51:02,565
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:51:05,161 Devicetime: 2015/03/12 14:51:06,730
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: a2 9a a9 c1 bc f5 d7 c0
[PPP] 2015/03/12 14:51:05,662 Devicetime: 2015/03/12 14:51:07,230
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: a2 9a a9 c1 bc f5 d7 c0
[PPP] 2015/03/12 14:51:06,664 Devicetime: 2015/03/12 14:51:08,231
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: a2 9a a9 c1 bc f5 d7 c0
[PPP] 2015/03/12 14:51:08,664 Devicetime: 2015/03/12 14:51:10,230
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: a2 9a a9 c1 bc f5 d7 c0
[PPP] 2015/03/12 14:51:12,667 Devicetime: 2015/03/12 14:51:14,224
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:51:12,667 Devicetime: 2015/03/12 14:51:14,230
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: a2 9a a9 c1 bc f5 d7 c0
[PPP] 2015/03/12 14:51:15,492 Devicetime: 2015/03/12 14:51:17,053
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 37 and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/12 14:51:23,862 Devicetime: 2015/03/12 14:51:25,430
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 02 f3 d7 5f ec c1 68 8f
[PPP] 2015/03/12 14:51:24,362 Devicetime: 2015/03/12 14:51:25,920
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/12 14:51:24,362 Devicetime: 2015/03/12 14:51:25,930
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 02 f3 d7 5f ec c1 68 8f
[PPP] 2015/03/12 14:51:25,363 Devicetime: 2015/03/12 14:51:26,930
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 02 f3 d7 5f ec c1 68 8f
[PPP] 2015/03/12 14:51:27,363 Devicetime: 2015/03/12 14:51:28,930
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 02 f3 d7 5f ec c1 68 8f
[PPP] 2015/03/12 14:51:31,363 Devicetime: 2015/03/12 14:51:32,930
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 02 f3 d7 5f ec c1 68 8f
[PPP] 2015/03/12 14:51:42,565 Devicetime: 2015/03/12 14:51:44,130
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: bd 92 cf 69 b3 71 e4 94
[PPP] 2015/03/12 14:51:42,659 Devicetime: 2015/03/12 14:51:44,225
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:51:43,065 Devicetime: 2015/03/12 14:51:44,630
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: bd 92 cf 69 b3 71 e4 94
[PPP] 2015/03/12 14:51:44,064 Devicetime: 2015/03/12 14:51:45,630
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: bd 92 cf 69 b3 71 e4 94
[PPP] 2015/03/12 14:51:45,506 Devicetime: 2015/03/12 14:51:47,071
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 38 and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/12 14:51:46,065 Devicetime: 2015/03/12 14:51:47,630
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: bd 92 cf 69 b3 71 e4 94
[PPP] 2015/03/12 14:51:50,066 Devicetime: 2015/03/12 14:51:51,630
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: bd 92 cf 69 b3 71 e4 94
[PPP] 2015/03/12 14:52:01,266 Devicetime: 2015/03/12 14:52:02,830
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: fb d6 bf b2 7d eb 5f d9
[PPP] 2015/03/12 14:52:01,765 Devicetime: 2015/03/12 14:52:03,330
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: fb d6 bf b2 7d eb 5f d9
[PPP] 2015/03/12 14:52:02,767 Devicetime: 2015/03/12 14:52:04,330
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: fb d6 bf b2 7d eb 5f d9
[PPP] 2015/03/12 14:52:04,766 Devicetime: 2015/03/12 14:52:06,330
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: fb d6 bf b2 7d eb 5f d9
[PPP] 2015/03/12 14:52:08,767 Devicetime: 2015/03/12 14:52:10,330
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: fb d6 bf b2 7d eb 5f d9
[PPP] 2015/03/12 14:52:12,663 Devicetime: 2015/03/12 14:52:14,226
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:52:14,357 Devicetime: 2015/03/12 14:52:15,920
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/12 14:52:15,528 Devicetime: 2015/03/12 14:52:17,089
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 39 and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/12 14:52:15,657 Devicetime: 2015/03/12 14:52:17,218
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
[PPP] 2015/03/12 14:52:19,969 Devicetime: 2015/03/12 14:52:21,530
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 69 a6 96 66 34 d3 4b 33
[PPP] 2015/03/12 14:52:19,995 Devicetime: 2015/03/12 14:52:21,556
Received PADO frame from peer 88:43:e1:89:9c:51 for session 0000
Service: (any), accepted
Host-ID: 69 a6 96 66 34 d3 4b 33, accepted
AC-Name: MUNA77-ths, accepted
Cookie: 4e a8 a3 dd ee 85 7e c1 9a 51 35 43 16 68 29 77, accepted
[PPP] 2015/03/12 14:52:19,995 Devicetime: 2015/03/12 14:52:21,556
Transmit PADR frame to peer 88:43:e1:89:9c:51 for session 0000
Service: (any)
Host-ID: 69 a6 96 66 34 d3 4b 33
Cookie: 4e a8 a3 dd ee 85 7e c1 9a 51 35 43 16 68 29 77
[PPP] 2015/03/12 14:52:20,023 Devicetime: 2015/03/12 14:52:21,584
Received PADS frame from peer 88:43:e1:89:9c:51 for session 2312
Service: (any), accepted
Host-ID: 69 a6 96 66 34 d3 4b 33, accepted
Cookie: 4e a8 a3 dd ee 85 7e c1 9a 51 35 43 16 68 29 77, accepted
[PPP] 2015/03/12 14:52:20,023 Devicetime: 2015/03/12 14:52:21,584
Change phase to ESTABLISH for O2-VDSL
Lower-Layer-Up event for LCP
Initializing LCP restart timer to 3000 milliseconds
Waiting up to 200ms for connection
Starting LCP restart timer with 200 milliseconds
[PPP] 2015/03/12 14:52:20,219 Devicetime: 2015/03/12 14:52:21,780
Positive Restart-Timeout event for LCP
Stop waiting for connection
Initializing LCP restart timer to 3000 milliseconds
Generating LCP configure-request for peer O2-VDSL
Inserting local MRU 1492
Inserting local magic number f7d126b9
Sending LCP configure-request with ID 00 and length 14 to peer O2-VDSL (channel 1)
Starting LCP restart timer with 3000 milliseconds
[PPP] 2015/03/12 14:52:20,235 Devicetime: 2015/03/12 14:52:21,796
Received LCP frame from peer O2-VDSL (channel 1)
Evaluate configure-request with ID 01 and size 18
Peer MRU 1492 accepted
Peer requests authentication protocol PAP, accepted
Peer magic number 8e9101c0 accepted
Positive Configure-Request-Received event for LCP
Sending LCP configure-ack with ID 01 and length 18 to peer O2-VDSL (channel 1)
[PPP] 2015/03/12 14:52:20,235 Devicetime: 2015/03/12 14:52:21,796
Received LCP frame from peer O2-VDSL (channel 1)
Evaluate configure-ack with ID 00 and size 14
Configure-Ack-Received event for LCP
Initializing LCP restart timer to 3000 milliseconds
This-Layer-Up action for LCP
Change phase to AUTHENTICATE for O2-VDSL
Generating PAP-request for peer O2-VDSL
Resolved peer as O2-VDSL in PPP table
Sending PAP-request with ID 04, size 39 and peer-id 08954780450@s93.bbi-o2.de to peer O2-VDSL (channel 1)
Stopping LCP restart timer
[PPP] 2015/03/12 14:52:20,325 Devicetime: 2015/03/12 14:52:21,885
Received PAP frame from peer O2-VDSL (channel 1)
Evaluate PAP ack with ID 04 and size 48
Embedded message: Pedo mellon a minno : <unknown> - <unknown>
This-Layer-Up action for LCP
Change phase to CALLBACK for O2-VDSL
This-Layer-Up action for LCP
Change phase to NETWORK for O2-VDSL
Lower-Layer-Up event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer O2-VDSL
Inserting IP address 0.0.0.0
Inserting primary DNS address 0.0.0.0
Inserting secondary DNS address 0.0.0.0
Sending IPCP configure-request with ID 00 and length 22 to peer O2-VDSL (channel 1)
Starting IPCP restart timer with 3000 milliseconds
[PPP] 2015/03/12 14:52:20,325 Devicetime: 2015/03/12 14:52:21,886
Received IPCP frame from peer O2-VDSL (channel 1)
Evaluate configure-request with ID 01 and size 10
Peer requests IP address 62.52.200.112, accepted
Positive Configure-Request-Received event for IPCP
Sending IPCP configure-ack with ID 01 and length 10 to peer O2-VDSL (channel 1)
[PPP] 2015/03/12 14:52:20,343 Devicetime: 2015/03/12 14:52:21,903
Received IPCP frame from peer O2-VDSL (channel 1)
Evaluate configure-nak with ID 00 and size 22
Peer NAKs IP address 89.15.48.91, accepted
Peer NAKs primary DNS address 62.109.121.2, accepted
Peer NAKs secondary DNS address 62.109.121.1, accepted
Configure-Nak/Rej-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer O2-VDSL
Inserting IP address 89.15.48.91
Inserting primary DNS address 62.109.121.2
Inserting secondary DNS address 62.109.121.1
Sending IPCP configure-request with ID 02 and length 22 to peer O2-VDSL (channel 1)
Starting IPCP restart timer with 3000 milliseconds
[PPP] 2015/03/12 14:52:20,359 Devicetime: 2015/03/12 14:52:21,921
Received IPCP frame from peer O2-VDSL (channel 1)
Evaluate configure-ack with ID 02 and size 22
Configure-Ack-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
This-Layer-Up action for IPCP
Stopping IPCP restart timer
[VDSL-Status] 2015/03/12 14:52:22,725 Devicetime: 2015/03/12 14:52:24,285
Booting modem: Modem restart request
[VDSL-Status] 2015/03/12 14:52:22,725 Devicetime: 2015/03/12 14:52:24,285
Link is down
[PPP] 2015/03/12 14:52:22,725 Devicetime: 2015/03/12 14:52:24,286
Change phase to DEAD for O2-VDSL
Stopping LCP restart timer
Stopping IPCP restart timer
Stopping CCP restart timer
Stopping BACP restart timer
Stopping IPV6CP restart timer
[VDSL-Status] 2015/03/12 14:52:22,827 Devicetime: 2015/03/12 14:52:24,388
Link is down
[VDSL-Status] 2015/03/12 14:52:37,015 Devicetime: 2015/03/12 14:52:38,575
Link is down
[PPP] 2015/03/12 14:52:45,548 Devicetime: 2015/03/12 14:52:47,107
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 3a and length 12 to peer MNETMODEM (channel 11)
[VDSL-Status] 2015/03/12 14:52:48,373 Devicetime: 2015/03/12 14:52:49,932
Link is down
[VDSL-Status] 2015/03/12 14:52:50,264 Devicetime: 2015/03/12 14:52:51,822
Link is down
[VDSL-Status] 2015/03/12 14:52:50,353 Devicetime: 2015/03/12 14:52:51,910
Link is down
[VDSL-Status] 2015/03/12 14:52:52,963 Devicetime: 2015/03/12 14:52:54,522
Link is down
[VDSL-Modem] 2015/03/12 14:52:58,726 Devicetime: 2015/03/12 14:53:00,282
setting up PTM PVC 0
[VDSL-Modem] 2015/03/12 14:53:01,432 Devicetime: 2015/03/12 14:53:02,988
starting supervisor
[VDSL-Modem] 2015/03/12 14:53:01,456 Devicetime: 2015/03/12 14:53:02,999
Management handler has been started.
[VDSL-Modem] 2015/03/12 14:53:01,456 Devicetime: 2015/03/12 14:53:03,004
Management reader has been started.
[VDSL-Modem] 2015/03/12 14:53:01,459 Devicetime: 2015/03/12 14:53:03,008
Status poll has been started.
[VDSL-Modem] 2015/03/12 14:53:01,459 Devicetime: 2015/03/12 14:53:03,011
DSL event handler has been started.
[VDSL-Modem] 2015/03/12 14:53:01,543 Devicetime: 2015/03/12 14:53:03,097
API is running.
[VDSL-Modem] 2015/03/12 14:53:01,543 Devicetime: 2015/03/12 14:53:03,099
Autoboot is running.
[VDSL-Status] 2015/03/12 14:53:02,316 Devicetime: 2015/03/12 14:53:03,874
New Protocol: Auto
[VDSL-Modem] 2015/03/12 14:53:02,371 Devicetime: 2015/03/12 14:53:03,924
setting up ATM PVC 0 to VPI 0, VCI 0, VC-Mux, bridged
[PPP] 2015/03/12 14:53:04,363 Devicetime: 2015/03/12 14:53:05,920
LCP polling timeout for peer MNETMODEM - data received during last interval
[VDSL-Modem] 2015/03/12 14:53:08,727 Devicetime: 2015/03/12 14:53:10,275
Startup parameters 10 00 10 40 00 04 01 07
[VDSL-Status] 2015/03/12 14:53:08,727 Devicetime: 2015/03/12 14:53:10,284
Auto/ISDN: Line state Down (0x0)
[VDSL-Status] 2015/03/12 14:53:08,745 Devicetime: 2015/03/12 14:53:10,303
Auto/ISDN: Line state Idle (0xff)
[VDSL-Status] 2015/03/12 14:53:10,519 Devicetime: 2015/03/12 14:53:12,077
Auto/ISDN: Line state Idle (0x100)
[VDSL-Status] 2015/03/12 14:53:10,729 Devicetime: 2015/03/12 14:53:12,282
Auto/ISDN: Line state Idle (0x100)
[VDSL-Status] 2015/03/12 14:53:10,819 Devicetime: 2015/03/12 14:53:12,376
Auto/ISDN: Line state Handshake (0x200)
[PPP] 2015/03/12 14:53:15,569 Devicetime: 2015/03/12 14:53:17,124
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 3b and length 12 to peer MNETMODEM (channel 11)
[VDSL-Status] 2015/03/12 14:53:16,820 Devicetime: 2015/03/12 14:53:18,376
Auto/ISDN: Line state Handshake (0x300)
[VDSL-Status] 2015/03/12 14:53:23,820 Devicetime: 2015/03/12 14:53:25,376
Auto/ISDN: Line state Training (0x380)
[PPP] 2015/03/12 14:53:45,588 Devicetime: 2015/03/12 14:53:47,141
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 3c and length 12 to peer MNETMODEM (channel 11)
[VDSL-Status] 2015/03/12 14:53:51,839 Devicetime: 2015/03/12 14:53:53,385
Datarate (Bit/s, Down/Up): 51392000/10048000
Interleave (ms, Down/Up): 8.00/8.25
INP (Symbols, Down/Up): 11.0/15.0
[VDSL-Status] 2015/03/12 14:53:51,839 Devicetime: 2015/03/12 14:53:53,392
SNR margin (dB, Down/Up): 6.4/-64.1
[VDSL-Status] 2015/03/12 14:53:51,839 Devicetime: 2015/03/12 14:53:53,392
Auto/ISDN: Line state Showtime (0x801)
[VDSL-Status] 2015/03/12 14:53:51,839 Devicetime: 2015/03/12 14:53:53,392
Link is up
[VDSL-Status] 2015/03/12 14:53:52,579 Devicetime: 2015/03/12 14:53:54,129
Standard VDSL (0x28), Profile 17a
[VDSL-Modem] 2015/03/12 14:53:52,579 Devicetime: 2015/03/12 14:53:54,130
Outcome: 00 00 00 00 00 00 00 02
[PPP] 2015/03/12 14:53:52,936 Devicetime: 2015/03/12 14:53:54,490
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: e7 93 cd a9 9e 71 65 f4
[PPP] 2015/03/12 14:53:53,436 Devicetime: 2015/03/12 14:53:54,990
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: e7 93 cd a9 9e 71 65 f4
[PPP] 2015/03/12 14:53:54,367 Devicetime: 2015/03/12 14:53:55,920
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/12 14:53:54,438 Devicetime: 2015/03/12 14:53:55,990
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: e7 93 cd a9 9e 71 65 f4
[PPP] 2015/03/12 14:53:56,438 Devicetime: 2015/03/12 14:53:57,990
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: e7 93 cd a9 9e 71 65 f4
[PPP] 2015/03/12 14:54:00,530 Devicetime: 2015/03/12 14:54:01,990
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: e7 93 cd a9 9e 71 65 f4
[VDSL-Status] 2015/03/12 14:54:01,623 Devicetime: 2015/03/12 14:54:03,165
SNR margin (dB, Down/Up): 6.6/12.7
[PPP] 2015/03/12 14:54:11,663 Devicetime: 2015/03/12 14:54:13,190
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: fe 76 af 9e 7f 3b 57 cf
[PPP] 2015/03/12 14:54:12,147 Devicetime: 2015/03/12 14:54:13,690
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: fe 76 af 9e 7f 3b 57 cf
[PPP] 2015/03/12 14:54:13,148 Devicetime: 2015/03/12 14:54:14,690
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: fe 76 af 9e 7f 3b 57 cf
[PPP] 2015/03/12 14:54:15,150 Devicetime: 2015/03/12 14:54:16,690
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: fe 76 af 9e 7f 3b 57 cf
[PPP] 2015/03/12 14:54:15,619 Devicetime: 2015/03/12 14:54:17,159
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 3d and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/12 14:54:19,143 Devicetime: 2015/03/12 14:54:20,690
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: fe 76 af 9e 7f 3b 57 cf
[VDSL-Status] 2015/03/12 14:54:21,112 Devicetime: 2015/03/12 14:54:22,651
Booting modem: Modem restart request
[VDSL-Status] 2015/03/12 14:54:21,112 Devicetime: 2015/03/12 14:54:22,651
Link is down
[VDSL-Status] 2015/03/12 14:54:21,205 Devicetime: 2015/03/12 14:54:22,754
Link is down
[VDSL-Status] 2015/03/12 14:54:35,388 Devicetime: 2015/03/12 14:54:36,934
Link is down
[PPP] 2015/03/12 14:54:44,381 Devicetime: 2015/03/12 14:54:45,920
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/12 14:54:45,647 Devicetime: 2015/03/12 14:54:47,178
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 3e and length 12 to peer MNETMODEM (channel 11)
[VDSL-Status] 2015/03/12 14:54:46,755 Devicetime: 2015/03/12 14:54:48,297
Link is down
[VDSL-Status] 2015/03/12 14:54:51,462 Devicetime: 2015/03/12 14:54:52,996
Link is down
[VDSL-Modem] 2015/03/12 14:54:57,109 Devicetime: 2015/03/12 14:54:58,644
setting up PTM PVC 0
[VDSL-Modem] 2015/03/12 14:54:59,807 Devicetime: 2015/03/12 14:55:01,342
starting supervisor
[VDSL-Modem] 2015/03/12 14:54:59,839 Devicetime: 2015/03/12 14:55:01,353
Management handler has been started.
[VDSL-Modem] 2015/03/12 14:54:59,839 Devicetime: 2015/03/12 14:55:01,358
Management reader has been started.
[VDSL-Modem] 2015/03/12 14:54:59,839 Devicetime: 2015/03/12 14:55:01,362
Status poll has been started.
[VDSL-Modem] 2015/03/12 14:54:59,839 Devicetime: 2015/03/12 14:55:01,366
DSL event handler has been started.
[VDSL-Modem] 2015/03/12 14:54:59,932 Devicetime: 2015/03/12 14:55:01,451
API is running.
[VDSL-Modem] 2015/03/12 14:54:59,932 Devicetime: 2015/03/12 14:55:01,453
Autoboot is running.
[VDSL-Status] 2015/03/12 14:55:00,711 Devicetime: 2015/03/12 14:55:02,236
New Protocol: Auto
[VDSL-Modem] 2015/03/12 14:55:00,758 Devicetime: 2015/03/12 14:55:02,289
setting up ATM PVC 0 to VPI 0, VCI 0, VC-Mux, bridged
[VDSL-Modem] 2015/03/12 14:55:07,114 Devicetime: 2015/03/12 14:55:08,638
Startup parameters 10 00 10 40 00 04 01 07
[VDSL-Status] 2015/03/12 14:55:07,114 Devicetime: 2015/03/12 14:55:08,649
Auto/ISDN: Line state Down (0x0)
[VDSL-Status] 2015/03/12 14:55:07,130 Devicetime: 2015/03/12 14:55:08,664
Auto/ISDN: Line state Idle (0xff)
[VDSL-Status] 2015/03/12 14:55:08,908 Devicetime: 2015/03/12 14:55:10,442
Auto/ISDN: Line state Idle (0x100)
[VDSL-Status] 2015/03/12 14:55:09,114 Devicetime: 2015/03/12 14:55:10,647
Auto/ISDN: Line state Idle (0x100)
[VDSL-Status] 2015/03/12 14:55:09,208 Devicetime: 2015/03/12 14:55:10,741
Auto/ISDN: Line state Handshake (0x200)
[VDSL-Status] 2015/03/12 14:55:15,210 Devicetime: 2015/03/12 14:55:16,740
Auto/ISDN: Line state Handshake (0x300)
[PPP] 2015/03/12 14:55:15,664 Devicetime: 2015/03/12 14:55:17,194
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 3f and length 12 to peer MNETMODEM (channel 11)
[VDSL-Status] 2015/03/12 14:55:22,214 Devicetime: 2015/03/12 14:55:23,741
Auto/ISDN: Line state Training (0x380)
[PPP] 2015/03/12 14:55:34,400 Devicetime: 2015/03/12 14:55:35,920
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/12 14:55:45,698 Devicetime: 2015/03/12 14:55:47,213
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 40 and length 12 to peer MNETMODEM (channel 11)
[VDSL-Status] 2015/03/12 14:55:50,238 Devicetime: 2015/03/12 14:55:51,749
Datarate (Bit/s, Down/Up): 51392000/10048000
Interleave (ms, Down/Up): 8.00/8.25
INP (Symbols, Down/Up): 11.0/15.0
[VDSL-Status] 2015/03/12 14:55:50,254 Devicetime: 2015/03/12 14:55:51,766
SNR margin (dB, Down/Up): 6.5/-64.1
[VDSL-Status] 2015/03/12 14:55:50,254 Devicetime: 2015/03/12 14:55:51,766
Auto/ISDN: Line state Showtime (0x801)
[VDSL-Status] 2015/03/12 14:55:50,254 Devicetime: 2015/03/12 14:55:51,766
Link is up
[VDSL-Status] 2015/03/12 14:55:50,985 Devicetime: 2015/03/12 14:55:52,494
Standard VDSL (0x28), Profile 17a
[VDSL-Modem] 2015/03/12 14:55:50,985 Devicetime: 2015/03/12 14:55:52,494
Outcome: 00 00 00 00 00 00 00 02
[PPP] 2015/03/12 14:55:51,347 Devicetime: 2015/03/12 14:55:52,860
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: e8 66 29 0f 99 8b 97 a7
[PPP] 2015/03/12 14:55:51,848 Devicetime: 2015/03/12 14:55:53,360
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: e8 66 29 0f 99 8b 97 a7
[PPP] 2015/03/12 14:55:51,876 Devicetime: 2015/03/12 14:55:53,386
Received PADO frame from peer 88:43:e1:89:9c:51 for session 0000
Service: (any), accepted
Host-ID: e8 66 29 0f 99 8b 97 a7, accepted
AC-Name: MUNA77-ths, accepted
Cookie: 4e a8 a3 dd ee 85 7e c1 9a 51 35 43 16 68 29 77, accepted
[PPP] 2015/03/12 14:55:51,876 Devicetime: 2015/03/12 14:55:53,386
Transmit PADR frame to peer 88:43:e1:89:9c:51 for session 0000
Service: (any)
Host-ID: e8 66 29 0f 99 8b 97 a7
Cookie: 4e a8 a3 dd ee 85 7e c1 9a 51 35 43 16 68 29 77
[PPP] 2015/03/12 14:55:51,904 Devicetime: 2015/03/12 14:55:53,414
Received PADS frame from peer 88:43:e1:89:9c:51 for session 2340
Service: (any), accepted
Host-ID: e8 66 29 0f 99 8b 97 a7, accepted
Cookie: 4e a8 a3 dd ee 85 7e c1 9a 51 35 43 16 68 29 77, accepted
[PPP] 2015/03/12 14:55:51,904 Devicetime: 2015/03/12 14:55:53,414
Change phase to ESTABLISH for O2-VDSL
Lower-Layer-Up event for LCP
Initializing LCP restart timer to 3000 milliseconds
Waiting up to 200ms for connection
Starting LCP restart timer with 200 milliseconds
[PPP] 2015/03/12 14:55:52,099 Devicetime: 2015/03/12 14:55:53,610
Positive Restart-Timeout event for LCP
Stop waiting for connection
Initializing LCP restart timer to 3000 milliseconds
Generating LCP configure-request for peer O2-VDSL
Inserting local MRU 1492
Inserting local magic number a17d48f3
Sending LCP configure-request with ID 00 and length 14 to peer O2-VDSL (channel 1)
Starting LCP restart timer with 3000 milliseconds
[PPP] 2015/03/12 14:55:52,117 Devicetime: 2015/03/12 14:55:53,626
Received LCP frame from peer O2-VDSL (channel 1)
Evaluate configure-request with ID 01 and size 18
Peer MRU 1492 accepted
Peer requests authentication protocol PAP, accepted
Peer magic number 8e943e34 accepted
Positive Configure-Request-Received event for LCP
Sending LCP configure-ack with ID 01 and length 18 to peer O2-VDSL (channel 1)
[PPP] 2015/03/12 14:55:52,117 Devicetime: 2015/03/12 14:55:53,626
Received LCP frame from peer O2-VDSL (channel 1)
Evaluate configure-ack with ID 00 and size 14
Configure-Ack-Received event for LCP
Initializing LCP restart timer to 3000 milliseconds
This-Layer-Up action for LCP
Change phase to AUTHENTICATE for O2-VDSL
Generating PAP-request for peer O2-VDSL
Resolved peer as O2-VDSL in PPP table
Sending PAP-request with ID 05, size 39 and peer-id 08954780450@s93.bbi-o2.de to peer O2-VDSL (channel 1)
Stopping LCP restart timer
[PPP] 2015/03/12 14:55:52,252 Devicetime: 2015/03/12 14:55:53,761
Received PAP frame from peer O2-VDSL (channel 1)
Evaluate PAP ack with ID 05 and size 48
Embedded message: Pedo mellon a minno : <unknown> - <unknown>
This-Layer-Up action for LCP
Change phase to CALLBACK for O2-VDSL
This-Layer-Up action for LCP
Change phase to NETWORK for O2-VDSL
Lower-Layer-Up event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer O2-VDSL
Inserting IP address 0.0.0.0
Inserting primary DNS address 0.0.0.0
Inserting secondary DNS address 0.0.0.0
Sending IPCP configure-request with ID 00 and length 22 to peer O2-VDSL (channel 1)
Starting IPCP restart timer with 3000 milliseconds
[PPP] 2015/03/12 14:55:52,252 Devicetime: 2015/03/12 14:55:53,762
Received IPCP frame from peer O2-VDSL (channel 1)
Evaluate configure-request with ID 01 and size 10
Peer requests IP address 62.52.200.112, accepted
Positive Configure-Request-Received event for IPCP
Sending IPCP configure-ack with ID 01 and length 10 to peer O2-VDSL (channel 1)
[PPP] 2015/03/12 14:55:52,268 Devicetime: 2015/03/12 14:55:53,779
Received IPCP frame from peer O2-VDSL (channel 1)
Evaluate configure-nak with ID 00 and size 22
Peer NAKs IP address 89.15.62.141, accepted
Peer NAKs primary DNS address 62.109.121.2, accepted
Peer NAKs secondary DNS address 62.109.121.1, accepted
Configure-Nak/Rej-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
Generating IPCP configure-request for peer O2-VDSL
Inserting IP address 89.15.62.141
Inserting primary DNS address 62.109.121.2
Inserting secondary DNS address 62.109.121.1
Sending IPCP configure-request with ID 02 and length 22 to peer O2-VDSL (channel 1)
Starting IPCP restart timer with 3000 milliseconds
[PPP] 2015/03/12 14:55:52,307 Devicetime: 2015/03/12 14:55:53,818
Received IPCP frame from peer O2-VDSL (channel 1)
Evaluate configure-ack with ID 02 and size 22
Configure-Ack-Received event for IPCP
Initializing IPCP restart timer to 3000 milliseconds
This-Layer-Up action for IPCP
Stopping IPCP restart timer
[VDSL-Status] 2015/03/12 14:56:00,023 Devicetime: 2015/03/12 14:56:01,530
SNR margin (dB, Down/Up): 6.6/12.7
[PPP] 2015/03/12 14:56:15,730 Devicetime: 2015/03/12 14:56:17,230
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 41 and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/12 14:56:24,425 Devicetime: 2015/03/12 14:56:25,920
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/12 14:56:42,323 Devicetime: 2015/03/12 14:56:43,810
LCP polling timeout for peer O2-VDSL - echo-response received during last interval
[PPP] 2015/03/12 14:56:45,763 Devicetime: 2015/03/12 14:56:47,247
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 42 and length 12 to peer MNETMODEM (channel 11)
[TraceStopped] 2015/03/12 14:56:54,899
Used config:
# Trace config
trace + PPP
trace + VDSL-Status
trace + VDSL-ModemRe: O2 VDSL Fehler tracen
Moin, moin!
Ich fasse das mal kurz zusammen:
Der Retrain erfolgt um um 14:49:45.
Anschließend versucht das LANCOM wieder eine PPP-Verbindung herzustellen, bekommt aber auf das PADI keine Antwort. Stattdessen empfängt es Datenframes zu einer (der?) alten PPP-Verbindung und versucht, die abzubauen:
[PPP] 2015/03/12 14:50:29,519 Devicetime: 2015/03/12 14:50:31,092
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
Der Abbau wird aber anscheinend vom Provider ignoriert, so daß sich das Spielchen eine ganze Weile fortsetzt. Um 15:52:19 schließlich scheint der Provider die alte Session beendet zu haben, denn das PADI wird beantwortet:
[PPP] 2015/03/12 14:52:19,969 Devicetime: 2015/03/12 14:52:21,530
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 69 a6 96 66 34 d3 4b 33
[PPP] 2015/03/12 14:52:19,995 Devicetime: 2015/03/12 14:52:21,556
Received PADO frame from peer 88:43:e1:89:9c:51 for session 0000
Service: (any), accepted
Host-ID: 69 a6 96 66 34 d3 4b 33, accepted
AC-Name: MUNA77-ths, accepted
Cookie: 4e a8 a3 dd ee 85 7e c1 9a 51 35 43 16 68 29 77, accepted
Allerdings wird das Modem neu gestartet, bevor die PPP-Verhandlung abgeschlossen werden kann.
Es sieht so aus, als ob auch durch das Retrain eine neue PPP-Verbindung aufgebaut wird, es dauert nur recht lange, bis der Provider die nicht mehr funktionierende PPP-Verbindung verwirft und eine neue Verbindung annimmt. Es ist demnach nicht notwendig, das Modem zu booten.
Jetzt wäre noch zu klären, ob ein Retrain die einzige Möglichkeit ist, eine neue PPP-Verbindung aufgzbauen, oder ob es nicht auch reicht, die alte PPP-Verbindung abzubauen. Hierzu könntest du beim nächsten Hänger einfach mal ein "do /o/m/d" machen (wieder mit PPP-Trace).
Ich nehme an, du hast im PPP das LCP-Polling aktiviert - sieht zumindest im Fall der Verbundung zu MNETMODEM so aus. Du könntest zusätzlich noch ein IP-Polling einrichten, das die Verbindung abbaut, wenn zwar das LCP-Polling (also die PPP-Verbindung an sich) noch funktioniert, aber keine Daten mehr übertragen werden können.
Ciao, Georg
Ich fasse das mal kurz zusammen:
Der Retrain erfolgt um um 14:49:45.
Anschließend versucht das LANCOM wieder eine PPP-Verbindung herzustellen, bekommt aber auf das PADI keine Antwort. Stattdessen empfängt es Datenframes zu einer (der?) alten PPP-Verbindung und versucht, die abzubauen:
[PPP] 2015/03/12 14:50:29,519 Devicetime: 2015/03/12 14:50:31,092
Received data packet for unknown session 0c88 from peer 88:43:e1:89:9c:51 => closing connection on AC
Transmit PADT frame to peer 88:43:e1:89:9c:51 for session 0c88
Der Abbau wird aber anscheinend vom Provider ignoriert, so daß sich das Spielchen eine ganze Weile fortsetzt. Um 15:52:19 schließlich scheint der Provider die alte Session beendet zu haben, denn das PADI wird beantwortet:
[PPP] 2015/03/12 14:52:19,969 Devicetime: 2015/03/12 14:52:21,530
Transmit PADI frame to peer ff:ff:ff:ff:ff:ff for session 0000
Service: (any)
Host-ID: 69 a6 96 66 34 d3 4b 33
[PPP] 2015/03/12 14:52:19,995 Devicetime: 2015/03/12 14:52:21,556
Received PADO frame from peer 88:43:e1:89:9c:51 for session 0000
Service: (any), accepted
Host-ID: 69 a6 96 66 34 d3 4b 33, accepted
AC-Name: MUNA77-ths, accepted
Cookie: 4e a8 a3 dd ee 85 7e c1 9a 51 35 43 16 68 29 77, accepted
Allerdings wird das Modem neu gestartet, bevor die PPP-Verhandlung abgeschlossen werden kann.
Es sieht so aus, als ob auch durch das Retrain eine neue PPP-Verbindung aufgebaut wird, es dauert nur recht lange, bis der Provider die nicht mehr funktionierende PPP-Verbindung verwirft und eine neue Verbindung annimmt. Es ist demnach nicht notwendig, das Modem zu booten.
Jetzt wäre noch zu klären, ob ein Retrain die einzige Möglichkeit ist, eine neue PPP-Verbindung aufgzbauen, oder ob es nicht auch reicht, die alte PPP-Verbindung abzubauen. Hierzu könntest du beim nächsten Hänger einfach mal ein "do /o/m/d" machen (wieder mit PPP-Trace).
Ich nehme an, du hast im PPP das LCP-Polling aktiviert - sieht zumindest im Fall der Verbundung zu MNETMODEM so aus. Du könntest zusätzlich noch ein IP-Polling einrichten, das die Verbindung abbaut, wenn zwar das LCP-Polling (also die PPP-Verbindung an sich) noch funktioniert, aber keine Daten mehr übertragen werden können.
Ciao, Georg
Re: O2 VDSL Fehler tracen
Nabend 
Danke für die ganzen Tipps und die Mühe. Ich habe eben Gelegenheit gehabt, zu versuchen die PPP-Verbindung abzubauen, aber leider konnektet sie sofort wieder blitzschnell, ist aber immer noch tot (also 0 kBit/s kommen rein).
Ich habe dann brav ein wenig gewartet, aber als sich daran nichts geändert hat, habe ich nochmal eine Retrain veranlasst, diesen aber komplett abgewartet - und Du hattest natürlich recht, dass der Vorgang zwar eeewig dauert, aber letztendlich irgendwann erfolgreich ist. (Trace diesmal im Anhang)
Dass heisst aber nun auch, dass der propagierte Workaround über ein IP-Polling nicht klappt und ich bei "hängender" Leitung mit einem Neustart schneller zu Ziele komme, selbst ein Neustart des ganezn Lancom ist imho schneller als ein Neustart nur des Modems (gefühlt, kann mich auch irren).
Selbst wenn ich über Aktionstabelle und IP-Prüfung festelle, das die Leitung hängt und dann komplett neustarte (wenn das überhaupt geht), ist das zwar evtl. eine temporäre Hilfe, aber ich würde natürlich gerne die Ursache angehen. Hast Du dazu evtl. einen Tipp für mich? Ausser Providerwechsel natürlich
...den würde ich ja gerne machen, aber Mangels adäquater Alternativen fällt das leider flach....
Nochmals Danke für deine Hilfe und dass Du Dir extra die Zeit nimmst.
Gruß
Michael
Danke für die ganzen Tipps und die Mühe. Ich habe eben Gelegenheit gehabt, zu versuchen die PPP-Verbindung abzubauen, aber leider konnektet sie sofort wieder blitzschnell, ist aber immer noch tot (also 0 kBit/s kommen rein).
Ich habe dann brav ein wenig gewartet, aber als sich daran nichts geändert hat, habe ich nochmal eine Retrain veranlasst, diesen aber komplett abgewartet - und Du hattest natürlich recht, dass der Vorgang zwar eeewig dauert, aber letztendlich irgendwann erfolgreich ist. (Trace diesmal im Anhang)
Dass heisst aber nun auch, dass der propagierte Workaround über ein IP-Polling nicht klappt und ich bei "hängender" Leitung mit einem Neustart schneller zu Ziele komme, selbst ein Neustart des ganezn Lancom ist imho schneller als ein Neustart nur des Modems (gefühlt, kann mich auch irren).
Selbst wenn ich über Aktionstabelle und IP-Prüfung festelle, das die Leitung hängt und dann komplett neustarte (wenn das überhaupt geht), ist das zwar evtl. eine temporäre Hilfe, aber ich würde natürlich gerne die Ursache angehen. Hast Du dazu evtl. einen Tipp für mich? Ausser Providerwechsel natürlich
...den würde ich ja gerne machen, aber Mangels adäquater Alternativen fällt das leider flach....Nochmals Danke für deine Hilfe und dass Du Dir extra die Zeit nimmst.
Gruß
Michael
Du hast keine ausreichende Berechtigung, um die Dateianhänge dieses Beitrags anzusehen.
Re: O2 VDSL Fehler tracen
Moin, moin!
Du könntest jetzt noch bei hängender Verbindung ein Ping über die O2-Leitung schicken. Wenn du im VDSL-Data-Trace siehst, aber keine Antworten kpmmen, dann würde ich das Problem auf der Providerseite suchen. Für das Modem ist es egal, ob es sich um PPP-Datenpakete oder z.B. LCP-Pakete handelt.
Es gibt da noch irgendwo den "alive-test" im Lancom - ich vergesse immer wo. Damit kannst du die Erreichbarkeit einer bestimmten Gegenstelle überwachen und im Fehlerfall eine Aktion ausführen - z.B. einen retrain ausführen.
Ciao, Georg
Du könntest jetzt noch bei hängender Verbindung ein Ping über die O2-Leitung schicken. Wenn du im VDSL-Data-Trace siehst, aber keine Antworten kpmmen, dann würde ich das Problem auf der Providerseite suchen. Für das Modem ist es egal, ob es sich um PPP-Datenpakete oder z.B. LCP-Pakete handelt.
Es gibt da noch irgendwo den "alive-test" im Lancom - ich vergesse immer wo. Damit kannst du die Erreichbarkeit einer bestimmten Gegenstelle überwachen und im Fehlerfall eine Aktion ausführen - z.B. einen retrain ausführen.
Ciao, Georg
Re: O2 VDSL Fehler tracen
Hierzu hätte ich ein paar Verständnisfragen. LCP-Polling ist wohl defaultmäßig aktiviert, ICMP-Polling habe ich eingerichtet.
Wenn ich PPP trace, sehe ich:
Wie lese ich das? "LCP polling timeout for peer O2-VDSL - data received during last interval" und "LCP polling timeout for peer MNETMODEM - data received during last interval", heisst das jeweils es gab einen Timeout oder so wird die timeout-prüfung initialisert?
Und ich sehe nur bei einer Leitung "Received LCP frame from peer MNETMODEM (channel 11) Sending LCP echo-response with ID 0e and length 12 to peer MNETMODEM (channel 11)"
Heisst das, die andere antwortet hier nie?
Davon ausgehend, dass das alles so seine Richtigkeit hat, was passiert denn, wenn eines der Pollings ins Leer läuft? Reagiert das Lancom in jedem Fall automatisch (wie?) oder kann/muss ich irgendwo eine entsprechende Aktion anlegen (wo?)
Unabhängig von all dem werde ich mich nun mal aufmachen den Lancom-Support einzuschalten, denn das ursächliche Problem liegt evtl. wirklich beim DSLAM, aber ich kann nicht mit O2 reden bzw. die reden gar nicht erst mit mir, denn mit deren Taiwan-Billig-Modem geht es ja ganz prima.
Wenn ich PPP trace, sehe ich:
Code: Alles auswählen
[PPP] 2015/03/17 11:16:06,969 Devicetime: 2015/03/17 11:16:07,270
LCP polling timeout for peer O2-VDSL - data received during last interval
[PPP] 2015/03/17 11:16:09,895 Devicetime: 2015/03/17 11:16:10,195
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 08 and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/17 11:16:19,834 Devicetime: 2015/03/17 11:16:20,130
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/17 11:16:39,928 Devicetime: 2015/03/17 11:16:40,215
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 09 and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/17 11:16:56,990 Devicetime: 2015/03/17 11:16:57,270
LCP polling timeout for peer O2-VDSL - data received during last interval
[PPP] 2015/03/17 11:17:09,854 Devicetime: 2015/03/17 11:17:10,130
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/17 11:17:09,952 Devicetime: 2015/03/17 11:17:10,229
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 0a and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/17 11:17:39,984 Devicetime: 2015/03/17 11:17:40,248
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 0b and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/17 11:17:47,008 Devicetime: 2015/03/17 11:17:47,270
LCP polling timeout for peer O2-VDSL - data received during last interval
[PPP] 2015/03/17 11:17:59,873 Devicetime: 2015/03/17 11:18:00,130
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/17 11:18:10,012 Devicetime: 2015/03/17 11:18:10,264
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 0c and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/17 11:18:37,029 Devicetime: 2015/03/17 11:18:37,270
LCP polling timeout for peer O2-VDSL - data received during last interval
[PPP] 2015/03/17 11:18:40,043 Devicetime: 2015/03/17 11:18:40,282
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 0d and length 12 to peer MNETMODEM (channel 11)
[PPP] 2015/03/17 11:18:49,894 Devicetime: 2015/03/17 11:18:50,130
LCP polling timeout for peer MNETMODEM - data received during last interval
[PPP] 2015/03/17 11:19:10,072 Devicetime: 2015/03/17 11:19:10,300
Received LCP frame from peer MNETMODEM (channel 11)
Sending LCP echo-response with ID 0e and length 12 to peer MNETMODEM (channel 11)Und ich sehe nur bei einer Leitung "Received LCP frame from peer MNETMODEM (channel 11) Sending LCP echo-response with ID 0e and length 12 to peer MNETMODEM (channel 11)"
Heisst das, die andere antwortet hier nie?
Davon ausgehend, dass das alles so seine Richtigkeit hat, was passiert denn, wenn eines der Pollings ins Leer läuft? Reagiert das Lancom in jedem Fall automatisch (wie?) oder kann/muss ich irgendwo eine entsprechende Aktion anlegen (wo?)
Unabhängig von all dem werde ich mich nun mal aufmachen den Lancom-Support einzuschalten, denn das ursächliche Problem liegt evtl. wirklich beim DSLAM, aber ich kann nicht mit O2 reden bzw. die reden gar nicht erst mit mir, denn mit deren Taiwan-Billig-Modem geht es ja ganz prima.
Re: O2 VDSL Fehler tracen
Moin Orca!
Ciao, Georg
Das LANCOM zieht einen Timer auf. Wird ein PPP-Paket empfangen, merkt sich das LANCOM das. Läuft der Timer ab und es wurde zwischenzeitlich ein PPP-Paket empfangen, wird nur der Timer wieder aufgezogen. Wurde kein PPP-Paket empfangen, schickt das LANCOM selber ein LCP-Echo-Request, um die Gegenstelle zum Versenden eines PPP-Pakets zu veranlassen.0rca hat geschrieben:Wie lese ich das? "LCP polling timeout for peer O2-VDSL - data received during last interval" und "LCP polling timeout for peer MNETMODEM - data received during last interval", heisst das jeweils es gab einen Timeout oder so wird die timeout-prüfung initialisert?
Hier hat die andere Seite selber ein LCP-Echo-Request gesendet und das LANCOM antwortet darauf.0rca hat geschrieben:Und ich sehe nur bei einer Leitung "Received LCP frame from peer MNETMODEM (channel 11) Sending LCP echo-response with ID 0e and length 12 to peer MNETMODEM (channel 11)"
Wird das LCP-Polling nicht beantwortet, dann baut das LANCOM die PPP-Verbindung ab und versucht, sie neu aufzubauen. In deinem Trace funktioniert das LCP-Polling aber einwandfrei.0rca hat geschrieben:Davon ausgehend, dass das alles so seine Richtigkeit hat, was passiert denn, wenn eines der Pollings ins Leer läuft? Reagiert das Lancom in jedem Fall automatisch (wie?) oder kann/muss ich irgendwo eine entsprechende Aktion anlegen (wo?)
Ciao, Georg
Re: O2 VDSL Fehler tracen
OK, cool. Herzlichen Dank!!
Da wir ja festgestellt haben, dass ein Abbau der PPP-Verbindung nichts hilft, müsste ich nun also ein retrain aufgrund eines fehlschlagenden LCP- oder ICMP-Pollings veranlassen (Sagtest Du ja auch weiter oben schon).
Welches Verbindungs-Ereignis (für Aktions-Tabelle) tritt denn ein, wenn ein Polling fehlschlägt? Abbau ohne Fehler, Ende (Abbau oder Abbruch), Abbruch mit Fehler oder gar Aufbaufehler? Finde nicht wirklich was zu den genauen Definition in der KB.
Danke für Deine Geduld....
Da wir ja festgestellt haben, dass ein Abbau der PPP-Verbindung nichts hilft, müsste ich nun also ein retrain aufgrund eines fehlschlagenden LCP- oder ICMP-Pollings veranlassen (Sagtest Du ja auch weiter oben schon).
Welches Verbindungs-Ereignis (für Aktions-Tabelle) tritt denn ein, wenn ein Polling fehlschlägt? Abbau ohne Fehler, Ende (Abbau oder Abbruch), Abbruch mit Fehler oder gar Aufbaufehler? Finde nicht wirklich was zu den genauen Definition in der KB.
Danke für Deine Geduld....
Re: O2 VDSL Fehler tracen
Moin Orca!
du kannst dafür auch den Alive-Test für nehmen. Dafür kannst du folgende Anleitung abwandeln: https://www2.lancom.de/kb.nsf/1275/0B40 ... C00048057D. Als Aktion müsstest du da den Modem-Retrain eintragen.
Alternativ geht es natürlich auch mit IP-Polling und der Aktionstabelle, da die Verbindung mit Polling bei dir nicht wieder zustande kommen würde. Ich kann dir allerdings nicht sagen, welcher Aufbaufehler gemeldet wird.
Ciao, Georg
du kannst dafür auch den Alive-Test für nehmen. Dafür kannst du folgende Anleitung abwandeln: https://www2.lancom.de/kb.nsf/1275/0B40 ... C00048057D. Als Aktion müsstest du da den Modem-Retrain eintragen.
Alternativ geht es natürlich auch mit IP-Polling und der Aktionstabelle, da die Verbindung mit Polling bei dir nicht wieder zustande kommen würde. Ich kann dir allerdings nicht sagen, welcher Aufbaufehler gemeldet wird.
Ciao, Georg