spread.log saying "BUG WORKAROUND...swallowing token"
Martin Schu <[email protected]> Wed, 1 Feb 2017 10:14:56 +0100
| Newsgroups | gmane.network.spread.user |
|---|---|
| Message-ID | <CAOSQKUXnE9zE2kjapc8jNv2FiRAd7xWFxmDWHiBvxyz=CmfAJg@mail.gmail.com> |
--===============0730599925976401886==
Content-Type: multipart/alternative; boundary=001a1136f6ba7a6bfd0547747ba0
--001a1136f6ba7a6bfd0547747ba0
Content-Type: text/plain; charset=UTF-8
Hi John,
we have a spread-4.4.0 installation and observe some mysterious problems.
We have configured multicast and we have multiple interfaces per machine.
Perhaps something is wrong with the configuration for multicast in our
network.
In spread.log we see a strange error message saying *"**Prot_handle_token:
BUG WORKAROUND...swallowing token"*. This message is occurring 4 times per
minute. See below.
Digging in the source code of spread-5.0.0-rc2 we see that strange message
is still implemented there.
Can you give us some hints which circumstances could induce that strange
"BUG WORKAROUND" message? Is this a "workaround" for a bug in spread or for
a bug in network setup?
In our situation there is a total malfunction. No spread communication
possible at all.
If we remove the multicast setup from our configuration everything is
working fine on the same machines/network.
Best regards,
Martin
--
Spread_Segment 239.192.2.1:5555 {
easydb20-ref-back 1.2.3.131 { 1.2.3.131 }
}
Spread_Segment 239.192.2.2:5555 {
easy22-ref-back 1.2.3.4 { 1.2.3.4 }
easy23-ref-back 1.2.3.5 { 1.2.3.5 }
easy24-ref-back 1.2.3.6 { 1.2.3.6 }
}
Spread_Segment 239.192.2.3:5555 {
easydb21-ref-back 1.2.3.195 { 1.2.3.195 }
}
Spread_Segment 239.192.2.4:5555 {
easy25-ref-back 1.2.3.68 { 1.2.3.68 }
easy26-ref-back 1.2.3.69 { 1.2.3.69 }
easy27-ref-back 1.2.3.70 { 1.2.3.70 }
}
...
2017-01-31 10:25:10 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds
in EVS state; swallowing token; state:
2017-01-31 10:25:25 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds
in EVS state; swallowing token; state:
2017-01-31 10:25:43 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds
in EVS state; swallowing token; state:
2017-01-31 10:25:58 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds
in EVS state; swallowing token; state:
2017-01-31 10:26:16 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds
in EVS state; swallowing token; state:
2017-01-31 10:26:31 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds
in EVS state; swallowing token; state:
2017-01-31 10:26:34 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds
in EVS state; swallowing token; state:
2017-01-31 10:26:37 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds
in EVS state; swallowing token; state:
...
2017-01-31 10:39:42 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds
in EVS state; swallowing token; state:
2017-01-31 10:39:42 GMT Aru: 0
2017-01-31 10:39:42 GMT My_aru: 3366
2017-01-31 10:39:42 GMT Highest_seq: 0
2017-01-31 10:39:42 GMT Highest_fifo_seq: 0
2017-01-31 10:39:42 GMT Last_discarded: 0
2017-01-31 10:39:42 GMT Last_delivered: 0
2017-01-31 10:39:42 GMT Last_seq: 3366
2017-01-31 10:39:42 GMT Token_rounds: 501
2017-01-31 10:39:42 GMT Last Token:
2017-01-31 10:39:42 GMT type: 0x80050080
2017-01-31 10:39:42 GMT transmiter_id: -1726901244
2017-01-31 10:39:42 GMT seq: 0
2017-01-31 10:39:42 GMT proc_id: -1726901244
2017-01-31 10:39:42 GMT aru: 0
2017-01-31 10:39:42 GMT aru_last_id: -1726901179
2017-01-31 10:39:42 GMT flow_control: 33
2017-01-31 10:39:42 GMT rtr_len: 144
2017-01-31 10:39:42 GMT conf_hash: -2016107656
2017-01-31 10:39:57 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds
in EVS state; swallowing token; state:
2017-01-31 10:39:57 GMT Aru: 0
2017-01-31 10:39:57 GMT My_aru: 3366
2017-01-31 10:39:57 GMT Highest_seq: 0
2017-01-31 10:39:57 GMT Highest_fifo_seq: 0
2017-01-31 10:39:57 GMT Last_discarded: 0
2017-01-31 10:39:57 GMT Last_delivered: 0
2017-01-31 10:39:57 GMT Last_seq: 3366
2017-01-31 10:39:57 GMT Token_rounds: 501
2017-01-31 10:39:57 GMT Last Token:
2017-01-31 10:39:57 GMT type: 0x80050080
2017-01-31 10:39:57 GMT transmiter_id: -1726901244
2017-01-31 10:39:57 GMT seq: 0
2017-01-31 10:39:57 GMT proc_id: -1726901244
2017-01-31 10:39:57 GMT aru: 0
2017-01-31 10:39:57 GMT aru_last_id: -1726901179
2017-01-31 10:39:57 GMT flow_control: 33
2017-01-31 10:39:57 GMT rtr_len: 144
2017-01-31 10:39:57 GMT conf_hash: -2016107656
--001a1136f6ba7a6bfd0547747ba0
Content-Type: text/html; charset=UTF-8
Content-Transfer-Encoding: quoted-printable
<div dir=3D"ltr"><div>Hi John,<br><br></div><div>we have a spread-4.4.0 ins=
tallation and observe some mysterious problems.<br><br>We have configured m=
ulticast and we have multiple interfaces per machine. Perhaps something is =
wrong with the configuration for multicast in our network.<br></div><div><b=
r></div><div>In spread.log we see a strange error message saying <i>"<=
/i><i>Prot_handle_token: BUG WORKAROUND...swallowing token"</i>. This =
message is occurring 4 times per minute. See below.<br><br></div><div>Diggi=
ng in the source code of spread-5.0.0-rc2 we see that strange message is st=
ill implemented there.<br><br></div><div>Can you give us some hints which c=
ircumstances could induce that strange "BUG WORKAROUND" message? =
Is this a "workaround" for a bug in spread or for a bug in networ=
k setup?<br><br></div><div>In our situation there is a total malfunction. N=
o spread communication possible at all.<br><br></div><div>If we remove the =
multicast setup from our configuration everything is working fine on the sa=
me machines/network.<br></div><div><br></div><div>Best regards,<br></div><d=
iv>Martin<br></div><div>--<br>Spread_Segment=C2=A0 <a href=3D"http://239.19=
2.2.1:5555" target=3D"_blank">239.192.2.1:5555</a> {<br>=C2=A0=C2=A0=C2=A0=
=C2=A0=C2=A0=C2=A0=C2=A0 easydb20-ref-back=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=
=A0 1.2.3.131=C2=A0 { 1.2.3.131 }<br>}<br>Spread_Segment=C2=A0 <a href=3D"h=
ttp://239.192.2.2:5555" target=3D"_blank">239.192.2.2:5555</a> {<br>=C2=A0=
=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 easy22-ref-back 1.2.3.4=C2=A0=C2=A0=C2=
=A0 { 1.2.3.4 }<br>=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 easy23-ref-ba=
ck 1.2.3.5=C2=A0=C2=A0=C2=A0 { 1.2.3.5 }<br>=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=
=C2=A0=C2=A0 easy24-ref-back 1.2.3.6=C2=A0=C2=A0=C2=A0 { 1.2.3.6 }<br>}<br>=
Spread_Segment=C2=A0 <a href=3D"http://239.192.2.3:5555" target=3D"_blank">=
239.192.2.3:5555</a> {<br>=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 easydb=
21-ref-back=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 1.2.3.195=C2=A0 { 1.2.3.195=
}<br>}<br>Spread_Segment=C2=A0 <a href=3D"http://239.192.2.4:5555" target=
=3D"_blank">239.192.2.4:5555</a> {<br>=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=
=C2=A0 easy25-ref-back 1.2.3.68=C2=A0=C2=A0 { 1.2.3.68 }<br>=C2=A0=C2=A0=C2=
=A0=C2=A0=C2=A0=C2=A0=C2=A0 easy26-ref-back 1.2.3.69=C2=A0=C2=A0 { 1.2.3.69=
}<br>=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 easy27-ref-back 1.2.3.70=
=C2=A0=C2=A0 { 1.2.3.70 }<br>}<br>...<br>2017-01-31 10:25:10 GMT Prot_handl=
e_token: BUG WORKAROUND: Too many rounds in EVS state; swallowing token; st=
ate:<br>2017-01-31 10:25:25 GMT Prot_handle_token: BUG WORKAROUND: Too many=
rounds in EVS state; swallowing token; state:<br>2017-01-31 10:25:43 GMT P=
rot_handle_token: BUG WORKAROUND: Too many rounds in EVS state; swallowing =
token; state:<br>2017-01-31 10:25:58 GMT Prot_handle_token: BUG WORKAROUND:=
Too many rounds in EVS state; swallowing token; state:<br>2017-01-31 10:26=
:16 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds in EVS state; sw=
allowing token; state:<br>2017-01-31 10:26:31 GMT Prot_handle_token: BUG WO=
RKAROUND: Too many rounds in EVS state; swallowing token; state:<br>2017-01=
-31 10:26:34 GMT Prot_handle_token: BUG WORKAROUND: Too many rounds in EVS =
state; swallowing token; state:<br>2017-01-31 10:26:37 GMT Prot_handle_toke=
n: BUG WORKAROUND: Too many rounds in EVS state; swallowing token; state:<b=
r>...<br>2017-01-31 10:39:42 GMT Prot_handle_token: BUG WORKAROUND: Too man=
y rounds in EVS state; swallowing token; state:<br>2017-01-31 10:39:42 GMT =
=C2=A0=C2=A0=C2=A0 Aru:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=
=A0=C2=A0=C2=A0=C2=A0=C2=A0 0<br>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=C2=A0=
My_aru:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 3366<b=
r>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=C2=A0 Highest_seq:=C2=A0=C2=A0=C2=A0=
=C2=A0=C2=A0 0<br>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=C2=A0 Highest_fifo_s=
eq: 0<br>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=C2=A0 Last_discarded:=C2=A0=
=C2=A0 0<br>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=C2=A0 Last_delivered:=C2=
=A0=C2=A0 0<br>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=C2=A0 Last_seq:=C2=A0=
=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 3366<br>2017-01-31 10:39:42 GMT =
=C2=A0=C2=A0=C2=A0 Token_rounds:=C2=A0=C2=A0=C2=A0=C2=A0 501<br>2017-01-31 =
10:39:42 GMT Last Token:<br>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=C2=A0 type=
:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 0=
x80050080<br>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=C2=A0 transmiter_id:=C2=
=A0=C2=A0=C2=A0 -1726901244<br>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=C2=A0 s=
eq:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=
=C2=A0 0<br>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=C2=A0 proc_id:=C2=A0=C2=A0=
=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 -1726901244<br>2017-01-31 10:39:=
42 GMT =C2=A0=C2=A0=C2=A0 aru:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=
=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 0<br>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=
=C2=A0 aru_last_id:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 -1726901179<br>2017-01-31=
10:39:42 GMT =C2=A0=C2=A0=C2=A0 flow_control:=C2=A0=C2=A0=C2=A0=C2=A0 33<b=
r>2017-01-31 10:39:42 GMT =C2=A0=C2=A0=C2=A0 rtr_len:=C2=A0=C2=A0=C2=A0=C2=
=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 144<br>2017-01-31 10:39:42 GMT =C2=A0=C2=
=A0=C2=A0 conf_hash:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 -2016107656<=
br>2017-01-31 10:39:57 GMT Prot_handle_token: BUG WORKAROUND: Too many roun=
ds in EVS state; swallowing token; state:<br>2017-01-31 10:39:57 GMT =C2=A0=
=C2=A0=C2=A0 Aru:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=
=A0=C2=A0=C2=A0=C2=A0 0<br>2017-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 My_ar=
u:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 3366<br>2017=
-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 Highest_seq:=C2=A0=C2=A0=C2=A0=C2=A0=
=C2=A0 0<br>2017-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 Highest_fifo_seq: 0<=
br>2017-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 Last_discarded:=C2=A0=C2=A0 0=
<br>2017-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 Last_delivered:=C2=A0=C2=A0 =
0<br>2017-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 Last_seq:=C2=A0=C2=A0=C2=A0=
=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 3366<br>2017-01-31 10:39:57 GMT =C2=A0=C2=A0=
=C2=A0 Token_rounds:=C2=A0=C2=A0=C2=A0=C2=A0 501<br>2017-01-31 10:39:57 GMT=
Last Token:<br>2017-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 type:=C2=A0=C2=
=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 0x80050080<=
br>2017-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 transmiter_id:=C2=A0=C2=A0=C2=
=A0 -1726901244<br>2017-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 seq:=C2=A0=C2=
=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 0<br>=
2017-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 proc_id:=C2=A0=C2=A0=C2=A0=C2=A0=
=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 -1726901244<br>2017-01-31 10:39:57 GMT =C2=
=A0=C2=A0=C2=A0 aru:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=
=C2=A0=C2=A0=C2=A0=C2=A0 0<br>2017-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 ar=
u_last_id:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 -1726901179<br>2017-01-31 10:39:57=
GMT =C2=A0=C2=A0=C2=A0 flow_control:=C2=A0=C2=A0=C2=A0=C2=A0 33<br>2017-01=
-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 rtr_len:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=
=C2=A0=C2=A0=C2=A0=C2=A0 144<br>2017-01-31 10:39:57 GMT =C2=A0=C2=A0=C2=A0 =
conf_hash:=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 -2016107656<br><br><br=
></div></div>
--001a1136f6ba7a6bfd0547747ba0--
--===============0730599925976401886==
Content-Type: text/plain; charset="us-ascii"
MIME-Version: 1.0
Content-Transfer-Encoding: 7bit
Content-Disposition: inline
_______________________________________________
Spread-users mailing list
[email protected]
http://lists.spread.org/mailman/listinfo/spread-users
--===============0730599925976401886==--