See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2275/changes>
Changes: [benjamin.hindman] More docs for attributes and resources. [benjamin.hindman] Removed trailing '/' in Makefile.am. ------------------------------------------ [...truncated 3341 lines...] I0723 02:18:01.662987 26575 log.cpp:672] Writer started with ending position 0 I0723 02:18:01.663213 26576 log.cpp:680] Attempting to append 11 bytes to the log I0723 02:18:01.663368 26576 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1 I0723 02:18:01.663586 26576 replica.cpp:508] Replica received write request for position 1 I0723 02:18:01.663589 26575 replica.cpp:508] Replica received write request for position 1 I0723 02:18:01.670192 26576 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 6.582697ms I0723 02:18:01.670217 26576 replica.cpp:676] Persisted action at 1 I0723 02:18:01.674784 26575 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 11.146285ms I0723 02:18:01.674808 26575 replica.cpp:676] Persisted action at 1 I0723 02:18:01.675060 26573 replica.cpp:655] Replica received learned notice for position 1 I0723 02:18:01.675065 26575 replica.cpp:655] Replica received learned notice for position 1 I0723 02:18:01.694130 26575 leveldb.cpp:343] Persisting action (29 bytes) to leveldb took 19.03493ms I0723 02:18:01.694154 26575 replica.cpp:676] Persisted action at 1 I0723 02:18:01.694160 26575 replica.cpp:661] Replica learned APPEND action at position 1 I0723 02:18:01.698678 26573 leveldb.cpp:343] Persisting action (29 bytes) to leveldb took 23.526821ms I0723 02:18:01.698709 26573 replica.cpp:676] Persisted action at 1 I0723 02:18:01.698719 26573 replica.cpp:661] Replica learned APPEND action at position 1 I0723 02:18:01.699117 26573 leveldb.cpp:438] Reading position from leveldb took 20227ns [ OK ] LogTest.WriteRead (6624 ms) [ RUN ] LogTest.Position Using temporary directory '/tmp/LogTest_Position_f1Gu2C' I0723 02:18:02.097327 26537 leveldb.cpp:176] Opened db in 388.612848ms I0723 02:18:02.167786 26537 leveldb.cpp:183] Compacted db in 70.388284ms I0723 02:18:02.167856 26537 leveldb.cpp:198] Created db iterator in 6243ns I0723 02:18:02.167865 26537 leveldb.cpp:204] Seeked to beginning of db in 1091ns I0723 02:18:02.167870 26537 leveldb.cpp:273] Iterated through 0 keys in the db in 180ns I0723 02:18:02.167889 26537 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 02:18:02.267482 26572 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 99.236386ms I0723 02:18:02.267544 26572 replica.cpp:320] Persisted replica status to VOTING I0723 02:18:02.596209 26537 leveldb.cpp:176] Opened db in 328.373722ms I0723 02:18:02.602221 26537 leveldb.cpp:183] Compacted db in 5.951668ms I0723 02:18:02.602241 26537 leveldb.cpp:198] Created db iterator in 5563ns I0723 02:18:02.602249 26537 leveldb.cpp:204] Seeked to beginning of db in 998ns I0723 02:18:02.602254 26537 leveldb.cpp:273] Iterated through 0 keys in the db in 273ns I0723 02:18:02.602273 26537 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 02:18:02.698930 26571 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 96.484534ms I0723 02:18:02.698979 26571 replica.cpp:320] Persisted replica status to VOTING I0723 02:18:02.990617 26537 leveldb.cpp:176] Opened db in 291.334119ms I0723 02:18:03.214690 26537 leveldb.cpp:183] Compacted db in 224.005865ms I0723 02:18:03.214766 26537 leveldb.cpp:198] Created db iterator in 8941ns I0723 02:18:03.214792 26537 leveldb.cpp:204] Seeked to beginning of db in 17424ns I0723 02:18:03.214818 26537 leveldb.cpp:273] Iterated through 1 keys in the db in 18525ns I0723 02:18:03.214835 26537 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 02:18:03.338562 26537 leveldb.cpp:176] Opened db in 123.631516ms I0723 02:18:03.438832 26537 leveldb.cpp:183] Compacted db in 100.201161ms I0723 02:18:03.438909 26537 leveldb.cpp:198] Created db iterator in 9154ns I0723 02:18:03.438935 26537 leveldb.cpp:204] Seeked to beginning of db in 16789ns I0723 02:18:03.438961 26537 leveldb.cpp:273] Iterated through 1 keys in the db in 19072ns I0723 02:18:03.438978 26537 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 02:18:03.439287 26571 recover.cpp:425] Starting replica recovery I0723 02:18:03.439410 26570 recover.cpp:451] Replica is in VOTING status I0723 02:18:03.439450 26570 recover.cpp:440] Recover process terminated I0723 02:18:03.439537 26574 log.cpp:656] Attempting to start the writer I0723 02:18:03.439862 26571 replica.cpp:474] Replica received implicit promise request with proposal 1 I0723 02:18:03.439889 26574 replica.cpp:474] Replica received implicit promise request with proposal 1 I0723 02:18:03.478307 26574 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 38.407423ms I0723 02:18:03.478343 26574 replica.cpp:342] Persisted promised to 1 I0723 02:18:03.478312 26571 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 38.43023ms I0723 02:18:03.478410 26571 replica.cpp:342] Persisted promised to 1 I0723 02:18:03.478560 26575 coordinator.cpp:230] Coordinator attemping to fill missing position I0723 02:18:03.478862 26575 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2 I0723 02:18:03.478924 26573 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2 I0723 02:18:03.486047 26575 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 7.168093ms I0723 02:18:03.486062 26575 replica.cpp:676] Persisted action at 0 I0723 02:18:03.489328 26573 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 10.395741ms I0723 02:18:03.489343 26573 replica.cpp:676] Persisted action at 0 I0723 02:18:03.489614 26571 replica.cpp:508] Replica received write request for position 0 I0723 02:18:03.489635 26571 leveldb.cpp:438] Reading position from leveldb took 12132ns I0723 02:18:03.489682 26575 replica.cpp:508] Replica received write request for position 0 I0723 02:18:03.489696 26575 leveldb.cpp:438] Reading position from leveldb took 5497ns I0723 02:18:03.494011 26571 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 4.363825ms I0723 02:18:03.494025 26571 replica.cpp:676] Persisted action at 0 I0723 02:18:03.501297 26575 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 11.592193ms I0723 02:18:03.501312 26575 replica.cpp:676] Persisted action at 0 I0723 02:18:03.501391 26575 replica.cpp:655] Replica received learned notice for position 0 I0723 02:18:03.501438 26571 replica.cpp:655] Replica received learned notice for position 0 I0723 02:18:03.505978 26575 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 4.575988ms I0723 02:18:03.505992 26575 replica.cpp:676] Persisted action at 0 I0723 02:18:03.506000 26575 replica.cpp:661] Replica learned NOP action at position 0 I0723 02:18:03.510304 26571 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 8.856383ms I0723 02:18:03.510318 26571 replica.cpp:676] Persisted action at 0 I0723 02:18:03.510324 26571 replica.cpp:661] Replica learned NOP action at position 0 I0723 02:18:03.510417 26571 log.cpp:672] Writer started with ending position 0 I0723 02:18:03.510565 26571 log.cpp:680] Attempting to append 11 bytes to the log I0723 02:18:03.510615 26571 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1 I0723 02:18:03.510784 26571 replica.cpp:508] Replica received write request for position 1 I0723 02:18:03.510843 26576 replica.cpp:508] Replica received write request for position 1 I0723 02:18:03.513939 26571 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 3.143922ms I0723 02:18:03.513967 26571 replica.cpp:676] Persisted action at 1 I0723 02:18:03.522253 26576 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 11.398334ms I0723 02:18:03.522269 26576 replica.cpp:676] Persisted action at 1 I0723 02:18:03.522400 26576 replica.cpp:655] Replica received learned notice for position 1 I0723 02:18:03.522457 26571 replica.cpp:655] Replica received learned notice for position 1 I0723 02:18:03.525913 26576 leveldb.cpp:343] Persisting action (29 bytes) to leveldb took 3.503837ms I0723 02:18:03.525928 26576 replica.cpp:676] Persisted action at 1 I0723 02:18:03.525934 26576 replica.cpp:661] Replica learned APPEND action at position 1 I0723 02:18:03.537183 26571 leveldb.cpp:343] Persisting action (29 bytes) to leveldb took 14.716382ms I0723 02:18:03.537199 26571 replica.cpp:676] Persisted action at 1 I0723 02:18:03.537205 26571 replica.cpp:661] Replica learned APPEND action at position 1 [ OK ] LogTest.Position (1838 ms) [----------] 2 tests from LogTest (8462 ms total) [----------] 17 tests from AuthorizationTest [ RUN ] AuthorizationTest.AnyPrincipalRunAsUser Using temporary directory '/tmp/AuthorizationTest_AnyPrincipalRunAsUser_rnTXY1' [ OK ] AuthorizationTest.AnyPrincipalRunAsUser (1 ms) [ RUN ] AuthorizationTest.SomePrincipalOnlySomeUser Using temporary directory '/tmp/AuthorizationTest_SomePrincipalOnlySomeUser_0nczVq' [ OK ] AuthorizationTest.SomePrincipalOnlySomeUser (0 ms) [ RUN ] AuthorizationTest.PrincipalRunAsSomeUserRestrictive Using temporary directory '/tmp/AuthorizationTest_PrincipalRunAsSomeUserRestrictive_sfAhSP' [ OK ] AuthorizationTest.PrincipalRunAsSomeUserRestrictive (0 ms) [ RUN ] AuthorizationTest.PrincipalNotOfferedAnyRoleRestrictive Using temporary directory '/tmp/AuthorizationTest_PrincipalNotOfferedAnyRoleRestrictive_YVO7Oe' [ OK ] AuthorizationTest.PrincipalNotOfferedAnyRoleRestrictive (1 ms) [ RUN ] AuthorizationTest.OnlySomePrincipalsRunAsSomeUsers Using temporary directory '/tmp/AuthorizationTest_OnlySomePrincipalsRunAsSomeUsers_vS36LD' [ OK ] AuthorizationTest.OnlySomePrincipalsRunAsSomeUsers (1 ms) [ RUN ] AuthorizationTest.SomeClientsPUTSomeURL Using temporary directory '/tmp/AuthorizationTest_SomeClientsPUTSomeURL_bXJfJ2' [ OK ] AuthorizationTest.SomeClientsPUTSomeURL (1 ms) [ RUN ] AuthorizationTest.PrincipalRunAsAnyUser Using temporary directory '/tmp/AuthorizationTest_PrincipalRunAsAnyUser_iNsvGr' [ OK ] AuthorizationTest.PrincipalRunAsAnyUser (0 ms) [ RUN ] AuthorizationTest.PrincipalOfferedRole Using temporary directory '/tmp/AuthorizationTest_PrincipalOfferedRole_JAlQDQ' [ OK ] AuthorizationTest.PrincipalOfferedRole (1 ms) [ RUN ] AuthorizationTest.SomeClientsCannotGETAnyURL Using temporary directory '/tmp/AuthorizationTest_SomeClientsCannotGETAnyURL_ypziBf' [ OK ] AuthorizationTest.SomeClientsCannotGETAnyURL (0 ms) [ RUN ] AuthorizationTest.NoClientsCanGETPUTAnyURLRestrictive Using temporary directory '/tmp/AuthorizationTest_NoClientsCanGETPUTAnyURLRestrictive_IeFRyE' [ OK ] AuthorizationTest.NoClientsCanGETPUTAnyURLRestrictive (0 ms) [ RUN ] AuthorizationTest.NoClientGETPUTSomeURL Using temporary directory '/tmp/AuthorizationTest_NoClientGETPUTSomeURL_EJUxw3' [ OK ] AuthorizationTest.NoClientGETPUTSomeURL (1 ms) [ RUN ] AuthorizationTest.AnyClientGETSomeURL Using temporary directory '/tmp/AuthorizationTest_AnyClientGETSomeURL_9z4lus' [ OK ] AuthorizationTest.AnyClientGETSomeURL (9 ms) [ RUN ] AuthorizationTest.NoPrincipalRunAsUser Using temporary directory '/tmp/AuthorizationTest_NoPrincipalRunAsUser_jReJtR' [ OK ] AuthorizationTest.NoPrincipalRunAsUser (1 ms) [ RUN ] AuthorizationTest.AnyPrincipalRunAsAnyUser Using temporary directory '/tmp/AuthorizationTest_AnyPrincipalRunAsAnyUser_DBvatg' [ OK ] AuthorizationTest.AnyPrincipalRunAsAnyUser (0 ms) [ RUN ] AuthorizationTest.SomePrincipalsOfferedRole Using temporary directory '/tmp/AuthorizationTest_SomePrincipalsOfferedRole_TNTFsF' [ OK ] AuthorizationTest.SomePrincipalsOfferedRole (0 ms) [ RUN ] AuthorizationTest.AnyPrincipalOfferedRole Using temporary directory '/tmp/AuthorizationTest_AnyPrincipalOfferedRole_Npggs4' [ OK ] AuthorizationTest.AnyPrincipalOfferedRole (0 ms) [ RUN ] AuthorizationTest.SomeClientsAggregatePUTRequestRestrictive Using temporary directory '/tmp/AuthorizationTest_SomeClientsAggregatePUTRequestRestrictive_ambVrt' [ OK ] AuthorizationTest.SomeClientsAggregatePUTRequestRestrictive (1 ms) [----------] 17 tests from AuthorizationTest (20 ms total) [----------] 1 test from ZooKeeper [ RUN ] ZooKeeper.URL [ OK ] ZooKeeper.URL (0 ms) [----------] 1 test from ZooKeeper (0 ms total) [----------] 5 tests from GarbageCollectorIntegrationTest [ RUN ] GarbageCollectorIntegrationTest.Unschedule Using temporary directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_AdkJrS' I0723 02:18:03.692968 26537 leveldb.cpp:176] Opened db in 133.957385ms I0723 02:18:03.717591 26537 leveldb.cpp:183] Compacted db in 24.597023ms I0723 02:18:03.717609 26537 leveldb.cpp:198] Created db iterator in 3617ns I0723 02:18:03.717617 26537 leveldb.cpp:204] Seeked to beginning of db in 689ns I0723 02:18:03.717622 26537 leveldb.cpp:273] Iterated through 0 keys in the db in 331ns I0723 02:18:03.717634 26537 replica.cpp:741] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0723 02:18:03.717854 26572 recover.cpp:425] Starting replica recovery I0723 02:18:03.717953 26572 recover.cpp:451] Replica is in EMPTY status I0723 02:18:03.718416 26571 replica.cpp:638] Replica in EMPTY status received a broadcasted recover request I0723 02:18:03.718639 26577 recover.cpp:188] Received a recover response from a replica in EMPTY status I0723 02:18:03.718844 26571 recover.cpp:542] Updating replica status to STARTING I0723 02:18:03.719818 26575 master.cpp:289] Master 20140723-021803-160088899-42310-26537 (vesta.apache.org) started on 67.195.138.9:42310 I0723 02:18:03.719876 26575 master.cpp:326] Master only allowing authenticated frameworks to register I0723 02:18:03.719885 26575 master.cpp:331] Master only allowing authenticated slaves to register I0723 02:18:03.719898 26575 credentials.hpp:36] Loading credentials for authentication from '/tmp/GarbageCollectorIntegrationTest_Unschedule_AdkJrS/credentials' I0723 02:18:03.720155 26575 master.cpp:360] Authorization enabled I0723 02:18:03.720485 26577 master.cpp:123] No whitelist given. Advertising offers for all slaves I0723 02:18:03.720554 26574 hierarchical_allocator_process.hpp:301] Initializing hierarchical allocator process with master : master@67.195.138.9:42310 I0723 02:18:03.721925 26573 master.cpp:1129] The newly elected leader is master@67.195.138.9:42310 with id 20140723-021803-160088899-42310-26537 I0723 02:18:03.721961 26573 master.cpp:1142] Elected as the leading master! I0723 02:18:03.721976 26573 master.cpp:960] Recovering from registrar I0723 02:18:03.722082 26572 registrar.cpp:313] Recovering registrar I0723 02:18:03.804371 26576 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 85.449675ms I0723 02:18:03.804425 26576 replica.cpp:320] Persisted replica status to STARTING I0723 02:18:03.804555 26572 recover.cpp:451] Replica is in STARTING status I0723 02:18:03.804999 26571 replica.cpp:638] Replica in STARTING status received a broadcasted recover request I0723 02:18:03.805147 26572 recover.cpp:188] Received a recover response from a replica in STARTING status I0723 02:18:03.805316 26577 recover.cpp:542] Updating replica status to VOTING I0723 02:18:03.817437 26572 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 12.062908ms I0723 02:18:03.817482 26572 replica.cpp:320] Persisted replica status to VOTING I0723 02:18:03.817548 26574 recover.cpp:556] Successfully joined the Paxos group I0723 02:18:03.817648 26574 recover.cpp:440] Recover process terminated I0723 02:18:03.817844 26577 log.cpp:656] Attempting to start the writer I0723 02:18:03.818308 26575 replica.cpp:474] Replica received implicit promise request with proposal 1 I0723 02:18:03.827908 26575 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 9.573439ms I0723 02:18:03.827949 26575 replica.cpp:342] Persisted promised to 1 I0723 02:18:03.828222 26571 coordinator.cpp:230] Coordinator attemping to fill missing position I0723 02:18:03.828791 26576 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2 I0723 02:18:03.839819 26576 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 11.006555ms I0723 02:18:03.839848 26576 replica.cpp:676] Persisted action at 0 I0723 02:18:03.840307 26576 replica.cpp:508] Replica received write request for position 0 I0723 02:18:03.840337 26576 leveldb.cpp:438] Reading position from leveldb took 15195ns I0723 02:18:03.851794 26576 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 11.436437ms I0723 02:18:03.851835 26576 replica.cpp:676] Persisted action at 0 I0723 02:18:03.852035 26576 replica.cpp:655] Replica received learned notice for position 0 I0723 02:18:03.861275 26576 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 9.226798ms I0723 02:18:03.861302 26576 replica.cpp:676] Persisted action at 0 I0723 02:18:03.861311 26576 replica.cpp:661] Replica learned NOP action at position 0 I0723 02:18:03.861644 26570 log.cpp:672] Writer started with ending position 0 I0723 02:18:03.862133 26576 leveldb.cpp:438] Reading position from leveldb took 11801ns I0723 02:18:03.863301 26576 registrar.cpp:346] Successfully fetched the registry (0B) I0723 02:18:03.863337 26576 registrar.cpp:422] Attempting to update the 'registry' I0723 02:18:03.864505 26571 log.cpp:680] Attempting to append 134 bytes to the log I0723 02:18:03.864604 26574 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1 I0723 02:18:03.865085 26574 replica.cpp:508] Replica received write request for position 1 I0723 02:18:03.875722 26574 leveldb.cpp:343] Persisting action (153 bytes) to leveldb took 10.61247ms I0723 02:18:03.875772 26574 replica.cpp:676] Persisted action at 1 I0723 02:18:03.876096 26572 replica.cpp:655] Replica received learned notice for position 1 I0723 02:18:03.885216 26572 leveldb.cpp:343] Persisting action (155 bytes) to leveldb took 9.083506ms I0723 02:18:03.885246 26572 replica.cpp:676] Persisted action at 1 I0723 02:18:03.885257 26572 replica.cpp:661] Replica learned APPEND action at position 1 I0723 02:18:03.885717 26572 registrar.cpp:479] Successfully updated 'registry' I0723 02:18:03.885756 26572 registrar.cpp:372] Successfully recovered registrar I0723 02:18:03.885807 26572 log.cpp:699] Attempting to truncate the log to 1 I0723 02:18:03.885898 26572 master.cpp:987] Recovered 0 slaves from the Registry (97B) ; allowing 10mins for slaves to re-register I0723 02:18:03.885938 26572 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2 I0723 02:18:03.886354 26577 replica.cpp:508] Replica received write request for position 2 I0723 02:18:03.895622 26577 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 9.228847ms I0723 02:18:03.895673 26577 replica.cpp:676] Persisted action at 2 I0723 02:18:03.896041 26574 replica.cpp:655] Replica received learned notice for position 2 I0723 02:18:03.905140 26574 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 9.079844ms I0723 02:18:03.905174 26574 leveldb.cpp:401] Deleting ~1 keys from leveldb took 12410ns I0723 02:18:03.905184 26574 replica.cpp:676] Persisted action at 2 I0723 02:18:03.905194 26574 replica.cpp:661] Replica learned TRUNCATE action at position 2 I0723 02:18:03.909507 26577 slave.cpp:169] Slave started on 1)@67.195.138.9:42310 I0723 02:18:03.909530 26577 credentials.hpp:84] Loading credential for authentication from '/tmp/GarbageCollectorIntegrationTest_Unschedule_VWsnoi/credential' I0723 02:18:03.909673 26577 slave.cpp:267] Slave using credential for: test-principal make[3]: *** [check-local] Segmentation fault make[3]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make[2]: *** [check-am] Error 2 make[2]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make[1]: *** [check] Error 2 make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src' make: *** [check-recursive] Error 1 Build step 'Execute shell' marked build as failure