Hi all,
I'm currently expierincing some vrrp issues where the backup vrrp takes over of the master. In the logs i'm seeing that the master is sending advertisements, but I'm seeing rather strange messages on the backup:
My theory is: VRRP is just working fine, untill it receives an other VRRP request, and because of that, the preempt starts. Am I right about this?
Mar 13 07:57:52 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5a800 err 0 MsgCode 11000 DestPtNm 8378 DestIp 127.0.0.1 PckTy 1025 Sequence number 3142
Mar 13 07:57:52 fpapps[1687]: <313328> <WARN> |fpapps| vrrp: vrid "147" - VRRP state transitioned from MASTER to BACKUP
Mar 13 07:57:52 fpapps[1687]: <306502> <DBUG> |fpapps| Publish service 'VRRP_STATE', object len 20
Mar 13 07:57:52 fpapps[1687]: <313330> <INFO> |fpapps| Sending Role change event to CFGM
Mar 13 07:57:52 fpapps[1687]: <313332> <WARN> |fpapps| VRRP: vrid "147"(Master) - Received VRRP Advertisement with HIGHER PRIORITY (110) from 172.16.4.8
Mar 13 07:57:52 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5a800 err 0 MsgCode 11000 DestPtNm 8378 DestIp 127.0.0.1 PckTy 1025 Sequence number 3143
Mar 13 07:57:52 fpapps[1687]: <313328> <WARN> |fpapps| vrrp: vrid "148" - VRRP state transitioned from MASTER to BACKUP
Mar 13 07:57:52 fpapps[1687]: <306502> <DBUG> |fpapps| Publish service 'VRRP_STATE', object len 20
Mar 13 07:57:52 fpapps[1687]: <313332> <WARN> |fpapps| VRRP: vrid "148"(Master) - Received VRRP Advertisement with HIGHER PRIORITY (110) from 10.160.5.208
Mar 13 07:57:52 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5a800 err 0 MsgCode 11000 DestPtNm 8378 DestIp 127.0.0.1 PckTy 1025 Sequence number 3144
Mar 13 07:57:52 fpapps[1687]: <313328> <WARN> |fpapps| vrrp: vrid "222" - VRRP state transitioned from MASTER to BACKUP
Mar 13 07:57:52 fpapps[1687]: <306502> <DBUG> |fpapps| Publish service 'VRRP_STATE', object len 20
Mar 13 07:57:52 fpapps[1687]: <313332> <WARN> |fpapps| VRRP: vrid "222"(Master) - Received VRRP Advertisement with HIGHER PRIORITY (110) from x.x.x.x
Mar 13 07:57:52 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5a800 err 0 MsgCode 11000 DestPtNm 8378 DestIp 127.0.0.1 PckTy 1025 Sequence number 3145
13 07:57:53 fpapps[1579]: <399814> <DBUG> |fpapps| Received a Switch IP Request from 8224
Mar 13 07:57:53 fpapps[1579]: <399814> <DBUG> |fpapps| Received a Switch IPv6 Request from 8224
Mar 13 07:57:53 fpapps[1579]: <399814> <DBUG> |fpapps| Received a Switch IP Request from 8224
Mar 13 07:57:53 fpapps[1579]: <399814> <DBUG> |fpapps| Received a Switch IPv6 Request from 8224
Mar 13 07:57:53 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5a800 err 0 MsgCode 7005 DestPtNm 8224 DestIp 127.0.0.1 PckTy 1025 Sequence number 3146
Mar 13 07:57:53 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5b000 err 0 MsgCode 7005 DestPtNm 8224 DestIp 127.0.0.1 PckTy 1025 Sequence number 3147
Mar 13 07:58:19 fpapps[1579]: <399835> <DBUG> |fpapps| Time taken for activation of configuration 0 sec and 195 microseconds.
Mar 13 07:58:19 fpapps[1579]: <306504> <DBUG> |fpapps| received subscription message from 8214 for service 'tunnel'
Mar 13 07:58:19 fpapps[1579]: <306504> <DBUG> |fpapps| received subscription message from 8214 for service 'VRRP'
Mar 13 07:58:19 fpapps[1579]: <306503> <DBUG> |fpapps| Publish service 'VRRP', object len 12 to module 8214
Mar 13 07:58:19 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5a800 err 0 MsgCode 11000 DestPtNm 8214 DestIp 127.0.0.1 PckTy 1025 Sequence number 3148
Mar 13 07:58:19 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5b000 err 0 MsgCode 11000 DestPtNm 8214 DestIp 127.0.0.1 PckTy 1025 Sequence number 3149
Mar 13 07:58:19 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5b800 err 0 MsgCode 11000 DestPtNm 8214 DestIp 127.0.0.1 PckTy 1025 Sequence number 3150
Mar 13 07:58:19 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5c000 err 0 MsgCode 11000 DestPtNm 8214 DestIp 127.0.0.1 PckTy 1025 Sequence number 3151
Mar 13 07:58:19 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x13773ad4 err 0 MsgCode 11000 DestPtNm 8214 DestIp 127.0.0.1 PckTy 1025 Sequence number 3152
Mar 13 07:58:19 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x13772744 err 0 MsgCode 11000 DestPtNm 8214 DestIp 127.0.0.1 PckTy 1025 Sequence number 3153
Mar 13 07:58:19 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x13772854 err 0 MsgCode 11000 DestPtNm 8214 DestIp 127.0.0.1 PckTy 1025 Sequence number 3154
Mar 13 07:58:19 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x13772eac err 0 MsgCode 11000 DestPtNm 8214 DestIp 127.0.0.1 PckTy 1025 Sequence number 3155
Mar 13 07:58:19 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x13772fbc err 0 MsgCode 11000 DestPtNm 8214 DestIp 127.0.0.1 PckTy 1025 Sequence number 3156
Mar 13 07:59:21 fpapps[1687]: <313508> <DBUG> |fpapps| Started the VRRP preempt delay timer for VRID 222
Mar 13 07:59:30 fpapps[1687]: <313509> <DBUG> |fpapps| VRRP Preempt delay timer expired for VRID 222
Mar 13 07:59:30 fpapps[1687]: <313329> <DBUG> |fpapps| VRRP: Sending Advertisement for vrid 222
Mar 13 07:59:30 fpapps[1687]: <313328> <WARN> |fpapps| vrrp: vrid "222" - VRRP state transitioned from BACKUP to MASTER
Mar 13 07:59:30 fpapps[1687]: <306502> <DBUG> |fpapps| Publish service 'VRRP_STATE', object len 20
Mar 13 07:59:31 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5a800 err 0 MsgCode 11000 DestPtNm 8378 DestIp 127.0.0.1 PckTy 1025 Sequence number 3157
Mar 13 07:59:31 fpapps[1687]: <313328> <WARN> |fpapps| vrrp: vrid "222" - VRRP state transitioned from MASTER to BACKUP
Mar 13 07:59:31 fpapps[1687]: <306502> <DBUG> |fpapps| Publish service 'VRRP_STATE', object len 20
Mar 13 07:59:31 fpapps[1687]: <313332> <WARN> |fpapps| VRRP: vrid "222"(Master) - Received VRRP Advertisement with HIGHER PRIORITY (110) from x.x.x.x
Mar 13 07:59:32 fpapps[1579]: <399815> <INFO> |fpapps| fpappsCB: msgPvtPart 0x12a5a800 err 0 MsgCode 11000 DestPtNm 8378 DestIp 127.0.0.1 PckTy 1025 Sequence number 3158
Mar 13 08:00:32 fpapps[1687]: <313508> <DBUG> |fpapps| Started the VRRP preempt delay timer for VRID 147
Mar 13 08:00:32 fpapps[1687]: <313508> <DBUG> |fpapps| Started the VRRP preempt delay timer for VRID 148
Mar 13 08:00:32 fpapps[1687]: <313508> <DBUG> |fpapps| Started the VRRP preempt delay timer for VRID 222
Mar 13 08:00:33 fpapps[1687]: <313508> <DBUG> |fpapps| Started the VRRP preempt delay timer for VRID 62
Mar 13 08:00:33 fpapps[1687]: <313508> <DBUG> |fpapps| Started the VRRP preempt delay timer for VRID 63
Mar 13 08:00:33 fpapps[1687]: <313508> <DBUG> |fpapps| Started the VRRP preempt delay timer for VRID 65
Mar 13 08:00:33 fpapps[1687]: <313508> <DBUG> |fpapps| Started the VRRP preempt delay timer for VRID 146
Mar 13 08:00:34 fpapps[1687]: <313508> <DBUG> |fpapps| Started the VRRP preempt delay timer for VRID 60
Mar 13 08:00:34 fpapps[1687]: <313508> <DBUG> |fpapps| Started the VRRP preempt delay timer for VRID 61
Mar 13 08:00:42 fpapps[1687]: <313509> <DBUG> |fpapps| VRRP Preempt delay timer expired for VRID 147
Mar 13 08:00:42 fpapps[1687]: <313329> <DBUG> |fpapps| VRRP: Sending Advertisement for vrid 147
Mar 13 08:00:42 fpapps[1687]: <313328> <WARN> |fpapps| vrrp: vrid "147" - VRRP state transitioned from BACKUP to MASTER
#7005