See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1937/changes>
Changes: [yujie.jay] Exposed VETH_PREFIX and used mesos instead of veth. [bmahler] Increased the registry store timeout for tests. ------------------------------------------ [...truncated 58960 lines...] I0627 00:30:37.958282 10246 replica.cpp:676] Persisted action at 4 I0627 00:30:37.958291 10246 replica.cpp:661] Replica learned APPEND action at position 4 I0627 00:30:37.958677 10251 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 9.58326ms I0627 00:30:37.958698 10251 replica.cpp:676] Persisted action at 4 I0627 00:30:37.958706 10251 replica.cpp:661] Replica learned APPEND action at position 4 I0627 00:30:37.959224 10251 replica.cpp:375] Replica received explicit promise request for position 5 with proposal 2 I0627 00:30:37.959249 10251 leveldb.cpp:438] Reading position from leveldb took 11048ns I0627 00:30:37.959334 10250 replica.cpp:375] Replica received explicit promise request for position 5 with proposal 2 I0627 00:30:37.959360 10250 leveldb.cpp:438] Reading position from leveldb took 12759ns I0627 00:30:37.959367 10247 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status I0627 00:30:37.968168 10250 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 8.791872ms I0627 00:30:37.968189 10250 replica.cpp:676] Persisted action at 5 I0627 00:30:38.047015 10251 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 87.74807ms I0627 00:30:38.047036 10251 replica.cpp:676] Persisted action at 5 I0627 00:30:38.047335 10250 replica.cpp:655] Replica received learned notice for position 5 I0627 00:30:38.047406 10253 replica.cpp:655] Replica received learned notice for position 5 I0627 00:30:38.113889 10253 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 66.467704ms I0627 00:30:38.113895 10250 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 66.545737ms I0627 00:30:38.113935 10253 replica.cpp:676] Persisted action at 5 I0627 00:30:38.113946 10250 replica.cpp:676] Persisted action at 5 I0627 00:30:38.113950 10253 replica.cpp:661] Replica learned APPEND action at position 5 I0627 00:30:38.113957 10250 replica.cpp:661] Replica learned APPEND action at position 5 I0627 00:30:38.114533 10253 replica.cpp:375] Replica received explicit promise request for position 6 with proposal 2 I0627 00:30:38.114562 10253 leveldb.cpp:438] Reading position from leveldb took 12992ns I0627 00:30:38.114614 10246 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status I0627 00:30:38.114666 10249 replica.cpp:375] Replica received explicit promise request for position 6 with proposal 2 I0627 00:30:38.114706 10249 leveldb.cpp:438] Reading position from leveldb took 25903ns I0627 00:30:38.126420 10253 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 11.837728ms I0627 00:30:38.126446 10253 replica.cpp:676] Persisted action at 6 I0627 00:30:38.126713 10253 replica.cpp:655] Replica received learned notice for position 6 I0627 00:30:38.126737 10250 replica.cpp:655] Replica received learned notice for position 6 I0627 00:30:38.180579 10253 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 53.845063ms I0627 00:30:38.180606 10253 replica.cpp:676] Persisted action at 6 I0627 00:30:38.180615 10253 replica.cpp:661] Replica learned APPEND action at position 6 I0627 00:30:38.188942 10250 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 62.182484ms I0627 00:30:38.188961 10250 replica.cpp:676] Persisted action at 6 I0627 00:30:38.188968 10250 replica.cpp:661] Replica learned APPEND action at position 6 I0627 00:30:38.189458 10250 replica.cpp:375] Replica received explicit promise request for position 7 with proposal 2 I0627 00:30:38.189478 10247 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status I0627 00:30:38.189484 10250 leveldb.cpp:438] Reading position from leveldb took 11150ns I0627 00:30:38.204727 10249 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 89.998712ms I0627 00:30:38.204751 10249 replica.cpp:676] Persisted action at 6 I0627 00:30:38.204794 10249 replica.cpp:375] Replica received explicit promise request for position 7 with proposal 2 I0627 00:30:38.204813 10249 leveldb.cpp:438] Reading position from leveldb took 9284ns I0627 00:30:38.213439 10250 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 23.93336ms I0627 00:30:38.213459 10250 replica.cpp:676] Persisted action at 7 I0627 00:30:38.213762 10246 replica.cpp:655] Replica received learned notice for position 7 I0627 00:30:38.213871 10247 replica.cpp:655] Replica received learned notice for position 7 I0627 00:30:38.248457 10249 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 43.629685ms I0627 00:30:38.248479 10249 replica.cpp:676] Persisted action at 7 I0627 00:30:38.295634 10247 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 81.74458ms I0627 00:30:38.295658 10247 replica.cpp:676] Persisted action at 7 I0627 00:30:38.295667 10247 replica.cpp:661] Replica learned APPEND action at position 7 I0627 00:30:38.307345 10246 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 93.559205ms I0627 00:30:38.307368 10246 replica.cpp:676] Persisted action at 7 I0627 00:30:38.307375 10246 replica.cpp:661] Replica learned APPEND action at position 7 I0627 00:30:38.308136 10247 replica.cpp:375] Replica received explicit promise request for position 8 with proposal 2 I0627 00:30:38.308162 10247 leveldb.cpp:438] Reading position from leveldb took 12744ns I0627 00:30:38.308184 10249 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status I0627 00:30:38.308217 10246 replica.cpp:375] Replica received explicit promise request for position 8 with proposal 2 I0627 00:30:38.308238 10246 leveldb.cpp:438] Reading position from leveldb took 9270ns I0627 00:30:38.340050 10246 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 31.797771ms I0627 00:30:38.340071 10246 replica.cpp:676] Persisted action at 8 I0627 00:30:38.340371 10253 replica.cpp:655] Replica received learned notice for position 8 I0627 00:30:38.340440 10251 replica.cpp:655] Replica received learned notice for position 8 I0627 00:30:38.366991 10247 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 58.811727ms I0627 00:30:38.367015 10247 replica.cpp:676] Persisted action at 8 I0627 00:30:38.390972 10251 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 50.511681ms I0627 00:30:38.390998 10251 replica.cpp:676] Persisted action at 8 I0627 00:30:38.391007 10251 replica.cpp:661] Replica learned APPEND action at position 8 I0627 00:30:38.391284 10253 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 50.863181ms I0627 00:30:38.391305 10253 replica.cpp:676] Persisted action at 8 I0627 00:30:38.391314 10253 replica.cpp:661] Replica learned APPEND action at position 8 I0627 00:30:38.391659 10250 replica.cpp:375] Replica received explicit promise request for position 9 with proposal 2 I0627 00:30:38.391690 10249 replica.cpp:375] Replica received explicit promise request for position 9 with proposal 2 I0627 00:30:38.391723 10249 leveldb.cpp:438] Reading position from leveldb took 15332ns I0627 00:30:38.391734 10248 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status I0627 00:30:38.391692 10250 leveldb.cpp:438] Reading position from leveldb took 12803ns I0627 00:30:38.415056 10250 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 23.285468ms I0627 00:30:38.415081 10250 replica.cpp:676] Persisted action at 9 I0627 00:30:38.415385 10253 replica.cpp:655] Replica received learned notice for position 9 I0627 00:30:38.415412 10248 replica.cpp:655] Replica received learned notice for position 9 I0627 00:30:38.415839 10249 leveldb.cpp:343] Persisting action (17 bytes) to leveldb took 24.065068ms I0627 00:30:38.415865 10249 replica.cpp:676] Persisted action at 9 I0627 00:30:38.424217 10253 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 8.754861ms I0627 00:30:38.424238 10253 replica.cpp:676] Persisted action at 9 I0627 00:30:38.424247 10253 replica.cpp:661] Replica learned APPEND action at position 9 I0627 00:30:38.424727 10248 leveldb.cpp:343] Persisting action (19 bytes) to leveldb took 9.258538ms I0627 00:30:38.424748 10248 replica.cpp:676] Persisted action at 9 I0627 00:30:38.424825 10248 replica.cpp:661] Replica learned APPEND action at position 9 I0627 00:30:38.425475 10247 replica.cpp:375] Replica received explicit promise request for position 10 with proposal 2 I0627 00:30:38.425516 10247 leveldb.cpp:438] Reading position from leveldb took 16562ns I0627 00:30:38.425530 10250 replica.cpp:375] Replica received explicit promise request for position 10 with proposal 2 I0627 00:30:38.425576 10251 replica.cpp:369] Replica ignoring promise request as it is in EMPTY status I0627 00:30:38.425590 10250 leveldb.cpp:438] Reading position from leveldb took 13475ns I0627 00:30:38.435344 10247 leveldb.cpp:343] Persisting action (20 bytes) to leveldb took 9.809854ms I0627 00:30:38.435381 10247 replica.cpp:676] Persisted action at 10 I0627 00:30:38.435674 10250 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 10.048488ms I0627 00:30:38.435711 10250 replica.cpp:676] Persisted action at 10 I0627 00:30:38.435775 10253 replica.cpp:655] Replica received learned notice for position 10 I0627 00:30:38.435791 10252 replica.cpp:655] Replica received learned notice for position 10 I0627 00:30:38.446406 10253 leveldb.cpp:343] Persisting action (20 bytes) to leveldb took 10.604367ms I0627 00:30:38.446427 10253 replica.cpp:676] Persisted action at 10 I0627 00:30:38.446435 10253 replica.cpp:661] Replica learned APPEND action at position 10 I0627 00:30:38.466703 10252 leveldb.cpp:343] Persisting action (20 bytes) to leveldb took 30.895328ms I0627 00:30:38.466738 10252 replica.cpp:676] Persisted action at 10 I0627 00:30:38.466752 10252 replica.cpp:661] Replica learned APPEND action at position 10 [ OK ] RecoverTest.CatchupRetry (3183 ms) [----------] 4 tests from RecoverTest (9230 ms total) [----------] 1 test from HealthTest [ RUN ] HealthTest.ObserveEndpoint Using temporary directory '/tmp/HealthTest_ObserveEndpoint_McVozs' I0627 00:30:38.622649 10221 leveldb.cpp:176] Opened db in 150.065184ms I0627 00:30:38.670471 10221 leveldb.cpp:183] Compacted db in 47.802371ms I0627 00:30:38.670493 10221 leveldb.cpp:198] Created db iterator in 3648ns I0627 00:30:38.670502 10221 leveldb.cpp:204] Seeked to beginning of db in 651ns I0627 00:30:38.670510 10221 leveldb.cpp:273] Iterated through 0 keys in the db in 270ns I0627 00:30:38.670523 10221 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0627 00:30:38.670796 10249 recover.cpp:425] Starting replica recovery I0627 00:30:38.670922 10249 recover.cpp:451] Replica is in EMPTY status I0627 00:30:38.671337 10248 replica.cpp:638] Replica in EMPTY status received a broadcasted recover request I0627 00:30:38.671502 10253 recover.cpp:188] Received a recover response from a replica in EMPTY status I0627 00:30:38.671728 10249 recover.cpp:542] Updating replica status to STARTING I0627 00:30:38.673523 10252 master.cpp:288] Master 20140627-003038-1015726915-38585-10221 (janus.apache.org) started on 67.195.138.60:38585 I0627 00:30:38.673552 10252 master.cpp:325] Master only allowing authenticated frameworks to register I0627 00:30:38.673558 10252 master.cpp:330] Master only allowing authenticated slaves to register I0627 00:30:38.673568 10252 credentials.hpp:35] Loading credentials for authentication from '/tmp/HealthTest_ObserveEndpoint_McVozs/credentials' I0627 00:30:38.673707 10252 master.cpp:356] Authorization enabled I0627 00:30:38.674092 10249 hierarchical_allocator_process.hpp:301] Initializing hierarchical allocator process with master : [email protected]:38585 I0627 00:30:38.674156 10249 master.cpp:122] No whitelist given. Advertising offers for all slaves I0627 00:30:38.674438 10252 master.cpp:1122] The newly elected leader is [email protected]:38585 with id 20140627-003038-1015726915-38585-10221 I0627 00:30:38.674454 10252 master.cpp:1135] Elected as the leading master! I0627 00:30:38.674463 10252 master.cpp:953] Recovering from registrar I0627 00:30:38.674515 10249 registrar.cpp:313] Recovering registrar I0627 00:30:38.747697 10248 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 75.87727ms I0627 00:30:38.747720 10248 replica.cpp:320] Persisted replica status to STARTING I0627 00:30:38.747853 10247 recover.cpp:451] Replica is in STARTING status I0627 00:30:38.748374 10248 replica.cpp:638] Replica in STARTING status received a broadcasted recover request I0627 00:30:38.748530 10253 recover.cpp:188] Received a recover response from a replica in STARTING status I0627 00:30:38.748682 10250 recover.cpp:542] Updating replica status to VOTING I0627 00:30:38.760135 10248 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 11.395006ms I0627 00:30:38.760155 10248 replica.cpp:320] Persisted replica status to VOTING I0627 00:30:38.760228 10250 recover.cpp:556] Successfully joined the Paxos group I0627 00:30:38.760331 10250 recover.cpp:440] Recover process terminated I0627 00:30:38.760556 10251 log.cpp:656] Attempting to start the writer I0627 00:30:38.761095 10250 replica.cpp:474] Replica received implicit promise request with proposal 1 I0627 00:30:38.799368 10250 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 38.252695ms I0627 00:30:38.799394 10250 replica.cpp:342] Persisted promised to 1 I0627 00:30:38.799718 10247 coordinator.cpp:230] Coordinator attemping to fill missing position I0627 00:30:38.800269 10250 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2 I0627 00:30:38.826768 10250 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 26.482031ms I0627 00:30:38.826788 10250 replica.cpp:676] Persisted action at 0 I0627 00:30:38.827288 10252 replica.cpp:508] Replica received write request for position 0 I0627 00:30:38.827314 10252 leveldb.cpp:438] Reading position from leveldb took 11492ns I0627 00:30:38.868405 10252 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 41.077155ms I0627 00:30:38.868425 10252 replica.cpp:676] Persisted action at 0 I0627 00:30:38.868607 10252 replica.cpp:655] Replica received learned notice for position 0 I0627 00:30:38.890993 10252 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 22.374204ms I0627 00:30:38.891013 10252 replica.cpp:676] Persisted action at 0 I0627 00:30:38.891022 10252 replica.cpp:661] Replica learned NOP action at position 0 I0627 00:30:38.891257 10252 log.cpp:672] Writer started with ending position 0 I0627 00:30:38.891635 10252 leveldb.cpp:438] Reading position from leveldb took 7971ns I0627 00:30:38.893203 10250 registrar.cpp:346] Successfully fetched the registry (0B) I0627 00:30:38.893287 10250 registrar.cpp:422] Attempting to update the 'registry' I0627 00:30:38.894888 10251 log.cpp:680] Attempting to append 138 bytes to the log I0627 00:30:38.894948 10251 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1 I0627 00:30:38.895194 10251 replica.cpp:508] Replica received write request for position 1 I0627 00:30:38.901726 10251 leveldb.cpp:343] Persisting action (157 bytes) to leveldb took 6.518477ms I0627 00:30:38.901744 10251 replica.cpp:676] Persisted action at 1 I0627 00:30:38.901978 10251 replica.cpp:655] Replica received learned notice for position 1 I0627 00:30:38.910063 10251 leveldb.cpp:343] Persisting action (159 bytes) to leveldb took 8.070617ms I0627 00:30:38.910083 10251 replica.cpp:676] Persisted action at 1 I0627 00:30:38.910091 10251 replica.cpp:661] Replica learned APPEND action at position 1 I0627 00:30:38.910475 10246 registrar.cpp:479] Successfully updated 'registry' I0627 00:30:38.910533 10246 registrar.cpp:372] Successfully recovered registrar I0627 00:30:38.910573 10248 log.cpp:699] Attempting to truncate the log to 1 I0627 00:30:38.910640 10247 master.cpp:980] Recovered 0 slaves from the Registry (100B) ; allowing 10mins for slaves to re-register I0627 00:30:38.910661 10252 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2 I0627 00:30:38.910989 10247 replica.cpp:508] Replica received write request for position 2 I0627 00:30:38.911031 10248 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.911124 10248 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.911370 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.911803 10252 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.911856 10252 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.912133 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.912610 10249 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.912664 10249 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.912873 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.913261 10249 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.913311 10249 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.913550 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.913858 10249 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.913998 10249 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.914175 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.914516 10249 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.914574 10249 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.914791 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.915143 10252 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.915197 10252 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.915423 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.915709 10248 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.915770 10248 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.916024 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.916404 10249 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.916455 10249 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.916738 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.917114 10246 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.917203 10246 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.917438 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.917853 10251 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.917943 10251 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.918190 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.918401 10247 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 7.381261ms I0627 00:30:38.918426 10247 replica.cpp:676] Persisted action at 2 I0627 00:30:38.918699 10247 replica.cpp:655] Replica received learned notice for position 2 I0627 00:30:38.918874 10246 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.918947 10246 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.919329 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.919821 10246 process.cpp:3335] Handling HTTP event for process 'master' with path: '/master/observe' I0627 00:30:38.919960 10246 http.cpp:258] HTTP request for '/master/observe' I0627 00:30:38.920220 10254 process.cpp:1037] Socket closed while receiving I0627 00:30:38.920464 10221 master.cpp:619] Master terminating I0627 00:30:38.926730 10247 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 8.011009ms I0627 00:30:38.926761 10247 leveldb.cpp:401] Deleting ~1 keys from leveldb took 12566ns I0627 00:30:38.926769 10247 replica.cpp:676] Persisted action at 2 I0627 00:30:38.926779 10247 replica.cpp:661] Replica learned TRUNCATE action at position 2 [ OK ] HealthTest.ObserveEndpoint (456 ms) [----------] 1 test from HealthTest (456 ms total) [----------] Global test environment tear-down [==========] 377 tests from 61 test cases ran. (391063 ms total) [ PASSED ] 376 tests. [ FAILED ] 1 test, listed below: [ FAILED ] ZooKeeperTest.LeaderDetectorTimeoutHandling 1 FAILED TEST YOU HAVE 7 DISABLED TESTS make[4]: *** [check-local] Error 1 make[4]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.20.0/_build/src'> make[3]: *** [check-am] Error 2 make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.20.0/_build/src'> make[2]: *** [check] Error 2 make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.20.0/_build/src'> make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/mesos-0.20.0/_build'> make: *** [distcheck] Error 1 Build step 'Execute shell' marked build as failure
