See
<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2261/changes>
Changes:
[benjamin.hindman] Abstracted out invoking 'mesos-fetcher'.
------------------------------------------
[...truncated 70651 lines...]
2014-11-08 04:44:55,244:26166(0x2b3a5c135700):ZOO_INFO@log_env@724: Client
environment:os.arch=3.13.0-36-lowlatency
2014-11-08 04:44:55,244:26166(0x2b3a5c135700):ZOO_INFO@log_env@725: Client
environment:os.version=#63-Ubuntu SMP PREEMPT Wed Sep 3 21:56:12 UTC 2014
2014-11-08 04:44:55,244:26166(0x2b3a5c537700):ZOO_INFO@log_env@741: Client
environment:user.home=/home/jenkins
2014-11-08 04:44:55,244:26166(0x2b3a5c537700):ZOO_INFO@log_env@753: Client
environment:user.dir=/tmp/LogZooKeeperTest_WriteRead_dFYVWf
2014-11-08 04:44:55,320:26166(0x2b3a5c537700):ZOO_INFO@zookeeper_init@786:
Initiating client connection, host=127.0.0.1:42847 sessionTimeout=10000
watcher=0x2b3a56028b0a sessionId=0 sessionPasswd=<null> context=0x2b3a980cc220
flags=0
2014-11-08 04:44:55,244:26166(0x2b3a5c135700):ZOO_INFO@log_env@733: Client
environment:user.name=jenkins
I1108 04:44:55.244516 26184 recover.cpp:437] Starting replica recovery
2014-11-08 04:44:55,320:26166(0x2b3a5c135700):ZOO_INFO@log_env@741: Client
environment:user.home=/home/jenkins
2014-11-08 04:44:55,320:26166(0x2b3a5c135700):ZOO_INFO@log_env@753: Client
environment:user.dir=/tmp/LogZooKeeperTest_WriteRead_dFYVWf
2014-11-08 04:44:55,320:26166(0x2b3a5c135700):ZOO_INFO@zookeeper_init@786:
Initiating client connection, host=127.0.0.1:42847 sessionTimeout=10000
watcher=0x2b3a56028b0a sessionId=0 sessionPasswd=<null> context=0x2b3a7408e8f0
flags=0
I1108 04:44:55.247128 26166 leveldb.cpp:176] Opened db in 3.573419ms
I1108 04:44:55.320708 26184 recover.cpp:463] Replica is in VOTING status
2014-11-08 04:44:55,320:26166(0x2b3e0ca04700):ZOO_INFO@check_events@1703:
initiated connection to server [127.0.0.1:42847]
2014-11-08 04:44:55,320:26166(0x2b3e0de2d700):ZOO_INFO@check_events@1703:
initiated connection to server [127.0.0.1:42847]
I1108 04:44:55.320850 26184 recover.cpp:452] Recover process terminated
2014-11-08 04:44:55,323:26166(0x2b3e0ca04700):ZOO_INFO@check_events@1750:
session establishment complete on server [127.0.0.1:42847],
sessionId=0x1498db822360000, negotiated timeout=10000
I1108 04:44:55.323797 26166 leveldb.cpp:183] Compacted db in 3.326034ms
I1108 04:44:55.323853 26166 leveldb.cpp:198] Created db iterator in 17790ns
I1108 04:44:55.323887 26166 leveldb.cpp:204] Seeked to beginning of db in
19544ns
I1108 04:44:55.323922 26166 leveldb.cpp:273] Iterated through 1 keys in the db
in 20972ns
I1108 04:44:55.323957 26166 replica.cpp:741] Replica recovered with log
positions 0 -> 0 with 1 holes and 0 unlearned
I1108 04:44:55.323997 26182 group.cpp:313] Group process
(group(53)@67.195.81.190:53738) connected to ZooKeeper
I1108 04:44:55.324029 26182 group.cpp:790] Syncing group operations: queue size
(joins, cancels, datas) = (0, 0, 0)
I1108 04:44:55.324050 26182 group.cpp:385] Trying to create path '/log' in
ZooKeeper
2014-11-08 04:44:55,324:26166(0x2b3e0de2d700):ZOO_INFO@check_events@1750:
session establishment complete on server [127.0.0.1:42847],
sessionId=0x1498db822360001, negotiated timeout=10000
2014-11-08 04:44:55,324:26166(0x2b3a5c336700):ZOO_INFO@log_env@712: Client
environment:zookeeper.version=zookeeper C client 3.4.5
2014-11-08 04:44:55,324:26166(0x2b3a5c336700):ZOO_INFO@log_env@716: Client
environment:host.name=pietas.apache.org
2014-11-08 04:44:55,324:26166(0x2b3a5c336700):ZOO_INFO@log_env@723: Client
environment:os.name=Linux
2014-11-08 04:44:55,324:26166(0x2b3a5c336700):ZOO_INFO@log_env@724: Client
environment:os.arch=3.13.0-36-lowlatency
2014-11-08 04:44:55,324:26166(0x2b3a5c336700):ZOO_INFO@log_env@725: Client
environment:os.version=#63-Ubuntu SMP PREEMPT Wed Sep 3 21:56:12 UTC 2014
2014-11-08 04:44:55,324:26166(0x2b3a5c336700):ZOO_INFO@log_env@733: Client
environment:user.name=jenkins
2014-11-08 04:44:55,324:26166(0x2b3a5c336700):ZOO_INFO@log_env@741: Client
environment:user.home=/home/jenkins
2014-11-08 04:44:55,324:26166(0x2b3a5c336700):ZOO_INFO@log_env@753: Client
environment:user.dir=/tmp/LogZooKeeperTest_WriteRead_dFYVWf
2014-11-08 04:44:55,324:26166(0x2b3a5c336700):ZOO_INFO@zookeeper_init@786:
Initiating client connection, host=127.0.0.1:42847 sessionTimeout=10000
watcher=0x2b3a56028b0a sessionId=0 sessionPasswd=<null> context=0x2b3a900ae0f0
flags=0
I1108 04:44:55.324905 26184 log.cpp:238] Attempting to join replica to
ZooKeeper group
2014-11-08 04:44:55,325:26166(0x2b3a5bb32700):ZOO_INFO@log_env@712: Client
environment:zookeeper.version=zookeeper C client 3.4.5
2014-11-08 04:44:55,325:26166(0x2b3a5bb32700):ZOO_INFO@log_env@716: Client
environment:host.name=pietas.apache.org
2014-11-08 04:44:55,325:26166(0x2b3a5bb32700):ZOO_INFO@log_env@723: Client
environment:os.name=Linux
2014-11-08 04:44:55,325:26166(0x2b3a5bb32700):ZOO_INFO@log_env@724: Client
environment:os.arch=3.13.0-36-lowlatency
2014-11-08 04:44:55,325:26166(0x2b3a5bb32700):ZOO_INFO@log_env@725: Client
environment:os.version=#63-Ubuntu SMP PREEMPT Wed Sep 3 21:56:12 UTC 2014
2014-11-08 04:44:55,325:26166(0x2b3a5bb32700):ZOO_INFO@log_env@733: Client
environment:user.name=jenkins
2014-11-08 04:44:55,325:26166(0x2b3a5bb32700):ZOO_INFO@log_env@741: Client
environment:user.home=/home/jenkins
2014-11-08 04:44:55,325:26166(0x2b3a5bb32700):ZOO_INFO@log_env@753: Client
environment:user.dir=/tmp/LogZooKeeperTest_WriteRead_dFYVWf
2014-11-08 04:44:55,325:26166(0x2b3a5bb32700):ZOO_INFO@zookeeper_init@786:
Initiating client connection, host=127.0.0.1:42847 sessionTimeout=10000
watcher=0x2b3a56028b0a sessionId=0 sessionPasswd=<null> context=0x2b3a6c0c79b0
flags=0
I1108 04:44:55.325539 26189 group.cpp:313] Group process
(group(54)@67.195.81.190:53738) connected to ZooKeeper
I1108 04:44:55.325574 26189 group.cpp:790] Syncing group operations: queue size
(joins, cancels, datas) = (1, 0, 0)
I1108 04:44:55.325588 26189 group.cpp:385] Trying to create path '/log' in
ZooKeeper
I1108 04:44:55.325582 26194 recover.cpp:437] Starting replica recovery
2014-11-08 04:44:55,325:26166(0x2b3e0e02e700):ZOO_INFO@check_events@1703:
initiated connection to server [127.0.0.1:42847]
I1108 04:44:55.325893 26194 recover.cpp:463] Replica is in VOTING status
2014-11-08 04:44:55,325:26166(0x2b3e0d60a700):ZOO_INFO@check_events@1703:
initiated connection to server [127.0.0.1:42847]
I1108 04:44:55.326051 26194 recover.cpp:452] Recover process terminated
I1108 04:44:55.326891 26187 log.cpp:656] Attempting to start the writer
2014-11-08 04:44:55,328:26166(0x2b3e0e02e700):ZOO_INFO@check_events@1750:
session establishment complete on server [127.0.0.1:42847],
sessionId=0x1498db822360002, negotiated timeout=10000
I1108 04:44:55.329056 26190 group.cpp:313] Group process
(group(55)@67.195.81.190:53738) connected to ZooKeeper
I1108 04:44:55.329090 26190 group.cpp:790] Syncing group operations: queue size
(joins, cancels, datas) = (0, 0, 0)
I1108 04:44:55.329113 26190 group.cpp:385] Trying to create path '/log' in
ZooKeeper
2014-11-08 04:44:55,329:26166(0x2b3e0d60a700):ZOO_INFO@check_events@1750:
session establishment complete on server [127.0.0.1:42847],
sessionId=0x1498db822360003, negotiated timeout=10000
I1108 04:44:55.330327 26188 group.cpp:313] Group process
(group(56)@67.195.81.190:53738) connected to ZooKeeper
I1108 04:44:55.330359 26188 group.cpp:790] Syncing group operations: queue size
(joins, cancels, datas) = (1, 0, 0)
I1108 04:44:55.330374 26188 group.cpp:385] Trying to create path '/log' in
ZooKeeper
I1108 04:44:55.332345 26192 network.hpp:424] ZooKeeper group memberships changed
I1108 04:44:55.332495 26181 group.cpp:659] Trying to get '/log/0000000000' in
ZooKeeper
I1108 04:44:55.333076 26192 network.hpp:424] ZooKeeper group memberships changed
I1108 04:44:55.333212 26191 group.cpp:659] Trying to get '/log/0000000000' in
ZooKeeper
I1108 04:44:55.334625 26194 network.hpp:466] ZooKeeper group PIDs: {
log-replica(438)@67.195.81.190:53738 }
I1108 04:44:55.335556 26186 network.hpp:466] ZooKeeper group PIDs: {
log-replica(438)@67.195.81.190:53738 }
I1108 04:44:55.335785 26180 replica.cpp:474] Replica received implicit promise
request with proposal 1
I1108 04:44:55.335963 26195 replica.cpp:474] Replica received implicit promise
request with proposal 1
I1108 04:44:55.336510 26194 network.hpp:424] ZooKeeper group memberships changed
I1108 04:44:55.336638 26195 leveldb.cpp:306] Persisting metadata (8 bytes) to
leveldb took 639914ns
I1108 04:44:55.336642 26180 leveldb.cpp:306] Persisting metadata (8 bytes) to
leveldb took 818147ns
I1108 04:44:55.336666 26195 replica.cpp:342] Persisted promised to 1
I1108 04:44:55.336673 26180 replica.cpp:342] Persisted promised to 1
I1108 04:44:55.336662 26182 group.cpp:659] Trying to get '/log/0000000000' in
ZooKeeper
I1108 04:44:55.337342 26186 network.hpp:424] ZooKeeper group memberships changed
I1108 04:44:55.337709 26189 coordinator.cpp:230] Coordinator attemping to fill
missing position
I1108 04:44:55.337903 26182 group.cpp:659] Trying to get '/log/0000000001' in
ZooKeeper
I1108 04:44:55.452550 26195 group.cpp:659] Trying to get '/log/0000000000' in
ZooKeeper
I1108 04:44:55.454025 26195 group.cpp:659] Trying to get '/log/0000000001' in
ZooKeeper
I1108 04:44:55.454591 26193 network.hpp:466] ZooKeeper group PIDs: {
log-replica(438)@67.195.81.190:53738, log-replica(439)@67.195.81.190:53738 }
I1108 04:44:55.454737 26187 replica.cpp:375] Replica received explicit promise
request for position 0 with proposal 2
I1108 04:44:55.455200 26185 replica.cpp:375] Replica received explicit promise
request for position 0 with proposal 2
I1108 04:44:55.455358 26187 leveldb.cpp:343] Persisting action (8 bytes) to
leveldb took 580678ns
I1108 04:44:55.455389 26187 replica.cpp:676] Persisted action at 0
I1108 04:44:55.455752 26185 leveldb.cpp:343] Persisting action (8 bytes) to
leveldb took 511428ns
I1108 04:44:55.455786 26185 replica.cpp:676] Persisted action at 0
I1108 04:44:55.455962 26195 network.hpp:466] ZooKeeper group PIDs: {
log-replica(438)@67.195.81.190:53738, log-replica(439)@67.195.81.190:53738 }
I1108 04:44:55.457196 26187 replica.cpp:508] Replica received write request for
position 0
I1108 04:44:55.457259 26187 leveldb.cpp:438] Reading position from leveldb took
28672ns
I1108 04:44:55.457571 26183 replica.cpp:508] Replica received write request for
position 0
I1108 04:44:55.457638 26183 leveldb.cpp:438] Reading position from leveldb took
29027ns
I1108 04:44:55.457691 26187 leveldb.cpp:343] Persisting action (14 bytes) to
leveldb took 390166ns
I1108 04:44:55.457720 26187 replica.cpp:676] Persisted action at 0
I1108 04:44:55.458050 26183 leveldb.cpp:343] Persisting action (14 bytes) to
leveldb took 374875ns
I1108 04:44:55.458076 26183 replica.cpp:676] Persisted action at 0
I1108 04:44:55.458554 26192 replica.cpp:655] Replica received learned notice
for position 0
I1108 04:44:55.458592 26193 replica.cpp:655] Replica received learned notice
for position 0
I1108 04:44:55.459014 26193 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 391444ns
I1108 04:44:55.459019 26192 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 431750ns
I1108 04:44:55.459045 26193 replica.cpp:676] Persisted action at 0
I1108 04:44:55.459055 26192 replica.cpp:676] Persisted action at 0
I1108 04:44:55.459067 26193 replica.cpp:661] Replica learned NOP action at
position 0
I1108 04:44:55.459077 26192 replica.cpp:661] Replica learned NOP action at
position 0
I1108 04:44:55.459836 26188 log.cpp:672] Writer started with ending position 0
I1108 04:44:55.460250 26180 log.cpp:680] Attempting to append 11 bytes to the
log
I1108 04:44:55.460368 26191 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 1
I1108 04:44:55.461184 26186 replica.cpp:508] Replica received write request for
position 1
I1108 04:44:55.461323 26183 replica.cpp:508] Replica received write request for
position 1
I1108 04:44:55.461616 26186 leveldb.cpp:343] Persisting action (27 bytes) to
leveldb took 394737ns
I1108 04:44:55.461643 26186 replica.cpp:676] Persisted action at 1
I1108 04:44:55.461727 26183 leveldb.cpp:343] Persisting action (27 bytes) to
leveldb took 370577ns
I1108 04:44:55.461750 26183 replica.cpp:676] Persisted action at 1
I1108 04:44:55.462525 26194 replica.cpp:655] Replica received learned notice
for position 1
I1108 04:44:55.462601 26191 replica.cpp:655] Replica received learned notice
for position 1
I1108 04:44:55.462906 26191 leveldb.cpp:343] Persisting action (29 bytes) to
leveldb took 275192ns
I1108 04:44:55.462906 26194 leveldb.cpp:343] Persisting action (29 bytes) to
leveldb took 351125ns
I1108 04:44:55.462939 26191 replica.cpp:676] Persisted action at 1
I1108 04:44:55.462947 26194 replica.cpp:676] Persisted action at 1
I1108 04:44:55.462960 26191 replica.cpp:661] Replica learned APPEND action at
position 1
I1108 04:44:55.462970 26194 replica.cpp:661] Replica learned APPEND action at
position 1
I1108 04:44:55.464081 26181 leveldb.cpp:438] Reading position from leveldb took
36464ns
2014-11-08 04:44:55,465:26166(0x2b3a53084080):ZOO_INFO@zookeeper_close@2505:
Closing zookeeper sessionId=0x1498db822360003 to [127.0.0.1:42847]
2014-11-08 04:44:55,466:26166(0x2b3a5b32e700):ZOO_INFO@zookeeper_close@2505:
Closing zookeeper sessionId=0x1498db822360002 to [127.0.0.1:42847]
I1108 04:44:55.468557 26186 network.hpp:424] ZooKeeper group memberships changed
I1108 04:44:55.468693 26193 group.cpp:659] Trying to get '/log/0000000000' in
ZooKeeper
2014-11-08 04:44:55,469:26166(0x2b3a5b931700):ZOO_INFO@zookeeper_close@2505:
Closing zookeeper sessionId=0x1498db822360001 to [127.0.0.1:42847]
2014-11-08 04:44:55,470:26166(0x2b3a5bf34700):ZOO_INFO@zookeeper_close@2505:
Closing zookeeper sessionId=0x1498db822360000 to [127.0.0.1:42847]
I1108 04:44:55.471582 26166 zookeeper_test_server.cpp:118] Shutting down
ZooKeeperTestServer on port 42847
[ OK ] LogZooKeeperTest.WriteRead (254 ms)
[ RUN ] LogZooKeeperTest.LostZooKeeper
I1108 04:44:55.475536 26166 zookeeper_test_server.cpp:158] Started
ZooKeeperTestServer on port 52306
I1108 04:44:55.475628 26166 log_tests.cpp:1945] Using temporary directory
'/tmp/LogZooKeeperTest_LostZooKeeper_B5z3jV'
I1108 04:44:55.478559 26166 leveldb.cpp:176] Opened db in 2.846954ms
I1108 04:44:55.479370 26166 leveldb.cpp:183] Compacted db in 775391ns
I1108 04:44:55.479416 26166 leveldb.cpp:198] Created db iterator in 16244ns
I1108 04:44:55.479460 26166 leveldb.cpp:204] Seeked to beginning of db in 1968ns
I1108 04:44:55.479472 26166 leveldb.cpp:273] Iterated through 0 keys in the db
in 482ns
I1108 04:44:55.479504 26166 replica.cpp:741] Replica recovered with log
positions 0 -> 0 with 1 holes and 0 unlearned
2014-11-08 04:44:55,479:26166(0x2b3a5b12d700):ZOO_INFO@log_env@712: Client
environment:zookeeper.version=zookeeper C client 3.4.5
2014-11-08 04:44:55,479:26166(0x2b3a5b12d700):ZOO_INFO@log_env@716: Client
environment:host.name=pietas.apache.org
2014-11-08 04:44:55,479:26166(0x2b3a5b12d700):ZOO_INFO@log_env@723: Client
environment:os.name=Linux
2014-11-08 04:44:55,479:26166(0x2b3a5b12d700):ZOO_INFO@log_env@724: Client
environment:os.arch=3.13.0-36-lowlatency
2014-11-08 04:44:55,479:26166(0x2b3a5b12d700):ZOO_INFO@log_env@725: Client
environment:os.version=#63-Ubuntu SMP PREEMPT Wed Sep 3 21:56:12 UTC 2014
2014-11-08 04:44:55,480:26166(0x2b3a5b12d700):ZOO_INFO@log_env@733: Client
environment:user.name=jenkins
2014-11-08 04:44:55,480:26166(0x2b3a5b12d700):ZOO_INFO@log_env@741: Client
environment:user.home=/home/jenkins
2014-11-08 04:44:55,480:26166(0x2b3a5b12d700):ZOO_INFO@log_env@753: Client
environment:user.dir=/tmp/LogZooKeeperTest_LostZooKeeper_B5z3jV
I1108 04:44:55.480145 26188 log.cpp:238] Attempting to join replica to
ZooKeeper group
2014-11-08 04:44:55,480:26166(0x2b3a5b12d700):ZOO_INFO@zookeeper_init@786:
Initiating client connection, host=127.0.0.1:52306 sessionTimeout=10000
watcher=0x2b3a56028b0a sessionId=0 sessionPasswd=<null> context=0x2b3a880a5d90
flags=0
2014-11-08 04:44:55,480:26166(0x2b3a5b931700):ZOO_INFO@log_env@712: Client
environment:zookeeper.version=zookeeper C client 3.4.5
2014-11-08 04:44:55,480:26166(0x2b3a5b931700):ZOO_INFO@log_env@716: Client
environment:host.name=pietas.apache.org
2014-11-08 04:44:55,480:26166(0x2b3a5b931700):ZOO_INFO@log_env@723: Client
environment:os.name=Linux
2014-11-08 04:44:55,480:26166(0x2b3a5b931700):ZOO_INFO@log_env@724: Client
environment:os.arch=3.13.0-36-lowlatency
2014-11-08 04:44:55,480:26166(0x2b3a5b931700):ZOO_INFO@log_env@725: Client
environment:os.version=#63-Ubuntu SMP PREEMPT Wed Sep 3 21:56:12 UTC 2014
2014-11-08 04:44:55,480:26166(0x2b3a5b931700):ZOO_INFO@log_env@733: Client
environment:user.name=jenkins
I1108 04:44:55.480638 26190 recover.cpp:437] Starting replica recovery
2014-11-08 04:44:55,480:26166(0x2b3e0ca04700):ZOO_INFO@check_events@1703:
initiated connection to server [127.0.0.1:52306]
2014-11-08 04:44:55,480:26166(0x2b3a5b931700):ZOO_INFO@log_env@741: Client
environment:user.home=/home/jenkins
2014-11-08 04:44:55,624:26166(0x2b3a5b931700):ZOO_INFO@log_env@753: Client
environment:user.dir=/tmp/LogZooKeeperTest_LostZooKeeper_B5z3jV
2014-11-08 04:44:55,624:26166(0x2b3a5b931700):ZOO_INFO@zookeeper_init@786:
Initiating client connection, host=127.0.0.1:52306 sessionTimeout=10000
watcher=0x2b3a56028b0a sessionId=0 sessionPasswd=<null> context=0x2b3a7c09cad0
flags=0
I1108 04:44:55.480916 26182 recover.cpp:463] Replica is in EMPTY status
2014-11-08 04:44:55,483:26166(0x2b3e0ca04700):ZOO_INFO@check_events@1750:
session establishment complete on server [127.0.0.1:52306],
sessionId=0x1498db823320000, negotiated timeout=10000
2014-11-08 04:44:55,624:26166(0x2b3e0de2d700):ZOO_INFO@check_events@1703:
initiated connection to server [127.0.0.1:52306]
I1108 04:44:55.625049 26189 group.cpp:313] Group process
(group(57)@67.195.81.190:53738) connected to ZooKeeper
I1108 04:44:55.625107 26189 group.cpp:790] Syncing group operations: queue size
(joins, cancels, datas) = (0, 0, 0)
I1108 04:44:55.625128 26189 group.cpp:385] Trying to create path '/log' in
ZooKeeper
I1108 04:44:55.625675 26192 replica.cpp:638] Replica in EMPTY status received a
broadcasted recover request
I1108 04:44:55.626123 26194 recover.cpp:188] Received a recover response from a
replica in EMPTY status
2014-11-08 04:44:55,626:26166(0x2b3e0de2d700):ZOO_INFO@check_events@1750:
session establishment complete on server [127.0.0.1:52306],
sessionId=0x1498db823320001, negotiated timeout=10000
I1108 04:44:55.626602 26188 recover.cpp:554] Updating replica status to STARTING
I1108 04:44:55.627254 26191 group.cpp:313] Group process
(group(58)@67.195.81.190:53738) connected to ZooKeeper
I1108 04:44:55.627338 26191 group.cpp:790] Syncing group operations: queue size
(joins, cancels, datas) = (1, 0, 0)
I1108 04:44:55.627353 26191 group.cpp:385] Trying to create path '/log' in
ZooKeeper
I1108 04:44:55.627354 26183 leveldb.cpp:306] Persisting metadata (8 bytes) to
leveldb took 597985ns
I1108 04:44:55.627388 26183 replica.cpp:320] Persisted replica status to
STARTING
I1108 04:44:55.627671 26187 recover.cpp:463] Replica is in STARTING status
I1108 04:44:55.628998 26186 replica.cpp:638] Replica in STARTING status
received a broadcasted recover request
I1108 04:44:55.629487 26192 recover.cpp:188] Received a recover response from a
replica in STARTING status
I1108 04:44:55.629966 26180 recover.cpp:554] Updating replica status to VOTING
I1108 04:44:55.630470 26182 leveldb.cpp:306] Persisting metadata (8 bytes) to
leveldb took 365147ns
I1108 04:44:55.630506 26182 replica.cpp:320] Persisted replica status to VOTING
I1108 04:44:55.630600 26185 recover.cpp:568] Successfully joined the Paxos group
I1108 04:44:55.630774 26185 recover.cpp:452] Recover process terminated
I1108 04:44:55.631284 26193 log.cpp:656] Attempting to start the writer
I1108 04:44:55.632472 26186 network.hpp:424] ZooKeeper group memberships changed
I1108 04:44:55.632601 26187 group.cpp:659] Trying to get '/log/0000000000' in
ZooKeeper
I1108 04:44:55.632657 26193 replica.cpp:474] Replica received implicit promise
request with proposal 1
I1108 04:44:55.633139 26193 leveldb.cpp:306] Persisting metadata (8 bytes) to
leveldb took 449102ns
I1108 04:44:55.633165 26193 replica.cpp:342] Persisted promised to 1
I1108 04:44:55.633846 26194 coordinator.cpp:230] Coordinator attemping to fill
missing position
I1108 04:44:55.634021 26190 network.hpp:466] ZooKeeper group PIDs: {
log-replica(440)@67.195.81.190:53738 }
I1108 04:44:55.635151 26180 replica.cpp:375] Replica received explicit promise
request for position 0 with proposal 2
I1108 04:44:55.635571 26180 leveldb.cpp:343] Persisting action (8 bytes) to
leveldb took 382219ns
I1108 04:44:55.635596 26180 replica.cpp:676] Persisted action at 0
I1108 04:44:55.636574 26187 replica.cpp:508] Replica received write request for
position 0
I1108 04:44:55.636646 26187 leveldb.cpp:438] Reading position from leveldb took
30476ns
I1108 04:44:55.637039 26187 leveldb.cpp:343] Persisting action (14 bytes) to
leveldb took 357220ns
I1108 04:44:55.637065 26187 replica.cpp:676] Persisted action at 0
I1108 04:44:55.637536 26182 replica.cpp:655] Replica received learned notice
for position 0
I1108 04:44:55.637980 26182 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 412783ns
I1108 04:44:55.638011 26182 replica.cpp:676] Persisted action at 0
I1108 04:44:55.638043 26182 replica.cpp:661] Replica learned NOP action at
position 0
I1108 04:44:55.638600 26183 log.cpp:672] Writer started with ending position 0
I1108 04:44:55.638844 26166 zookeeper_test_server.cpp:118] Shutting down
ZooKeeperTestServer on port 52306
2014-11-08
04:44:55,639:26166(0x2b3e0ca04700):ZOO_ERROR@handle_socket_error_msg@1721:
Socket [127.0.0.1:52306] zk retcode=-4, errno=112(Host is down): failed while
receiving a server response
2014-11-08
04:44:55,639:26166(0x2b3e0de2d700):ZOO_ERROR@handle_socket_error_msg@1721:
Socket [127.0.0.1:52306] zk retcode=-4, errno=112(Host is down): failed while
receiving a server response
I1108 04:44:55.640256 26183 group.cpp:418] Lost connection to ZooKeeper,
attempting to reconnect ...
I1108 04:44:55.640638 26184 group.cpp:418] Lost connection to ZooKeeper,
attempting to reconnect ...
I1108 04:44:55.641289 26188 log.cpp:680] Attempting to append 11 bytes to the
log
I1108 04:44:55.641464 26182 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 1
I1108 04:44:55.642262 26184 replica.cpp:508] Replica received write request for
position 1
I1108 04:44:55.642676 26184 leveldb.cpp:343] Persisting action (27 bytes) to
leveldb took 375727ns
I1108 04:44:55.642704 26184 replica.cpp:676] Persisted action at 1
I1108 04:44:55.643218 26182 replica.cpp:655] Replica received learned notice
for position 1
I1108 04:44:55.643797 26182 leveldb.cpp:343] Persisting action (29 bytes) to
leveldb took 551262ns
I1108 04:44:55.643828 26182 replica.cpp:676] Persisted action at 1
I1108 04:44:55.643854 26182 replica.cpp:661] Replica learned APPEND action at
position 1
I1108 04:44:55.644922 26185 leveldb.cpp:438] Reading position from leveldb took
33977ns
2014-11-08 04:44:55,646:26166(0x2b3a5a929700):ZOO_INFO@zookeeper_close@2522:
Freeing zookeeper resources for sessionId=0x1498db823320001
2014-11-08 04:44:55,647:26166(0x2b3a5c336700):ZOO_INFO@zookeeper_close@2522:
Freeing zookeeper resources for sessionId=0x1498db823320000
[ OK ] LogZooKeeperTest.LostZooKeeper (176 ms)
[----------] 2 tests from LogZooKeeperTest (430 ms total)
[----------] Global test environment tear-down
[==========] 458 tests from 71 test cases ran. (373138 ms total)
[ PASSED ] 457 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] MesosContainerizerExecuteTest.IoRedirection
1 FAILED TEST
YOU HAVE 6 DISABLED TESTS
make[3]: *** [check-local] Error 1
make[3]: Leaving directory
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make: *** [check-recursive] Error 1
Test finished
Exit status: 2
Build step 'Execute shell' marked build as failure
Recording test results
