Re: [jira] [Created] (MESOS-4635) CoordinatorTest.AppendDiscarded is flaky

[Benjamin Mahler <bm...@apache.org>]

+jie

Jie, have you seen this?

On Wed, Feb 10, 2016 at 7:46 AM, Greg Mann (JIRA) <ji...@apache.org> wrote:

> Greg Mann created MESOS-4635:
> --------------------------------
>
>              Summary: CoordinatorTest.AppendDiscarded is flaky
>                  Key: MESOS-4635
>                  URL: https://issues.apache.org/jira/browse/MESOS-4635
>              Project: Mesos
>           Issue Type: Bug
>     Affects Versions: 0.27.0
>          Environment: Ubuntu 14.04 with clang
>             Reporter: Greg Mann
>
>
> Just saw this failure on the ASF Jenkins CI:
>
> {code}
> [ RUN      ] CoordinatorTest.AppendDiscarded
> I0210 09:34:39.188288 31550 leveldb.cpp:174] Opened db in 2.043145ms
> I0210 09:34:39.189136 31550 leveldb.cpp:181] Compacted db in 811003ns
> I0210 09:34:39.189182 31550 leveldb.cpp:196] Created db iterator in 27506ns
> I0210 09:34:39.189208 31550 leveldb.cpp:202] Seeked to beginning of db in
> 10415ns
> I0210 09:34:39.189224 31550 leveldb.cpp:271] Iterated through 0 keys in
> the db in 8230ns
> I0210 09:34:39.189260 31550 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0210 09:34:39.190004 31577 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 471666ns
> I0210 09:34:39.190028 31577 replica.cpp:320] Persisted replica status to
> VOTING
> I0210 09:34:39.192812 31550 leveldb.cpp:174] Opened db in 2.215995ms
> I0210 09:34:39.193488 31550 leveldb.cpp:181] Compacted db in 660244ns
> I0210 09:34:39.193528 31550 leveldb.cpp:196] Created db iterator in 23068ns
> I0210 09:34:39.193554 31550 leveldb.cpp:202] Seeked to beginning of db in
> 10451ns
> I0210 09:34:39.193570 31550 leveldb.cpp:271] Iterated through 0 keys in
> the db in 7996ns
> I0210 09:34:39.193603 31550 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0210 09:34:39.194510 31569 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 393072ns
> I0210 09:34:39.194537 31569 replica.cpp:320] Persisted replica status to
> VOTING
> I0210 09:34:39.196895 31550 leveldb.cpp:174] Opened db in 1.804552ms
> I0210 09:34:39.198554 31550 leveldb.cpp:181] Compacted db in 1.642208ms
> I0210 09:34:39.198593 31550 leveldb.cpp:196] Created db iterator in 19381ns
> I0210 09:34:39.198633 31550 leveldb.cpp:202] Seeked to beginning of db in
> 35677ns
> I0210 09:34:39.198673 31550 leveldb.cpp:271] Iterated through 1 keys in
> the db in 26460ns
> I0210 09:34:39.198703 31550 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0210 09:34:39.200898 31550 leveldb.cpp:174] Opened db in 2.09532ms
> I0210 09:34:39.202641 31550 leveldb.cpp:181] Compacted db in 1.7251ms
> I0210 09:34:39.202697 31550 leveldb.cpp:196] Created db iterator in 39337ns
> I0210 09:34:39.202836 31550 leveldb.cpp:202] Seeked to beginning of db in
> 34194ns
> I0210 09:34:39.202965 31550 leveldb.cpp:271] Iterated through 1 keys in
> the db in 39383ns
> I0210 09:34:39.203088 31550 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0210 09:34:39.204413 31573 replica.cpp:493] Replica received implicit
> promise request from (2636)@172.17.0.2:58132 with proposal 1
> I0210 09:34:39.204494 31572 replica.cpp:493] Replica received implicit
> promise request from (2637)@172.17.0.2:58132 with proposal 1
> I0210 09:34:39.204854 31573 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 417201ns
> I0210 09:34:39.204880 31573 replica.cpp:342] Persisted promised to 1
> I0210 09:34:39.205060 31572 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 471800ns
> I0210 09:34:39.205087 31572 replica.cpp:342] Persisted promised to 1
> I0210 09:34:39.205577 31582 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0210 09:34:39.206393 31579 replica.cpp:388] Replica received explicit
> promise request from (2638)@172.17.0.2:58132 for position 0 with proposal
> 2
> I0210 09:34:39.206569 31578 replica.cpp:388] Replica received explicit
> promise request from (2639)@172.17.0.2:58132 for position 0 with proposal
> 2
> I0210 09:34:39.206840 31579 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 335263ns
> I0210 09:34:39.206881 31579 replica.cpp:712] Persisted action at 0
> I0210 09:34:39.207236 31578 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 442481ns
> I0210 09:34:39.207258 31578 replica.cpp:712] Persisted action at 0
> I0210 09:34:39.208065 31577 replica.cpp:537] Replica received write
> request for position 0 from (2640)@172.17.0.2:58132
> I0210 09:34:39.208160 31568 replica.cpp:537] Replica received write
> request for position 0 from (2641)@172.17.0.2:58132
> I0210 09:34:39.208206 31568 leveldb.cpp:436] Reading position from leveldb
> took 67699ns
> I0210 09:34:39.208117 31577 leveldb.cpp:436] Reading position from leveldb
> took 225587ns
> I0210 09:34:39.208647 31568 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 374594ns
> I0210 09:34:39.208652 31577 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 317146ns
> I0210 09:34:39.208673 31568 replica.cpp:712] Persisted action at 0
> I0210 09:34:39.208683 31577 replica.cpp:712] Persisted action at 0
> I0210 09:34:39.209205 31583 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0210 09:34:39.209290 31579 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0210 09:34:39.209755 31579 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 450007ns
> I0210 09:34:39.209781 31579 replica.cpp:712] Persisted action at 0
> I0210 09:34:39.209799 31579 replica.cpp:697] Replica learned NOP action at
> position 0
> I0210 09:34:39.210258 31583 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 1.039277ms
> I0210 09:34:39.210302 31583 replica.cpp:712] Persisted action at 0
> I0210 09:34:39.210327 31583 replica.cpp:697] Replica learned NOP action at
> position 0
> I0210 09:34:39.210742 31568 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0210 09:34:39.211360 31576 replica.cpp:537] Replica received write
> request for position 1 from (2642)@172.17.0.2:58132
> I0210 09:34:39.211781 31576 leveldb.cpp:341] Persisting action (27 bytes)
> to leveldb took 413428ns
> I0210 09:34:39.211823 31576 replica.cpp:712] Persisted action at 1
> ../../src/tests/log_tests.cpp:781: Failure
> (appending).failure(): Coordinator is currently writing
> [  FAILED  ] CoordinatorTest.AppendDiscarded (27 ms)
> {code}
>
>
>
> --
> This message was sent by Atlassian JIRA
> (v6.3.4#6332)
>