See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2374/changes>
Changes: [bmahler] Added a Version class to stout. ------------------------------------------ [...truncated 58686 lines...] I0916 23:01:05.386067 21051 replica.cpp:676] Persisted action at 0 I0916 23:01:05.386090 21051 replica.cpp:661] Replica learned NOP action at position 0 I0916 23:01:05.083233 21042 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 121548ns I0916 23:01:05.386147 21042 replica.cpp:676] Persisted action at 0 I0916 23:01:05.386168 21042 replica.cpp:661] Replica learned NOP action at position 0 I0916 23:01:05.386436 21040 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1 I0916 23:01:05.386842 21048 replica.cpp:508] Replica received write request for position 1 I0916 23:01:05.386904 21040 replica.cpp:508] Replica received write request for position 1 I0916 23:01:05.387473 21048 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 603894ns I0916 23:01:05.387475 21040 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 538731ns I0916 23:01:05.387511 21048 replica.cpp:676] Persisted action at 1 I0916 23:01:05.387524 21040 replica.cpp:676] Persisted action at 1 I0916 23:01:05.387969 21043 replica.cpp:655] Replica received learned notice for position 1 I0916 23:01:05.387998 21053 replica.cpp:655] Replica received learned notice for position 1 I0916 23:01:05.388157 21043 leveldb.cpp:343] Persisting action (29 bytes) to leveldb took 165034ns I0916 23:01:05.388177 21043 replica.cpp:676] Persisted action at 1 I0916 23:01:05.388188 21043 replica.cpp:661] Replica learned APPEND action at position 1 I0916 23:01:05.388212 21053 leveldb.cpp:343] Persisting action (29 bytes) to leveldb took 190343ns I0916 23:01:05.388236 21053 replica.cpp:676] Persisted action at 1 I0916 23:01:05.388245 21053 replica.cpp:661] Replica learned APPEND action at position 1 I0916 23:01:05.390319 21026 leveldb.cpp:176] Opened db in 1.951015ms I0916 23:01:05.391607 21026 leveldb.cpp:183] Compacted db in 1.261904ms I0916 23:01:05.391633 21026 leveldb.cpp:198] Created db iterator in 4244ns I0916 23:01:05.391650 21026 leveldb.cpp:204] Seeked to beginning of db in 7069ns I0916 23:01:05.391669 21026 leveldb.cpp:273] Iterated through 1 keys in the db in 8220ns I0916 23:01:05.391685 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:05.392328 21047 replica.cpp:474] Replica received implicit promise request with proposal 1 I0916 23:01:05.392354 21047 replica.cpp:479] Replica denying promise request with proposal 1 I0916 23:01:05.392408 21043 replica.cpp:474] Replica received implicit promise request with proposal 1 I0916 23:01:05.392943 21043 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 510019ns I0916 23:01:05.392963 21043 replica.cpp:342] Persisted promised to 1 I0916 23:01:05.393698 21055 replica.cpp:474] Replica received implicit promise request with proposal 2 I0916 23:01:05.393705 21045 replica.cpp:474] Replica received implicit promise request with proposal 2 I0916 23:01:05.394114 21045 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 380162ns I0916 23:01:05.394114 21055 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 390574ns I0916 23:01:05.394136 21045 replica.cpp:342] Persisted promised to 2 I0916 23:01:05.394152 21055 replica.cpp:342] Persisted promised to 2 I0916 23:01:05.394424 21053 coordinator.cpp:230] Coordinator attemping to fill missing position I0916 23:01:05.395113 21046 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 3 I0916 23:01:05.395136 21055 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 3 I0916 23:01:05.395153 21046 leveldb.cpp:438] Reading position from leveldb took 16040ns I0916 23:01:05.395339 21055 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 181686ns I0916 23:01:05.395359 21055 replica.cpp:676] Persisted action at 0 I0916 23:01:05.395392 21046 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 215312ns I0916 23:01:05.395414 21046 replica.cpp:676] Persisted action at 0 I0916 23:01:05.395781 21047 replica.cpp:655] Replica received learned notice for position 0 I0916 23:01:05.395792 21049 replica.cpp:655] Replica received learned notice for position 0 I0916 23:01:05.395972 21047 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 163947ns I0916 23:01:05.395992 21047 replica.cpp:676] Persisted action at 0 I0916 23:01:05.396003 21047 replica.cpp:661] Replica learned NOP action at position 0 I0916 23:01:05.396059 21049 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 198163ns I0916 23:01:05.396105 21049 replica.cpp:676] Persisted action at 0 I0916 23:01:05.396118 21049 replica.cpp:661] Replica learned NOP action at position 0 I0916 23:01:05.396561 21053 replica.cpp:375] Replica received explicit promise request for position 1 with proposal 3 I0916 23:01:05.396602 21053 leveldb.cpp:438] Reading position from leveldb took 17412ns I0916 23:01:05.396661 21048 replica.cpp:375] Replica received explicit promise request for position 1 with proposal 3 I0916 23:01:05.396785 21053 leveldb.cpp:343] Persisting action (29 bytes) to leveldb took 158375ns I0916 23:01:05.396806 21053 replica.cpp:676] Persisted action at 1 I0916 23:01:05.396877 21048 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 189711ns I0916 23:01:05.396903 21048 replica.cpp:676] Persisted action at 1 I0916 23:01:05.397061 21049 replica.cpp:655] Replica received learned notice for position 1 I0916 23:01:05.397063 21042 replica.cpp:655] Replica received learned notice for position 1 I0916 23:01:05.397454 21042 leveldb.cpp:343] Persisting action (29 bytes) to leveldb took 353980ns I0916 23:01:05.397455 21049 leveldb.cpp:343] Persisting action (29 bytes) to leveldb took 366829ns I0916 23:01:05.397477 21042 replica.cpp:676] Persisted action at 1 I0916 23:01:05.397488 21042 replica.cpp:661] Replica learned APPEND action at position 1 I0916 23:01:05.397490 21049 replica.cpp:676] Persisted action at 1 I0916 23:01:05.397511 21049 replica.cpp:661] Replica learned APPEND action at position 1 I0916 23:01:05.397948 21053 leveldb.cpp:438] Reading position from leveldb took 18632ns [ OK ] CoordinatorTest.Fill (338 ms) [ RUN ] CoordinatorTest.ElectWithClockPaused Using temporary directory '/tmp/CoordinatorTest_ElectWithClockPaused_XPakdP' I0916 23:01:05.401895 21026 leveldb.cpp:176] Opened db in 2.126108ms I0916 23:01:05.402295 21026 leveldb.cpp:183] Compacted db in 372807ns I0916 23:01:05.402319 21026 leveldb.cpp:198] Created db iterator in 3347ns I0916 23:01:05.402335 21026 leveldb.cpp:204] Seeked to beginning of db in 575ns I0916 23:01:05.402346 21026 leveldb.cpp:273] Iterated through 0 keys in the db in 210ns I0916 23:01:05.402362 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:05.403061 21041 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 439929ns I0916 23:01:05.403081 21041 replica.cpp:320] Persisted replica status to VOTING I0916 23:01:05.405195 21026 leveldb.cpp:176] Opened db in 1.857658ms I0916 23:01:05.405761 21026 leveldb.cpp:183] Compacted db in 539011ns I0916 23:01:05.405781 21026 leveldb.cpp:198] Created db iterator in 2735ns I0916 23:01:05.405791 21026 leveldb.cpp:204] Seeked to beginning of db in 475ns I0916 23:01:05.405799 21026 leveldb.cpp:273] Iterated through 0 keys in the db in 195ns I0916 23:01:05.405814 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:05.406510 21046 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 452694ns I0916 23:01:05.406533 21046 replica.cpp:320] Persisted replica status to VOTING I0916 23:01:05.408746 21026 leveldb.cpp:176] Opened db in 1.965229ms I0916 23:01:05.410229 21026 leveldb.cpp:183] Compacted db in 1.454674ms I0916 23:01:05.410260 21026 leveldb.cpp:198] Created db iterator in 4550ns I0916 23:01:05.410284 21026 leveldb.cpp:204] Seeked to beginning of db in 7785ns I0916 23:01:05.410303 21026 leveldb.cpp:273] Iterated through 1 keys in the db in 8398ns I0916 23:01:05.410320 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:10.189376 21026 leveldb.cpp:176] Opened db in 2.600519ms I0916 23:01:10.191403 21026 leveldb.cpp:183] Compacted db in 1.996275ms I0916 23:01:10.191436 21026 leveldb.cpp:198] Created db iterator in 4378ns I0916 23:01:10.191457 21026 leveldb.cpp:204] Seeked to beginning of db in 7234ns I0916 23:01:10.191476 21026 leveldb.cpp:273] Iterated through 1 keys in the db in 8102ns I0916 23:01:10.191493 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:10.191894 21049 replica.cpp:474] Replica received implicit promise request with proposal 1 I0916 23:01:10.191937 21044 replica.cpp:474] Replica received implicit promise request with proposal 1 I0916 23:01:10.192438 21049 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 517437ns I0916 23:01:10.192461 21049 replica.cpp:342] Persisted promised to 1 I0916 23:01:10.192517 21044 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 555333ns I0916 23:01:10.192543 21044 replica.cpp:342] Persisted promised to 1 I0916 23:01:10.193327 21055 coordinator.cpp:230] Coordinator attemping to fill missing position I0916 23:01:10.194192 21044 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2 I0916 23:01:10.194272 21049 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2 I0916 23:01:10.195019 21049 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 721630ns I0916 23:01:10.195019 21044 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 799669ns I0916 23:01:10.195044 21049 replica.cpp:676] Persisted action at 0 I0916 23:01:10.195061 21044 replica.cpp:676] Persisted action at 0 I0916 23:01:10.195617 21053 replica.cpp:508] Replica received write request for position 0 I0916 23:01:10.195672 21053 leveldb.cpp:438] Reading position from leveldb took 13897ns I0916 23:01:10.195801 21051 replica.cpp:508] Replica received write request for position 0 I0916 23:01:10.195837 21051 leveldb.cpp:438] Reading position from leveldb took 12352ns I0916 23:01:10.195870 21053 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 177322ns I0916 23:01:10.195894 21053 replica.cpp:676] Persisted action at 0 I0916 23:01:10.196024 21051 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 161185ns I0916 23:01:10.196045 21051 replica.cpp:676] Persisted action at 0 I0916 23:01:10.196480 21043 replica.cpp:655] Replica received learned notice for position 0 I0916 23:01:10.196480 21046 replica.cpp:655] Replica received learned notice for position 0 I0916 23:01:10.196704 21046 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 173626ns I0916 23:01:10.196750 21046 replica.cpp:676] Persisted action at 0 I0916 23:01:10.196754 21043 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 237051ns I0916 23:01:10.196761 21046 replica.cpp:661] Replica learned NOP action at position 0 I0916 23:01:10.196777 21043 replica.cpp:676] Persisted action at 0 I0916 23:01:10.196789 21043 replica.cpp:661] Replica learned NOP action at position 0 [ OK ] CoordinatorTest.ElectWithClockPaused (4803 ms) [ RUN ] CoordinatorTest.FillInconsistent Using temporary directory '/tmp/CoordinatorTest_FillInconsistent_ZKE6XE' [ OK ] CoordinatorTest.FillInconsistent (0 ms) [----------] 21 tests from CoordinatorTest (19050 ms total) [----------] 4 tests from ReplicaTest [ RUN ] ReplicaTest.Promise Using temporary directory '/tmp/ReplicaTest_Promise_NBZUIu' I0916 23:01:10.205260 21026 leveldb.cpp:176] Opened db in 2.150092ms I0916 23:01:10.205966 21026 leveldb.cpp:183] Compacted db in 677731ns I0916 23:01:10.205986 21026 leveldb.cpp:198] Created db iterator in 2735ns I0916 23:01:10.205996 21026 leveldb.cpp:204] Seeked to beginning of db in 472ns I0916 23:01:10.206006 21026 leveldb.cpp:273] Iterated through 0 keys in the db in 198ns I0916 23:01:10.206019 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:10.206781 21054 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 583987ns I0916 23:01:10.206801 21054 replica.cpp:320] Persisted replica status to VOTING I0916 23:01:10.209231 21026 leveldb.cpp:176] Opened db in 2.210186ms I0916 23:01:10.211395 21026 leveldb.cpp:183] Compacted db in 2.13344ms I0916 23:01:10.211428 21026 leveldb.cpp:198] Created db iterator in 4390ns I0916 23:01:10.211451 21026 leveldb.cpp:204] Seeked to beginning of db in 7710ns I0916 23:01:10.211470 21026 leveldb.cpp:273] Iterated through 1 keys in the db in 8295ns I0916 23:01:10.211487 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:10.211709 21054 replica.cpp:474] Replica received implicit promise request with proposal 2 I0916 23:01:10.212260 21054 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 526905ns I0916 23:01:10.212280 21054 replica.cpp:342] Persisted promised to 2 I0916 23:01:10.212738 21046 replica.cpp:474] Replica received implicit promise request with proposal 1 I0916 23:01:10.212757 21046 replica.cpp:479] Replica denying promise request with proposal 1 I0916 23:01:10.213058 21047 replica.cpp:474] Replica received implicit promise request with proposal 3 I0916 23:01:10.213518 21047 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 433010ns I0916 23:01:10.213537 21047 replica.cpp:342] Persisted promised to 3 [ OK ] ReplicaTest.Promise (12 ms) [ RUN ] ReplicaTest.Restore Using temporary directory '/tmp/ReplicaTest_Restore_Hpbyvk' I0916 23:01:10.216367 21026 leveldb.cpp:176] Opened db in 2.043673ms I0916 23:01:10.216773 21026 leveldb.cpp:183] Compacted db in 379902ns I0916 23:01:10.216794 21026 leveldb.cpp:198] Created db iterator in 2885ns I0916 23:01:10.216804 21026 leveldb.cpp:204] Seeked to beginning of db in 500ns I0916 23:01:10.216814 21026 leveldb.cpp:273] Iterated through 0 keys in the db in 200ns I0916 23:01:10.216828 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:10.217572 21051 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 469143ns I0916 23:01:10.217592 21051 replica.cpp:320] Persisted replica status to VOTING I0916 23:01:10.219882 21026 leveldb.cpp:176] Opened db in 1.930543ms I0916 23:01:10.221320 21026 leveldb.cpp:183] Compacted db in 1.410263ms I0916 23:01:10.221349 21026 leveldb.cpp:198] Created db iterator in 3975ns I0916 23:01:10.221366 21026 leveldb.cpp:204] Seeked to beginning of db in 7110ns I0916 23:01:10.221385 21026 leveldb.cpp:273] Iterated through 1 keys in the db in 8097ns I0916 23:01:10.221401 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:10.221575 21049 replica.cpp:474] Replica received implicit promise request with proposal 1 I0916 23:01:10.222064 21049 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 460628ns I0916 23:01:10.222082 21049 replica.cpp:342] Persisted promised to 1 I0916 23:01:10.222256 21051 replica.cpp:508] Replica received write request for position 1 I0916 23:01:10.222446 21051 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 160079ns I0916 23:01:10.222465 21051 replica.cpp:676] Persisted action at 1 I0916 23:01:10.222645 21046 leveldb.cpp:438] Reading position from leveldb took 21315ns I0916 23:01:10.224990 21026 leveldb.cpp:176] Opened db in 2.097744ms I0916 23:01:10.226565 21026 leveldb.cpp:183] Compacted db in 1.548383ms I0916 23:01:10.226593 21026 leveldb.cpp:198] Created db iterator in 4283ns I0916 23:01:10.226610 21026 leveldb.cpp:204] Seeked to beginning of db in 7267ns I0916 23:01:10.226636 21026 leveldb.cpp:273] Iterated through 2 keys in the db in 14932ns I0916 23:01:10.226655 21026 replica.cpp:741] Replica recovered with log positions 0 -> 1 with 1 holes and 1 unlearned I0916 23:01:10.226730 21047 leveldb.cpp:438] Reading position from leveldb took 29797ns [ OK ] ReplicaTest.Restore (13 ms) [ RUN ] ReplicaTest.NonVoting Using temporary directory '/tmp/ReplicaTest_NonVoting_vDwNJa' I0916 23:01:10.386850 21026 leveldb.cpp:176] Opened db in 2.50423ms I0916 23:01:10.387466 21026 leveldb.cpp:183] Compacted db in 587774ns I0916 23:01:10.387492 21026 leveldb.cpp:198] Created db iterator in 3455ns I0916 23:01:10.387508 21026 leveldb.cpp:204] Seeked to beginning of db in 540ns I0916 23:01:10.387518 21026 leveldb.cpp:273] Iterated through 0 keys in the db in 210ns I0916 23:01:10.387533 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:10.387671 21047 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status I0916 23:01:10.397832 21053 replica.cpp:503] Replica ignoring write request as it is in EMPTY status [ OK ] ReplicaTest.NonVoting (24 ms) [ RUN ] ReplicaTest.Append Using temporary directory '/tmp/ReplicaTest_Append_PfpX10' I0916 23:01:10.410902 21026 leveldb.cpp:176] Opened db in 2.480258ms I0916 23:01:10.411463 21026 leveldb.cpp:183] Compacted db in 531801ns I0916 23:01:10.411485 21026 leveldb.cpp:198] Created db iterator in 3215ns I0916 23:01:10.411501 21026 leveldb.cpp:204] Seeked to beginning of db in 550ns I0916 23:01:10.411512 21026 leveldb.cpp:273] Iterated through 0 keys in the db in 188ns I0916 23:01:10.411528 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:10.412348 21050 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 632675ns I0916 23:01:10.412370 21050 replica.cpp:320] Persisted replica status to VOTING I0916 23:01:10.415046 21026 leveldb.cpp:176] Opened db in 2.365599ms I0916 23:01:10.416496 21026 leveldb.cpp:183] Compacted db in 1.42057ms I0916 23:01:10.416522 21026 leveldb.cpp:198] Created db iterator in 4235ns I0916 23:01:10.416542 21026 leveldb.cpp:204] Seeked to beginning of db in 7197ns I0916 23:01:10.416559 21026 leveldb.cpp:273] Iterated through 1 keys in the db in 8095ns I0916 23:01:10.416576 21026 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0916 23:01:10.416733 21048 replica.cpp:474] Replica received implicit promise request with proposal 1 I0916 23:01:10.417361 21048 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 599444ns I0916 23:01:10.417383 21048 replica.cpp:342] Persisted promised to 1 I0916 23:01:10.417697 21044 replica.cpp:508] Replica received write request for position 1 I0916 23:01:10.417881 21044 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 157842ns I0916 23:01:10.417899 21044 replica.cpp:676] Persisted action at 1 I0916 23:01:10.418081 21044 leveldb.cpp:438] Reading position from leveldb took 21617ns [ OK ] ReplicaTest.Append (11 ms) [----------] 4 tests from ReplicaTest (217 ms total) [----------] Global test environment tear-down [==========] 365 tests from 59 test cases ran. (379816 ms total) [ PASSED ] 363 tests. [ FAILED ] 2 tests, listed below: [ FAILED ] HealthCheckTest.HealthStatusChange [ FAILED ] Strict/RegistrarTest.remove/1, where GetParam() = true 2 FAILED TESTS YOU HAVE 5 DISABLED TESTS make[3]: *** [check-local] Error 1 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[2]: *** [check-am] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make[1]: *** [check] Error 2 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'> make: *** [check-recursive] Error 1 Test finished Exit status: 2 Build step 'Execute shell' marked build as failure