Gisteravond (12 oktober) tussen 23:21:18 - 23:21:59 en later nog een keer tussen 00:02:34 - 02:45:15 was mijn VDSL2 verbinding down. Nu terminineer ik deze op een Juniper SRX, onderstaande de logs (LETOP de tijden zijn in UTC dus 1 uur vroeger)
****@VDSL-srx2> show log messages| match "pt-1/0/0|PFE-6-MVDSL" | except "INTERACT-6-UI_CMDLINE_READ_LINE|USER-6-IFINFO_IFD_NAME"
Nov 12 22:21:18 VDSL-srx2 : %PFE-6: ifp pt-1/0/0 ifd_mdown: 957626649 ms
Nov 12 22:21:18 VDSL-srx2 : %PFE-6-MVDSL: mvdsl_ifd_line_control, OP type = 4
Nov 12 22:21:19 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.32767 index 94 <Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 22:21:19 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.34 index 93 <Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 22:21:19 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFA_NOTIFICATION: EVENT <UpDown> pt-1/0/0.34 index 93 145.129.61.17/24 -> 145.129.61.255 <Broadcast Multicast Localup>
Nov 12 22:21:19 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.7 index 92 <Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 22:21:19 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFD_NOTIFICATION: EVENT <UpDown> pt-1/0/0 index 152 <Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 22:21:19 VDSL-srx2 mib2d22007]: %DAEMON-4-SNMP_TRAP_LINK_DOWN: ifIndex 535, ifAdminStatus up(1), ifOperStatus down(2), ifName pt-1/0/0
Nov 12 22:21:22 VDSL-srx2 /kernel: %KERN-7: iff_handle_ifa_delete: deletion of address on IFL pt-1/0/0 has resulted in the removal of primary source address
Nov 12 22:21:58 VDSL-srx2 : %PFE-6-MVDSL: mvdsl_ifd_line_control, OP type = 4
Nov 12 22:21:59 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.32767 index 94 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 22:21:59 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.34 index 93 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 22:21:59 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.7 index 92 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 22:21:59 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFD_NOTIFICATION: EVENT <UpDown> pt-1/0/0 index 152 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 22:21:59 VDSL-srx2 mib2d22007]: %DAEMON-5-SNMP_TRAP_LINK_UP: ifIndex 535, ifAdminStatus up(1), ifOperStatus up(1), ifName pt-1/0/0
Nov 12 22:21:59 VDSL-srx2 mib2d22007]: %DAEMON-6-SNMP_TRAP_LINK_UP: ifIndex 514, ifAdminStatus up(1), ifOperStatus up(1), ifName pt-1/0/0.7
Nov 12 22:21:59 VDSL-srx2 mib2d22007]: %DAEMON-6-SNMP_TRAP_LINK_UP: ifIndex 536, ifAdminStatus up(1), ifOperStatus up(1), ifName pt-1/0/0.34
Nov 12 22:21:59 VDSL-srx2 mib2d22007]: %DAEMON-6-SNMP_TRAP_LINK_UP: ifIndex 537, ifAdminStatus up(1), ifOperStatus up(1), ifName pt-1/0/0.32767
Nov 12 23:02:34 VDSL-srx2 : %PFE-6: ifp pt-1/0/0 ifd_mdown: 960103086 ms
Nov 12 23:02:34 VDSL-srx2 : %PFE-6-MVDSL: mvdsl_ifd_line_control, OP type = 4
Nov 12 23:02:35 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.32767 index 94 <Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 23:02:35 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.34 index 93 <Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 23:02:35 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFA_NOTIFICATION: EVENT <UpDown> pt-1/0/0.34 index 93 145.129.61.17/24 -> 145.129.61.255 <Broadcast Multicast Localup>
Nov 12 23:02:35 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.7 index 92 <Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 23:02:35 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFD_NOTIFICATION: EVENT <UpDown> pt-1/0/0 index 152 <Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 12 23:02:35 VDSL-srx2 mib2d22007]: %DAEMON-4-SNMP_TRAP_LINK_DOWN: ifIndex 535, ifAdminStatus up(1), ifOperStatus down(2), ifName pt-1/0/0
Nov 12 23:02:40 VDSL-srx2 /kernel: %KERN-7: iff_handle_ifa_delete: deletion of address on IFL pt-1/0/0 has resulted in the removal of primary source address
Nov 13 01:45:14 VDSL-srx2 : %PFE-6-MVDSL: mvdsl_ifd_line_control, OP type = 4
Nov 13 01:45:15 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.32767 index 94 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 13 01:45:15 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.34 index 93 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 13 01:45:15 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <UpDown> pt-1/0/0.7 index 92 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 13 01:45:15 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFD_NOTIFICATION: EVENT <UpDown> pt-1/0/0 index 152 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 13 01:45:15 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFD_NOTIFICATION: EVENT <Bandwidth> pt-1/0/0 index 152 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 13 01:45:15 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <Bandwidth> pt-1/0/0.32767 index 94 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 13 01:45:15 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <Bandwidth> pt-1/0/0.34 index 93 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 13 01:45:15 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <Bandwidth> pt-1/0/0.7 index 92 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 13 01:45:15 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <Bandwidth> pt-1/0/0.7 index 92 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 13 01:45:15 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <Bandwidth> pt-1/0/0.34 index 93 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 13 01:45:15 VDSL-srx2 rpdp2008]: %DAEMON-6-RPD_IFL_NOTIFICATION: EVENT <Bandwidth> pt-1/0/0.32767 index 94 <Up Broadcast Multicast> address #0 58.0.bb.a6.c1.aa
Nov 13 01:45:15 VDSL-srx2 mib2d22007]: %DAEMON-5-SNMP_TRAP_LINK_UP: ifIndex 535, ifAdminStatus up(1), ifOperStatus up(1), ifName pt-1/0/0
Nov 13 01:45:15 VDSL-srx2 mib2d22007]: %DAEMON-6-SNMP_TRAP_LINK_UP: ifIndex 514, ifAdminStatus up(1), ifOperStatus up(1), ifName pt-1/0/0.7
Nov 13 01:45:15 VDSL-srx2 mib2d22007]: %DAEMON-6-SNMP_TRAP_LINK_UP: ifIndex 536, ifAdminStatus up(1), ifOperStatus up(1), ifName pt-1/0/0.34
Nov 13 01:45:15 VDSL-srx2 mib2d22007]: %DAEMON-6-SNMP_TRAP_LINK_UP: ifIndex 537, ifAdminStatus up(1), ifOperStatus up(1), ifName pt-1/0/0.32767
Nov 13 01:45:15 VDSL-srx2 : %PFE-6-COSMAN: IFD pt-1/0/0 BW percent 0 subrate 7807000
Nov 13 01:45:15 VDSL-srx2 : %PFE-7: cosman_update_specific_sched_policy_for_ifd:2686 compute params for ifd 152 (pt-1/0/0) return 0
Waar ik normaal via SMS informatie kreeg over werkzaamheden heb ik die deze keer niet gehad.
Verder na de eerste storing trainde mijn verbinding een stuk trager in op op 35mbit down en 10mbit up (waar ik normaal 50-55mb down en 10mb up gewent ben
pieter@VDSL-srx2> show interfaces pt-1/0/0 extensive
Physical interface: pt-1/0/0, Enabled, Physical link is Up
<snip>
VDSL status:
Modem status : Showtime (Profile-17a)
VDSL profile : Auto Annex B
Last fail code: None
Subfunction : 0x00
Seconds in showtime : 27
VDSL Chipset Information: VTU-R VTU-C
Vendor Country : 0xb5 0xb5
Vendor ID : BDCM BDCM
Vendor Specific: 0x9385 0xa495
VDSL Statistics: VTU-R VTU-C
Attenuation (dB) : 0.0 0.0
Capacity used (%) : 0 0
Noise margin (dB) : 4.5 9.0
Output power (dBm) : 14.5 4.0
Interleave Fast Interleave Fast
Bit rate (kbps) : 35509 0 10111 0
CRC : 0 3223 0 0
FEC : 0 0 0 0
HEC : 0 0 0 0
Na de laatste storing is het wat beter 40mbit down en 7.8mbit up
****@VDSL-srx2> show interfaces pt-1/0/0 extensive
Physical interface: pt-1/0/0, Enabled, Physical link is Up
<snip>
VDSL status:
Modem status : Showtime (Profile-17a)
VDSL profile : Auto Annex B
Last fail code: None
Subfunction : 0x00
Seconds in showtime : 25164
VDSL Chipset Information: VTU-R VTU-C
Vendor Country : 0xb5 0xb5
Vendor ID : BDCM BDCM
Vendor Specific: 0x9385 0xb1a1
VDSL Statistics: VTU-R VTU-C
Attenuation (dB) : 0.0 0.0
Capacity used (%) : 0 0
Noise margin (dB) : 14.0 14.5
Output power (dBm) : 14.0 -7.0
Interleave Fast Interleave Fast
Bit rate (kbps) : 40959 0 7807 0
CRC : 0 2669 0 0
FEC : 0 0 0 0
HEC : 0 0 0 0
De vraag is wat is er gebeurt, de noise marges zijn flink omhoog…
Voor de zekerheid heb ik tijdens de 2de storing mijn experia box aangesloten, ook daar was het geen link.
Waar ik voorheen waardes zag van 53mbit down en 10mbit up met andere noise margins (waardes van 28 oktober)
****@VDSL-srx2> show interfaces pt-1/0/0 extensive
Physical interface: pt-1/0/0, Enabled, Physical link is Up
<snip>
VDSL status:
Modem status : Showtime (Profile-17a)
VDSL profile : Auto Annex B
Last fail code: None
Subfunction : 0x00
Seconds in showtime : 143016
VDSL Chipset Information: VTU-R VTU-C
Vendor Country : 0xb5 0xb5
Vendor ID : BDCM BDCM
Vendor Specific: 0x9385 0xa495
VDSL Statistics: VTU-R VTU-C
Attenuation (dB) : 0.0 0.0
Capacity used (%) : 0 0
Noise margin (dB) : 6.0 13.0
Output power (dBm) : 14.5 -1.5
Interleave Fast Interleave Fast
Bit rate (kbps) : 53001 0 10111 0
CRC : 0 2920 0 0
FEC : 0 0 0 0
HEC : 0 0 0 0
Ik gebruik een Juniper SRX320 met een recente JunOS (19.3R2-S4) en de 2.16.0 firmware op de VDSL2 mpim die vectoring ondersteunt. Dit gebruik ik reeds 2-3 jaar en deze lage snelheden heb ik niet eerder gezien.
De vraag is wat is er gebeurd en waarom zijn de noise margins veranderd?