Re: Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #2277

[Benjamin Mahler <be...@gmail.com>]

Seen this issue in a few of the slave recovery tests, will push a fix.


On Wed, Jul 30, 2014 at 11:21 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:

> See <
> https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/2277/changes
> >
>
> Changes:
>
> [yujie.jay] Adjusted the order in which filters are added/removed to
> tolerate slave
>
> ------------------------------------------
> [...truncated 60736 lines...]
> I0731 06:21:18.372212 15017 leveldb.cpp:343] Persisting action (18 bytes)
> to leveldb took 19.936017ms
> I0731 06:21:18.372248 15017 leveldb.cpp:401] Deleting ~2 keys from leveldb
> took 22408ns
> I0731 06:21:18.372258 15017 replica.cpp:676] Persisted action at 4
> I0731 06:21:18.372267 15017 replica.cpp:661] Replica learned TRUNCATE
> action at position 4
> I0731 06:21:18.372515 15017 log.cpp:680] Attempting to append 1092 bytes
> to the log
> I0731 06:21:18.372555 15017 coordinator.cpp:340] Coordinator attempting to
> write APPEND action at position 5
> I0731 06:21:18.372774 15017 replica.cpp:508] Replica received write
> request for position 5
> I0731 06:21:18.372864 15019 sched.cpp:747] Stopping framework
> '20140731-062118-453759884-45430-14840-0000'
> I0731 06:21:18.372928 15024 master.cpp:1537] Asked to unregister framework
> 20140731-062118-453759884-45430-14840-0000
> I0731 06:21:18.372938 15024 master.cpp:3826] Removing framework
> 20140731-062118-453759884-45430-14840-0000
> I0731 06:21:18.373008 15024 master.hpp:826] Removing offer
> 20140731-062118-453759884-45430-14840-0 with resources cpus(*):2;
> mem(*):1024; disk(*):127026; ports(*):[31000-32000] on slave
> 20140731-062118-453759884-45430-14840-0 (hemera.apache.org)
> I0731 06:21:18.373090 15024 hierarchical_allocator_process.hpp:407]
> Deactivated framework 20140731-062118-453759884-45430-14840-0000
> I0731 06:21:18.373163 15024 hierarchical_allocator_process.hpp:635]
> Recovered cpus(*):2; mem(*):1024; disk(*):127026; ports(*):[31000-32000]
> (total allocatable: cpus(*):2; mem(*):1024; disk(*):127026;
> ports(*):[31000-32000]) on slave 20140731-062118-453759884-45430-14840-0
> from framework 20140731-062118-453759884-45430-14840-0000
> I0731 06:21:18.373205 15024 hierarchical_allocator_process.hpp:362]
> Removed framework 20140731-062118-453759884-45430-14840-0000
> I0731 06:21:18.373226 15024 slave.cpp:1407] Asked to shut down framework
> 20140731-062118-453759884-45430-14840-0000 by master@140.211.11.27:45430
> W0731 06:21:18.373236 15024 slave.cpp:1422] Cannot shut down unknown
> framework 20140731-062118-453759884-45430-14840-0000
> I0731 06:21:18.373348 15024 master.cpp:626] Master terminating
> I0731 06:21:18.385665 15017 leveldb.cpp:343] Persisting action (1111
> bytes) to leveldb took 12.87577ms
> I0731 06:21:18.385743 15017 replica.cpp:676] Persisted action at 5
> I0731 06:21:18.401689 15024 slave.cpp:2332] master@140.211.11.27:45430
> exited
> W0731 06:21:18.401705 15024 slave.cpp:2335] Master disconnected! Waiting
> for a new master to be elected
> I0731 06:21:18.401717 15024 slave.cpp:2332] master@140.211.11.27:45430
> exited
> W0731 06:21:18.401726 15024 slave.cpp:2335] Master disconnected! Waiting
> for a new master to be elected
> I0731 06:21:18.401737 15024 slave.cpp:2332] master@140.211.11.27:45430
> exited
> W0731 06:21:18.401746 15024 slave.cpp:2335] Master disconnected! Waiting
> for a new master to be elected
> I0731 06:21:18.401757 15024 slave.cpp:2332] master@140.211.11.27:45430
> exited
> W0731 06:21:18.401764 15024 slave.cpp:2335] Master disconnected! Waiting
> for a new master to be elected
> I0731 06:21:18.401777 15024 slave.cpp:2332] master@140.211.11.27:45430
> exited
> W0731 06:21:18.401783 15024 slave.cpp:2335] Master disconnected! Waiting
> for a new master to be elected
> I0731 06:21:18.401795 15024 slave.cpp:2332] master@140.211.11.27:45430
> exited
> W0731 06:21:18.401803 15024 slave.cpp:2335] Master disconnected! Waiting
> for a new master to be elected
> I0731 06:21:18.401815 15024 slave.cpp:2332] master@140.211.11.27:45430
> exited
> W0731 06:21:18.401823 15024 slave.cpp:2335] Master disconnected! Waiting
> for a new master to be elected
> I0731 06:21:18.401834 15024 slave.cpp:2332] master@140.211.11.27:45430
> exited
> W0731 06:21:18.401841 15024 slave.cpp:2335] Master disconnected! Waiting
> for a new master to be elected
> I0731 06:21:18.401854 15024 slave.cpp:2332] master@140.211.11.27:45430
> exited
> W0731 06:21:18.401860 15024 slave.cpp:2335] Master disconnected! Waiting
> for a new master to be elected
> I0731 06:21:18.401872 15024 slave.cpp:2332] master@140.211.11.27:45430
> exited
> W0731 06:21:18.401880 15024 slave.cpp:2335] Master disconnected! Waiting
> for a new master to be elected
> I0731 06:21:18.405755 15017 slave.cpp:972] Will retry registration in
> 77.775373ms if necessary
> I0731 06:21:18.406038 15017 replica.cpp:655] Replica received learned
> notice for position 5
> I0731 06:21:18.409749 15000 slave.cpp:972] Will retry registration in
> 23.352753ms if necessary
> I0731 06:21:18.409802 15000 slave.cpp:972] Will retry registration in
> 27.086834ms if necessary
> I0731 06:21:18.409842 15000 slave.cpp:972] Will retry registration in
> 42.935941ms if necessary
> I0731 06:21:18.417685 15018 slave.cpp:972] Will retry registration in
> 132.768753ms if necessary
> I0731 06:21:18.419458 15017 leveldb.cpp:343] Persisting action (1113
> bytes) to leveldb took 13.347198ms
> I0731 06:21:18.419528 15017 replica.cpp:676] Persisted action at 5
> I0731 06:21:18.419582 15017 replica.cpp:661] Replica learned APPEND action
> at position 5
> I0731 06:21:18.425699 14840 slave.cpp:485] Slave terminating
> I0731 06:21:18.430366 14840 slave.cpp:485] Slave terminating
> I0731 06:21:18.431041 14840 slave.cpp:485] Slave terminating
> I0731 06:21:18.431717 14840 slave.cpp:485] Slave terminating
> I0731 06:21:18.432378 14840 slave.cpp:485] Slave terminating
> I0731 06:21:18.436923 14840 slave.cpp:485] Slave terminating
> I0731 06:21:18.436970 15016 slave.cpp:972] Will retry registration in
> 92.266549ms if necessary
> I0731 06:21:18.437124 15023 slave.cpp:972] Will retry registration in
> 121.742796ms if necessary
> I0731 06:21:18.445706 15021 slave.cpp:972] Will retry registration in
> 151.010495ms if necessary
> I0731 06:21:18.461736 14840 slave.cpp:485] Slave terminating
> I0731 06:21:18.473750 14840 slave.cpp:485] Slave terminating
> I0731 06:21:18.485093 14840 slave.cpp:485] Slave terminating
> I0731 06:21:18.515475 15001 slave.cpp:485] Slave terminating
> [       OK ] ResourceOffersTest.ResourceOfferWithMultipleSlaves (492 ms)
> [----------] 8 tests from ResourceOffersTest (3066 ms total)
>
> [----------] 2 tests from LogTest
> [ RUN      ] LogTest.Position
> Using temporary directory '/tmp/LogTest_Position_ENuUQi'
> I0731 06:21:18.565770 14840 leveldb.cpp:176] Opened db in 46.453576ms
> I0731 06:21:18.593652 14840 leveldb.cpp:183] Compacted db in 27.795314ms
> I0731 06:21:18.593732 14840 leveldb.cpp:198] Created db iterator in 4475ns
> I0731 06:21:18.593792 14840 leveldb.cpp:204] Seeked to beginning of db in
> 870ns
> I0731 06:21:18.593847 14840 leveldb.cpp:273] Iterated through 0 keys in
> the db in 364ns
> I0731 06:21:18.593907 14840 replica.cpp:741] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0731 06:21:18.621683 14998 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 16.276828ms
> I0731 06:21:18.621708 14998 replica.cpp:320] Persisted replica status to
> VOTING
> I0731 06:21:18.681762 14840 leveldb.cpp:176] Opened db in 59.68388ms
> I0731 06:21:18.717654 14840 leveldb.cpp:183] Compacted db in 35.805286ms
> I0731 06:21:18.717751 14840 leveldb.cpp:198] Created db iterator in 2921ns
> I0731 06:21:18.717820 14840 leveldb.cpp:204] Seeked to beginning of db in
> 560ns
> I0731 06:21:18.717876 14840 leveldb.cpp:273] Iterated through 0 keys in
> the db in 203ns
> I0731 06:21:18.717937 14840 replica.cpp:741] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0731 06:21:18.745687 14999 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 16.001175ms
> I0731 06:21:18.745708 14999 replica.cpp:320] Persisted replica status to
> VOTING
> I0731 06:21:18.809784 14840 leveldb.cpp:176] Opened db in 59.996199ms
> I0731 06:21:18.869654 14840 leveldb.cpp:183] Compacted db in 59.787424ms
> I0731 06:21:18.869738 14840 leveldb.cpp:198] Created db iterator in 4618ns
> I0731 06:21:18.869808 14840 leveldb.cpp:204] Seeked to beginning of db in
> 8683ns
> I0731 06:21:18.869879 14840 leveldb.cpp:273] Iterated through 1 keys in
> the db in 10597ns
> I0731 06:21:18.869943 14840 replica.cpp:741] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0731 06:21:18.917790 14840 leveldb.cpp:176] Opened db in 47.743962ms
> I0731 06:21:18.989655 14840 leveldb.cpp:183] Compacted db in 71.778443ms
> I0731 06:21:18.989735 14840 leveldb.cpp:198] Created db iterator in 4037ns
> I0731 06:21:18.989797 14840 leveldb.cpp:204] Seeked to beginning of db in
> 7789ns
> I0731 06:21:18.989859 14840 leveldb.cpp:273] Iterated through 1 keys in
> the db in 6960ns
> I0731 06:21:18.989923 14840 replica.cpp:741] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0731 06:21:18.993734 14999 recover.cpp:425] Starting replica recovery
> I0731 06:21:18.993785 14999 recover.cpp:451] Replica is in VOTING status
> I0731 06:21:18.993813 14999 recover.cpp:440] Recover process terminated
> I0731 06:21:18.993875 14999 log.cpp:656] Attempting to start the writer
> I0731 06:21:18.994127 14999 replica.cpp:474] Replica received implicit
> promise request with proposal 1
> I0731 06:21:18.994243 14994 replica.cpp:474] Replica received implicit
> promise request with proposal 1
> I0731 06:21:19.010805 14994 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 16.545078ms
> I0731 06:21:19.010828 14994 replica.cpp:342] Persisted promised to 1
> I0731 06:21:19.013653 14999 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 19.510179ms
> I0731 06:21:19.013672 14999 replica.cpp:342] Persisted promised to 1
> I0731 06:21:19.013784 14999 coordinator.cpp:230] Coordinator attemping to
> fill missing position
> I0731 06:21:19.014053 14999 replica.cpp:375] Replica received explicit
> promise request for position 0 with proposal 2
> I0731 06:21:19.017716 15014 replica.cpp:375] Replica received explicit
> promise request for position 0 with proposal 2
> I0731 06:21:19.031008 15014 leveldb.cpp:343] Persisting action (8 bytes)
> to leveldb took 13.267332ms
> I0731 06:21:19.031034 15014 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.033658 14999 leveldb.cpp:343] Persisting action (8 bytes)
> to leveldb took 19.58481ms
> I0731 06:21:19.033679 14999 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.033877 14999 replica.cpp:508] Replica received write
> request for position 0
> I0731 06:21:19.033896 14999 leveldb.cpp:438] Reading position from leveldb
> took 8798ns
> I0731 06:21:19.034108 15014 replica.cpp:508] Replica received write
> request for position 0
> I0731 06:21:19.034132 15014 leveldb.cpp:438] Reading position from leveldb
> took 10604ns
> I0731 06:21:19.053652 15014 leveldb.cpp:343] Persisting action (14 bytes)
> to leveldb took 19.505384ms
> I0731 06:21:19.053678 15014 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.053655 14999 leveldb.cpp:343] Persisting action (14 bytes)
> to leveldb took 19.745824ms
> I0731 06:21:19.057660 14999 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.057759 14999 replica.cpp:655] Replica received learned
> notice for position 0
> I0731 06:21:19.057819 15014 replica.cpp:655] Replica received learned
> notice for position 0
> I0731 06:21:19.077653 14999 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 19.88005ms
> I0731 06:21:19.077741 14999 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.077790 15014 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 19.956873ms
> I0731 06:21:19.077816 15014 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.077826 15014 replica.cpp:661] Replica learned NOP action at
> position 0
> I0731 06:21:19.077949 15014 log.cpp:672] Writer started with ending
> position 0
> I0731 06:21:19.077798 14999 replica.cpp:661] Replica learned NOP action at
> position 0
> I0731 06:21:19.081692 15009 log.cpp:680] Attempting to append 11 bytes to
> the log
> I0731 06:21:19.085706 15009 coordinator.cpp:340] Coordinator attempting to
> write APPEND action at position 1
> I0731 06:21:19.085923 15009 replica.cpp:508] Replica received write
> request for position 1
> I0731 06:21:19.088106 15018 replica.cpp:508] Replica received write
> request for position 1
> I0731 06:21:19.099246 15018 leveldb.cpp:343] Persisting action (27 bytes)
> to leveldb took 11.122205ms
> I0731 06:21:19.099267 15018 replica.cpp:676] Persisted action at 1
> I0731 06:21:19.101660 15009 leveldb.cpp:343] Persisting action (27 bytes)
> to leveldb took 15.715562ms
> I0731 06:21:19.101685 15009 replica.cpp:676] Persisted action at 1
> I0731 06:21:19.101840 15009 replica.cpp:655] Replica received learned
> notice for position 1
> I0731 06:21:19.105238 15018 replica.cpp:655] Replica received learned
> notice for position 1
> I0731 06:21:19.115360 15018 leveldb.cpp:343] Persisting action (29 bytes)
> to leveldb took 10.10679ms
> I0731 06:21:19.115380 15018 replica.cpp:676] Persisted action at 1
> I0731 06:21:19.115386 15018 replica.cpp:661] Replica learned APPEND action
> at position 1
> I0731 06:21:19.116194 15009 leveldb.cpp:343] Persisting action (29 bytes)
> to leveldb took 14.322513ms
> I0731 06:21:19.116215 15009 replica.cpp:676] Persisted action at 1
> I0731 06:21:19.116224 15009 replica.cpp:661] Replica learned APPEND action
> at position 1
> [       OK ] LogTest.Position (615 ms)
> [ RUN      ] LogTest.WriteRead
> Using temporary directory '/tmp/LogTest_WriteRead_VIeCx3'
> I0731 06:21:19.185766 14840 leveldb.cpp:176] Opened db in 51.378249ms
> I0731 06:21:19.213649 14840 leveldb.cpp:183] Compacted db in 27.860856ms
> I0731 06:21:19.213672 14840 leveldb.cpp:198] Created db iterator in 2428ns
> I0731 06:21:19.213680 14840 leveldb.cpp:204] Seeked to beginning of db in
> 412ns
> I0731 06:21:19.213686 14840 leveldb.cpp:273] Iterated through 0 keys in
> the db in 107ns
> I0731 06:21:19.213696 14840 replica.cpp:741] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0731 06:21:19.237655 14995 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 16.897864ms
> I0731 06:21:19.237678 14995 replica.cpp:320] Persisted replica status to
> VOTING
> I0731 06:21:19.290518 14840 leveldb.cpp:176] Opened db in 48.761665ms
> I0731 06:21:19.317649 14840 leveldb.cpp:183] Compacted db in 27.108499ms
> I0731 06:21:19.317672 14840 leveldb.cpp:198] Created db iterator in 2292ns
> I0731 06:21:19.317678 14840 leveldb.cpp:204] Seeked to beginning of db in
> 321ns
> I0731 06:21:19.317684 14840 leveldb.cpp:273] Iterated through 0 keys in
> the db in 107ns
> I0731 06:21:19.317695 14840 replica.cpp:741] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0731 06:21:19.345665 14999 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 15.998932ms
> I0731 06:21:19.345743 14999 replica.cpp:320] Persisted replica status to
> VOTING
> I0731 06:21:19.399914 14840 leveldb.cpp:176] Opened db in 50.161779ms
> I0731 06:21:19.453106 14840 leveldb.cpp:183] Compacted db in 53.167898ms
> I0731 06:21:19.453130 14840 leveldb.cpp:198] Created db iterator in 3542ns
> I0731 06:21:19.453145 14840 leveldb.cpp:204] Seeked to beginning of db in
> 7140ns
> I0731 06:21:19.453158 14840 leveldb.cpp:273] Iterated through 1 keys in
> the db in 6584ns
> I0731 06:21:19.453169 14840 replica.cpp:741] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0731 06:21:19.497555 14840 leveldb.cpp:176] Opened db in 44.336223ms
> I0731 06:21:19.561662 14840 leveldb.cpp:183] Compacted db in 64.079557ms
> I0731 06:21:19.561689 14840 leveldb.cpp:198] Created db iterator in 3780ns
> I0731 06:21:19.561703 14840 leveldb.cpp:204] Seeked to beginning of db in
> 6854ns
> I0731 06:21:19.561717 14840 leveldb.cpp:273] Iterated through 1 keys in
> the db in 6255ns
> I0731 06:21:19.561728 14840 replica.cpp:741] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0731 06:21:19.565757 14999 recover.cpp:425] Starting replica recovery
> I0731 06:21:19.565862 14999 recover.cpp:451] Replica is in VOTING status
> I0731 06:21:19.565948 14999 recover.cpp:440] Recover process terminated
> I0731 06:21:19.566064 14999 log.cpp:656] Attempting to start the writer
> I0731 06:21:19.566344 14999 replica.cpp:474] Replica received implicit
> promise request with proposal 1
> I0731 06:21:19.569701 15003 replica.cpp:474] Replica received implicit
> promise request with proposal 1
> I0731 06:21:19.589653 15003 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 19.925455ms
> I0731 06:21:19.589679 15003 replica.cpp:342] Persisted promised to 1
> I0731 06:21:19.589675 14999 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 23.261838ms
> I0731 06:21:19.589695 14999 replica.cpp:342] Persisted promised to 1
> I0731 06:21:19.609776 15023 coordinator.cpp:230] Coordinator attemping to
> fill missing position
> I0731 06:21:19.610081 15023 replica.cpp:375] Replica received explicit
> promise request for position 0 with proposal 2
> I0731 06:21:19.610147 15003 replica.cpp:375] Replica received explicit
> promise request for position 0 with proposal 2
> I0731 06:21:19.630955 15023 leveldb.cpp:343] Persisting action (8 bytes)
> to leveldb took 20.858547ms
> I0731 06:21:19.630977 15023 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.631041 15003 leveldb.cpp:343] Persisting action (8 bytes)
> to leveldb took 20.880195ms
> I0731 06:21:19.631055 15003 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.631253 15003 replica.cpp:508] Replica received write
> request for position 0
> I0731 06:21:19.631274 15003 leveldb.cpp:438] Reading position from leveldb
> took 10142ns
> I0731 06:21:19.631785 15012 replica.cpp:508] Replica received write
> request for position 0
> I0731 06:21:19.631809 15012 leveldb.cpp:438] Reading position from leveldb
> took 9447ns
> I0731 06:21:19.652690 15012 leveldb.cpp:343] Persisting action (14 bytes)
> to leveldb took 20.867824ms
> I0731 06:21:19.652710 15012 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.653658 15003 leveldb.cpp:343] Persisting action (14 bytes)
> to leveldb took 22.36579ms
> I0731 06:21:19.653681 15003 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.653790 15003 replica.cpp:655] Replica received learned
> notice for position 0
> I0731 06:21:19.657672 14999 replica.cpp:655] Replica received learned
> notice for position 0
> I0731 06:21:19.673180 15003 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 19.372121ms
> I0731 06:21:19.673202 15003 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.673210 15003 replica.cpp:661] Replica learned NOP action at
> position 0
> I0731 06:21:19.679220 14999 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 21.531769ms
> I0731 06:21:19.679241 14999 replica.cpp:676] Persisted action at 0
> I0731 06:21:19.679250 14999 replica.cpp:661] Replica learned NOP action at
> position 0
> I0731 06:21:19.679374 14999 log.cpp:672] Writer started with ending
> position 0
> I0731 06:21:19.679474 14999 log.cpp:680] Attempting to append 11 bytes to
> the log
> I0731 06:21:19.679510 14999 coordinator.cpp:340] Coordinator attempting to
> write APPEND action at position 1
> I0731 06:21:19.679671 14999 replica.cpp:508] Replica received write
> request for position 1
> I0731 06:21:19.681674 15000 replica.cpp:508] Replica received write
> request for position 1
> I0731 06:21:19.699331 14999 leveldb.cpp:343] Persisting action (27 bytes)
> to leveldb took 19.64623ms
> I0731 06:21:19.699357 14999 replica.cpp:676] Persisted action at 1
> I0731 06:21:19.699331 15000 leveldb.cpp:343] Persisting action (27 bytes)
> to leveldb took 17.637473ms
> I0731 06:21:19.699421 15000 replica.cpp:676] Persisted action at 1
> I0731 06:21:19.699585 15000 replica.cpp:655] Replica received learned
> notice for position 1
> I0731 06:21:19.701697 15002 replica.cpp:655] Replica received learned
> notice for position 1
> I0731 06:21:19.721653 15002 leveldb.cpp:343] Persisting action (29 bytes)
> to leveldb took 19.938675ms
> I0731 06:21:19.721674 15002 replica.cpp:676] Persisted action at 1
> I0731 06:21:19.721683 15000 leveldb.cpp:343] Persisting action (29 bytes)
> to leveldb took 22.08056ms
> I0731 06:21:19.721709 15000 replica.cpp:676] Persisted action at 1
> I0731 06:21:19.721717 15000 replica.cpp:661] Replica learned APPEND action
> at position 1
> I0731 06:21:19.721683 15002 replica.cpp:661] Replica learned APPEND action
> at position 1
> I0731 06:21:19.729660 15002 leveldb.cpp:438] Reading position from leveldb
> took 9793ns
> [       OK ] LogTest.WriteRead (599 ms)
> [----------] 2 tests from LogTest (1214 ms total)
>
> [----------] Global test environment tear-down
> [==========] 391 tests from 64 test cases ran. (441542 ms total)
> [  PASSED  ] 390 tests.
> [  FAILED  ] 1 test, listed below:
> [  FAILED  ] SlaveRecoveryTest/0.MasterFailover, where TypeParam =
> mesos::internal::slave::MesosContainerizer
>
>  1 FAILED TEST
>   YOU HAVE 4 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[4]: Leaving directory
> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.20.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory
> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.20.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory
> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.20.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory
> `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.20.0/_build'
> make: *** [distcheck] Error 1
> Build step 'Execute shell' marked build as failure
>