Wired Intelligent Edge

 View Only

BPDU Starved

This thread has been viewed 14 times
  • 1.  BPDU Starved

    Posted Aug 07, 2023 08:06 AM

    I have a problem that starts with CIST STARVED FOR A BPDU RX 

    And then a whole circus starts. 

    Root changes and so on, 

    Things i've checked: 

    - Configu of spanning-tree is everywhere the same, only root has prio of 4096 instead of default 32768
    - no loops seen with LLDP neighbor info
    - mst-conf has everywhere same digest key

    undezrneath you can see the logging that starts with the starved log and triggers root change an reauth of all clients.  

    2023-08-07T13:08:56.429698+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:08:54.912812+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:08:54.911469+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:08:54.908251+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:08:54.799250+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 32768: ec:02:73:60:2d:c0 to 4096: 0a:00:00:00:00:01
    2023-08-07T13:08:54.797709+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:08:52.530907+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 4096: 0a:00:00:00:00:01 to 32768: ec:02:73:60:2d:c0
    2023-08-07T13:08:52.529517+02:00 SW6100-05 hpe-mstpd[2306]: Event|2008|LOG_INFO|AMM|1/1|CIST starved for a BPDU Rx on port 1/1/47 from 32768:ec0273-608200
    2023-08-07T13:08:46.784130+02:00 SW6100-05 hpe-mstpd[2306]: Event|2018|LOG_INFO|AMM|1/1|CIST Root Port changed from 1/1/48 to 1/1/47
    2023-08-07T13:08:46.252038+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 32768: ec:02:73:60:2d:c0 to 4096: 0a:00:00:00:00:01
    2023-08-07T13:08:45.527667+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 4096: 0a:00:00:00:00:01 to 32768: ec:02:73:60:2d:c0
    2023-08-07T13:08:45.526296+02:00 SW6100-05 hpe-mstpd[2306]: Event|2008|LOG_INFO|AMM|1/1|CIST starved for a BPDU Rx on port 1/1/47 from 32768:ec0273-608200
    2023-08-07T13:08:40.429171+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:08:39.410037+02:00 SW6100-05 lldpd[2320]: Event|104|LOG_INFO|AMM|1/1|LLDP neighbor ec:02:73:60:82:00 added on 1/1/47
    2023-08-07T13:08:38.934477+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:08:38.785636+02:00 SW6100-05 hpe-mstpd[2306]: Event|2018|LOG_INFO|AMM|1/1|CIST Root Port changed from 1/1/48 to 1/1/47
    2023-08-07T13:08:36.282499+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 32768: ec:02:73:60:2d:c0 to 4096: 0a:00:00:00:00:01
    2023-08-07T13:08:35.529904+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 4096: 0a:00:00:00:00:01 to 32768: ec:02:73:60:2d:c0
    2023-08-07T13:08:35.528520+02:00 SW6100-05 hpe-mstpd[2306]: Event|2008|LOG_INFO|AMM|1/1|CIST starved for a BPDU Rx on port 1/1/48 from 32768:ec0273-60ca80
    2023-08-07T13:08:30.251335+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 32768: ec:02:73:60:2d:c0 to 4096: 0a:00:00:00:00:01
    2023-08-07T13:08:28.530000+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 4096: 0a:00:00:00:00:01 to 32768: ec:02:73:60:2d:c0
    2023-08-07T13:08:28.528615+02:00 SW6100-05 hpe-mstpd[2306]: Event|2008|LOG_INFO|AMM|1/1|CIST starved for a BPDU Rx on port 1/1/47 from 32768:ec0273-608200
    2023-08-07T13:08:24.257086+02:00 SW6100-05 port-accessd[2321]: Event|10503|LOG_INFO|AMM|1/1|Port 1/1/5 is unblocked by port-access
    2023-08-07T13:08:24.233261+02:00 SW6100-05 ops-switchd[537]: Event|2108|LOG_INFO|AMM|1/1|Created Mac based VLAN entry. VLAN 159 is mapped to client 9c:eb:e8:6a:04:44 on port 1/1/5
    2023-08-07T13:08:24.183901+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/5 pvid = 159, Neighbor 9c:eb:e8:6a:04:44 port_id = 9c:eb:e8:6a:04:44 pvid = 0
    2023-08-07T13:08:23.593840+02:00 SW6100-05 ops-switchd[537]: Event|2110|LOG_INFO|AMM|1/1|Deleted Mac based VLAN entry for 9c:eb:e8:6a:04:44 with VLAN 159 on port 1/1/5
    2023-08-07T13:08:23.370670+02:00 SW6100-05 lldpd[2320]: Event|106|LOG_INFO|AMM|1/1|LLDP neighbor ec:02:73:60:82:00 deleted on 1/1/47
    2023-08-07T13:08:23.360486+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/5 pvid = 172, Neighbor 9c:eb:e8:6a:04:44 port_id = 9c:eb:e8:6a:04:44 pvid = 0
    2023-08-07T13:08:23.293925+02:00 SW6100-05 port-accessd[2321]: Event|10502|LOG_INFO|AMM|1/1|Port 1/1/5 is blocked by port-access
    2023-08-07T13:08:22.995975+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:08:22.991561+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:08:22.990157+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:08:22.818705+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 32768: ec:02:73:60:2d:c0 to 4096: 0a:00:00:00:00:01
    2023-08-07T13:07:16.528182+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 4096: 0a:00:00:00:00:01 to 32768: ec:02:73:60:2d:c0
    2023-08-07T13:07:16.526799+02:00 SW6100-05 hpe-mstpd[2306]: Event|2008|LOG_INFO|AMM|1/1|CIST starved for a BPDU Rx on port 1/1/48 from 32768:ec0273-60ca80
    2023-08-07T13:07:10.846549+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/48 for CIST from source: ec:02:73:60:ca:91
    2023-08-07T13:07:10.250130+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 32768: ec:02:73:60:2d:c0 to 4096: 0a:00:00:00:00:01
    2023-08-07T13:07:08.531968+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 4096: 0a:00:00:00:00:01 to 32768: ec:02:73:60:2d:c0
    2023-08-07T13:07:08.529449+02:00 SW6100-05 hpe-mstpd[2306]: Event|2008|LOG_INFO|AMM|1/1|CIST starved for a BPDU Rx on port 1/1/47 from 32768:ec0273-608200
    2023-08-07T13:07:03.303352+02:00 SW6100-05 port-accessd[2321]: Event|10503|LOG_INFO|AMM|1/1|Port 1/1/40 is unblocked by port-access
    2023-08-07T13:07:03.031883+02:00 SW6100-05 port-accessd[2321]: Event|10503|LOG_INFO|AMM|1/1|Port 1/1/17 is unblocked by port-access
    2023-08-07T13:07:02.968089+02:00 SW6100-05 port-accessd[2321]: Event|10503|LOG_INFO|AMM|1/1|Port 1/1/12 is unblocked by port-access
    2023-08-07T13:07:02.859379+02:00 SW6100-05 port-accessd[2321]: Event|10503|LOG_INFO|AMM|1/1|Port 1/1/30 is unblocked by port-access
    2023-08-07T13:07:02.851798+02:00 SW6100-05 ops-switchd[537]: Event|2108|LOG_INFO|AMM|1/1|Created Mac based VLAN entry. VLAN 159 is mapped to client 64:00:6a:4c:4d:d4 on port 1/1/40
    2023-08-07T13:07:02.819239+02:00 SW6100-05 port-accessd[2321]: Event|10503|LOG_INFO|AMM|1/1|Port 1/1/37 is unblocked by port-access
    2023-08-07T13:07:02.790208+02:00 SW6100-05 port-accessd[2321]: Event|10503|LOG_INFO|AMM|1/1|Port 1/1/20 is unblocked by port-access
    2023-08-07T13:07:02.774722+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/40 pvid = 159, Neighbor 64:00:6a:4c:4d:d4 port_id = 64:00:6a:4c:4d:d4 pvid = 0
    2023-08-07T13:07:02.688009+02:00 SW6100-05 ops-switchd[537]: Event|2108|LOG_INFO|AMM|1/1|Created Mac based VLAN entry. VLAN 159 is mapped to client 10:e7:c6:30:6d:bb on port 1/1/17
    2023-08-07T13:07:02.605376+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/17 pvid = 159, Neighbor 10:e7:c6:30:6d:bb port_id = 10:e7:c6:30:6d:bb pvid = 0
    2023-08-07T13:07:02.517188+02:00 SW6100-05 ops-switchd[537]: Event|2108|LOG_INFO|AMM|1/1|Created Mac based VLAN entry. VLAN 159 is mapped to client 34:48:ed:2f:e9:1f on port 1/1/12
    2023-08-07T13:07:02.490221+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/12 pvid = 159, Neighbor 34:48:ed:2f:e9:1f port_id = 34:48:ed:2f:e9:1f pvid = 0
    2023-08-07T13:07:02.322452+02:00 SW6100-05 ops-switchd[537]: Event|2108|LOG_INFO|AMM|1/1|Created Mac based VLAN entry. VLAN 159 is mapped to client f8:b1:56:c6:11:7f on port 1/1/30
    2023-08-07T13:07:02.279592+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/30 pvid = 159, Neighbor f8:b1:56:c6:11:7f port_id = f8:b1:56:c6:11:7f pvid = 0
    2023-08-07T13:07:02.103799+02:00 SW6100-05 ops-switchd[537]: Event|2108|LOG_INFO|AMM|1/1|Created Mac based VLAN entry. VLAN 159 is mapped to client 34:48:ed:28:e4:01 on port 1/1/37
    2023-08-07T13:07:02.098262+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/37 pvid = 159, Neighbor 34:48:ed:28:e4:01 port_id = 34:48:ed:28:e4:01 pvid = 0
    2023-08-07T13:07:01.913253+02:00 SW6100-05 ops-switchd[537]: Event|2108|LOG_INFO|AMM|1/1|Created Mac based VLAN entry. VLAN 159 is mapped to client 6c:3c:8c:25:8e:88 on port 1/1/20
    2023-08-07T13:07:01.855678+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/20 pvid = 159, Neighbor 6c:3c:8c:25:8e:88 port_id = 6c:3c:8c:25:8e:88 pvid = 0
    2023-08-07T13:07:01.428881+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:07:00.707153+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/17 pvid = 172, Neighbor 10:e7:c6:30:6d:bb port_id = 10:e7:c6:30:6d:bb pvid = 0
    2023-08-07T13:07:00.695021+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/12 pvid = 172, Neighbor 34:48:ed:2f:e9:1f port_id = 34:48:ed:2f:e9:1f pvid = 0
    2023-08-07T13:07:00.676768+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/30 pvid = 172, Neighbor f8:b1:56:c6:11:7f port_id = f8:b1:56:c6:11:7f pvid = 0
    2023-08-07T13:07:00.667065+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/40 pvid = 172, Neighbor 64:00:6a:4c:4d:d4 port_id = 64:00:6a:4c:4d:d4 pvid = 0
    2023-08-07T13:07:00.596024+02:00 SW6100-05 hpe-mstpd[2306]: Event|2011|LOG_INFO|AMM|1/1|Topology Change received on port 1/1/47 for CIST from source: ec:02:73:60:82:10
    2023-08-07T13:07:00.381589+02:00 SW6100-05 ops-switchd[537]: Event|2110|LOG_INFO|AMM|1/1|Deleted Mac based VLAN entry for 64:00:6a:4c:4d:d4 with VLAN 159 on port 1/1/40
    2023-08-07T13:07:00.239344+02:00 SW6100-05 ops-switchd[537]: Event|2110|LOG_INFO|AMM|1/1|Deleted Mac based VLAN entry for 10:e7:c6:30:6d:bb with VLAN 159 on port 1/1/17
    2023-08-07T13:07:00.147466+02:00 SW6100-05 ops-switchd[537]: Event|2110|LOG_INFO|AMM|1/1|Deleted Mac based VLAN entry for 34:48:ed:2f:e9:1f with VLAN 159 on port 1/1/12
    2023-08-07T13:07:00.067082+02:00 SW6100-05 port-accessd[2321]: Event|10502|LOG_INFO|AMM|1/1|Port 1/1/40 is blocked by port-access
    2023-08-07T13:07:00.043521+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/37 pvid = 172, Neighbor 34:48:ed:28:e4:01 port_id = 34:48:ed:28:e4:01 pvid = 0
    2023-08-07T13:07:00.029577+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/12 pvid = 172, Neighbor 34:48:ed:2f:e9:1f port_id = 34:48:ed:2f:e9:1f pvid = 0
    2023-08-07T13:06:59.992086+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/30 pvid = 172, Neighbor f8:b1:56:c6:11:7f port_id = f8:b1:56:c6:11:7f pvid = 0
    2023-08-07T13:06:59.949463+02:00 SW6100-05 ops-switchd[537]: Event|2110|LOG_INFO|AMM|1/1|Deleted Mac based VLAN entry for f8:b1:56:c6:11:7f with VLAN 159 on port 1/1/30
    2023-08-07T13:06:59.922800+02:00 SW6100-05 ops-switchd[537]: Event|2110|LOG_INFO|AMM|1/1|Deleted Mac based VLAN entry for 60:a4:b7:8a:06:7c with VLAN 172 on port 1/1/29
    2023-08-07T13:06:59.916786+02:00 SW6100-05 ops-switchd[537]: Event|2110|LOG_INFO|AMM|1/1|Deleted Mac based VLAN entry for 34:48:ed:28:e4:01 with VLAN 159 on port 1/1/37
    2023-08-07T13:06:59.854072+02:00 SW6100-05 port-accessd[2321]: Event|10502|LOG_INFO|AMM|1/1|Port 1/1/17 is blocked by port-access
    2023-08-07T13:06:59.750180+02:00 SW6100-05 port-accessd[2321]: Event|10502|LOG_INFO|AMM|1/1|Port 1/1/12 is blocked by port-access
    2023-08-07T13:06:59.689831+02:00 SW6100-05 ops-switchd[537]: Event|2110|LOG_INFO|AMM|1/1|Deleted Mac based VLAN entry for 6c:3c:8c:25:8e:88 with VLAN 159 on port 1/1/20
    2023-08-07T13:06:59.662723+02:00 SW6100-05 port-accessd[2321]: Event|10502|LOG_INFO|AMM|1/1|Port 1/1/30 is blocked by port-access
    2023-08-07T13:06:59.650352+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/37 pvid = 172, Neighbor 34:48:ed:28:e4:01 port_id = 34:48:ed:28:e4:01 pvid = 0
    2023-08-07T13:06:59.575374+02:00 SW6100-05 port-accessd[2321]: Event|10502|LOG_INFO|AMM|1/1|Port 1/1/29 is blocked by port-access
    2023-08-07T13:06:59.461263+02:00 SW6100-05 lldpd[2320]: Event|113|LOG_INFO|AMM|1/1|PVID mismatch on 1/1/20 pvid = 172, Neighbor 6c:3c:8c:25:8e:88 port_id = 6c:3c:8c:25:8e:88 pvid = 0
    2023-08-07T13:06:59.388342+02:00 SW6100-05 port-accessd[2321]: Event|10502|LOG_INFO|AMM|1/1|Port 1/1/37 is blocked by port-access
    2023-08-07T13:06:59.349685+02:00 SW6100-05 port-accessd[2321]: Event|10502|LOG_INFO|AMM|1/1|Port 1/1/20 is blocked by port-access
    2023-08-07T13:06:58.955284+02:00 SW6100-05 hpe-mstpd[2306]: Event|2018|LOG_INFO|AMM|1/1|CIST Root Port changed from 1/1/48 to 1/1/47
    2023-08-07T13:06:58.797317+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 32768: ec:02:73:60:2d:c0 to 4096: 0a:00:00:00:00:01
    2023-08-07T13:06:57.530248+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 4096: 0a:00:00:00:00:01 to 32768: ec:02:73:60:2d:c0
    2023-08-07T13:06:57.528798+02:00 SW6100-05 hpe-mstpd[2306]: Event|2008|LOG_INFO|AMM|1/1|CIST starved for a BPDU Rx on port 1/1/48 from 32768:ec0273-60ca80
    2023-08-07T13:06:50.266446+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 32768: ec:02:73:60:2d:c0 to 4096: 0a:00:00:00:00:01
    2023-08-07T13:06:49.856790+02:00 SW6100-05 lldpd[2320]: Event|104|LOG_INFO|AMM|1/1|LLDP neighbor f4:ee:08:e1:81:3d added on 1/1/42
    2023-08-07T13:06:49.527788+02:00 SW6100-05 hpe-mstpd[2306]: Event|2006|LOG_INFO|AMM|1/1|CST  - Root changed from 4096: 0a:00:00:00:00:01 to 32768: ec:02:73:60:2d:c0
    2023-08-07T13:06:49.526378+02:00 SW6100-05 hpe-mstpd[2306]: Event|2008|LOG_INFO|AMM|1/1|CIST starved for a BPDU Rx on port 1/1/47 from 32768:ec0273-608200