See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2769/changes>
Changes: [benjamin.mahler] Fixed a style issue in the test launcher. ------------------------------------------ [...truncated 73598 lines...] I0224 23:24:43.264854 30611 replica.cpp:477] Replica received implicit promise request with proposal 1 I0224 23:24:43.265419 30611 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 522680ns I0224 23:24:43.265450 30611 replica.cpp:345] Persisted promised to 1 I0224 23:24:43.266196 30611 replica.cpp:511] Replica received write request for position 1 I0224 23:24:43.266633 30611 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 399003ns I0224 23:24:43.266664 30611 replica.cpp:679] Persisted action at 1 I0224 23:24:43.267392 30603 leveldb.cpp:438] Reading position from leveldb took 43309ns [ OK ] ReplicaTest.Append (15 ms) [ RUN ] ReplicaTest.Restore Using temporary directory '/tmp/ReplicaTest_Restore_qPPk2P' I0224 23:24:43.270603 30589 leveldb.cpp:176] Opened db in 2.071789ms I0224 23:24:43.271622 30589 leveldb.cpp:183] Compacted db in 992394ns I0224 23:24:43.271656 30589 leveldb.cpp:198] Created db iterator in 10538ns I0224 23:24:43.271672 30589 leveldb.cpp:204] Seeked to beginning of db in 1317ns I0224 23:24:43.271679 30589 leveldb.cpp:273] Iterated through 0 keys in the db in 258ns I0224 23:24:43.271700 30589 replica.cpp:744] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0224 23:24:43.272707 30616 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 791193ns I0224 23:24:43.272742 30616 replica.cpp:323] Persisted replica status to 1 I0224 23:24:43.275786 30589 leveldb.cpp:176] Opened db in 2.530228ms I0224 23:24:43.278626 30589 leveldb.cpp:183] Compacted db in 2.810496ms I0224 23:24:43.278669 30589 leveldb.cpp:198] Created db iterator in 15115ns I0224 23:24:43.278700 30589 leveldb.cpp:204] Seeked to beginning of db in 16562ns I0224 23:24:43.278728 30589 leveldb.cpp:273] Iterated through 1 keys in the db in 17125ns I0224 23:24:43.278753 30589 replica.cpp:744] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0224 23:24:43.279011 30607 replica.cpp:477] Replica received implicit promise request with proposal 1 I0224 23:24:43.279803 30607 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 749127ns I0224 23:24:43.279836 30607 replica.cpp:345] Persisted promised to 1 I0224 23:24:43.280689 30608 replica.cpp:511] Replica received write request for position 1 I0224 23:24:43.281257 30608 leveldb.cpp:343] Persisting action (27 bytes) to leveldb took 535421ns I0224 23:24:43.281333 30608 replica.cpp:679] Persisted action at 1 I0224 23:24:43.281960 30618 leveldb.cpp:438] Reading position from leveldb took 37962ns I0224 23:24:43.285873 30589 leveldb.cpp:176] Opened db in 3.117624ms I0224 23:24:43.288627 30589 leveldb.cpp:183] Compacted db in 2.716969ms I0224 23:24:43.288691 30589 leveldb.cpp:198] Created db iterator in 22976ns I0224 23:24:43.288729 30589 leveldb.cpp:204] Seeked to beginning of db in 18855ns I0224 23:24:43.288792 30589 leveldb.cpp:273] Iterated through 2 keys in the db in 46966ns I0224 23:24:43.288859 30589 replica.cpp:744] Replica recovered with log positions 0 -> 1 with 1 holes and 1 unlearned I0224 23:24:43.289180 30610 leveldb.cpp:438] Reading position from leveldb took 137487ns [ OK ] ReplicaTest.Restore (22 ms) [----------] 4 tests from ReplicaTest (79 ms total) [----------] 4 tests from SorterTest [ RUN ] SorterTest.DRFSorter [ OK ] SorterTest.DRFSorter (2 ms) [ RUN ] SorterTest.SplitResourceShares [ OK ] SorterTest.SplitResourceShares (0 ms) [ RUN ] SorterTest.WDRFSorter [ OK ] SorterTest.WDRFSorter (2 ms) [ RUN ] SorterTest.Update [ OK ] SorterTest.Update (0 ms) [----------] 4 tests from SorterTest (4 ms total) [----------] 1 test from HealthTest [ RUN ] HealthTest.ObserveEndpoint Using temporary directory '/tmp/HealthTest_ObserveEndpoint_BrOFOP' I0224 23:24:43.298955 30589 leveldb.cpp:176] Opened db in 2.702986ms I0224 23:24:43.299968 30589 leveldb.cpp:183] Compacted db in 976189ns I0224 23:24:43.300016 30589 leveldb.cpp:198] Created db iterator in 13129ns I0224 23:24:43.300037 30589 leveldb.cpp:204] Seeked to beginning of db in 1815ns I0224 23:24:43.300047 30589 leveldb.cpp:273] Iterated through 0 keys in the db in 330ns I0224 23:24:43.300077 30589 replica.cpp:744] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0224 23:24:43.300639 30605 recover.cpp:449] Starting replica recovery I0224 23:24:43.300912 30605 recover.cpp:475] Replica is in 4 status I0224 23:24:43.302189 30604 replica.cpp:641] Replica in 4 status received a broadcasted recover request I0224 23:24:43.302718 30613 recover.cpp:195] Received a recover response from a replica in 4 status I0224 23:24:43.303071 30607 recover.cpp:566] Updating replica status to 3 I0224 23:24:43.303583 30606 master.cpp:349] Master 20150224-232443-3142697795-38391-30589 (pomona.apache.org) started on 67.195.81.187:38391 I0224 23:24:43.303719 30606 master.cpp:395] Master only allowing authenticated frameworks to register I0224 23:24:43.303772 30606 master.cpp:400] Master only allowing authenticated slaves to register I0224 23:24:43.303815 30606 credentials.hpp:37] Loading credentials for authentication from '/tmp/HealthTest_ObserveEndpoint_BrOFOP/credentials' I0224 23:24:43.303989 30618 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 656119ns I0224 23:24:43.304013 30618 replica.cpp:323] Persisted replica status to 3 I0224 23:24:43.304044 30606 master.cpp:442] Authorization enabled I0224 23:24:43.304319 30617 recover.cpp:475] Replica is in 3 status I0224 23:24:43.304613 30614 whitelist_watcher.cpp:79] No whitelist given I0224 23:24:43.304688 30617 hierarchical.hpp:287] Initialized hierarchical allocator process I0224 23:24:43.305397 30608 replica.cpp:641] Replica in 3 status received a broadcasted recover request I0224 23:24:43.305654 30606 master.cpp:1356] The newly elected leader is [email protected]:38391 with id 20150224-232443-3142697795-38391-30589 I0224 23:24:43.305680 30606 master.cpp:1369] Elected as the leading master! I0224 23:24:43.305696 30606 master.cpp:1187] Recovering from registrar I0224 23:24:43.305791 30609 registrar.cpp:313] Recovering registrar I0224 23:24:43.306355 30614 recover.cpp:195] Received a recover response from a replica in 3 status I0224 23:24:43.306690 30616 recover.cpp:566] Updating replica status to 1 I0224 23:24:43.307319 30607 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 471046ns I0224 23:24:43.307348 30607 replica.cpp:323] Persisted replica status to 1 I0224 23:24:43.307440 30605 recover.cpp:580] Successfully joined the Paxos group I0224 23:24:43.307687 30605 recover.cpp:464] Recover process terminated I0224 23:24:43.308090 30613 log.cpp:660] Attempting to start the writer I0224 23:24:43.309535 30604 replica.cpp:477] Replica received implicit promise request with proposal 1 I0224 23:24:43.309991 30604 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 428836ns I0224 23:24:43.310012 30604 replica.cpp:345] Persisted promised to 1 I0224 23:24:43.310864 30613 coordinator.cpp:230] Coordinator attemping to fill missing position I0224 23:24:43.312067 30606 replica.cpp:378] Replica received explicit promise request for position 0 with proposal 2 I0224 23:24:43.312515 30606 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 419380ns I0224 23:24:43.312536 30606 replica.cpp:679] Persisted action at 0 I0224 23:24:43.313459 30604 replica.cpp:511] Replica received write request for position 0 I0224 23:24:43.313505 30604 leveldb.cpp:438] Reading position from leveldb took 23451ns I0224 23:24:43.313957 30604 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 423524ns I0224 23:24:43.313978 30604 replica.cpp:679] Persisted action at 0 I0224 23:24:43.314461 30611 replica.cpp:658] Replica received learned notice for position 0 I0224 23:24:43.314920 30611 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 433991ns I0224 23:24:43.314941 30611 replica.cpp:679] Persisted action at 0 I0224 23:24:43.314956 30611 replica.cpp:664] Replica learned 1 action at position 0 I0224 23:24:43.315454 30605 log.cpp:676] Writer started with ending position 0 I0224 23:24:43.316376 30609 leveldb.cpp:438] Reading position from leveldb took 24621ns I0224 23:24:43.318526 30613 registrar.cpp:346] Successfully fetched the registry (0B) in 12.660992ms I0224 23:24:43.318621 30613 registrar.cpp:445] Applied 1 operations in 21449ns; attempting to update the 'registry' I0224 23:24:43.320602 30603 log.cpp:684] Attempting to append 139 bytes to the log I0224 23:24:43.320705 30617 coordinator.cpp:340] Coordinator attempting to write 2 action at position 1 I0224 23:24:43.321363 30604 replica.cpp:511] Replica received write request for position 1 I0224 23:24:43.321965 30604 leveldb.cpp:343] Persisting action (158 bytes) to leveldb took 573123ns I0224 23:24:43.321990 30604 replica.cpp:679] Persisted action at 1 I0224 23:24:43.322602 30610 replica.cpp:658] Replica received learned notice for position 1 I0224 23:24:43.323130 30610 leveldb.cpp:343] Persisting action (160 bytes) to leveldb took 502467ns I0224 23:24:43.323156 30610 replica.cpp:679] Persisted action at 1 I0224 23:24:43.323174 30610 replica.cpp:664] Replica learned 2 action at position 1 I0224 23:24:43.323870 30617 registrar.cpp:490] Successfully updated the 'registry' in 5.199872ms I0224 23:24:43.323982 30617 registrar.cpp:376] Successfully recovered registrar I0224 23:24:43.324081 30608 log.cpp:703] Attempting to truncate the log to 1 I0224 23:24:43.324206 30611 master.cpp:1214] Recovered 0 slaves from the Registry (101B) ; allowing 10mins for slaves to re-register I0224 23:24:43.324302 30604 coordinator.cpp:340] Coordinator attempting to write 3 action at position 2 I0224 23:24:43.325017 30604 replica.cpp:511] Replica received write request for position 2 I0224 23:24:43.325666 30604 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 620436ns I0224 23:24:43.325692 30604 replica.cpp:679] Persisted action at 2 I0224 23:24:43.326210 30609 replica.cpp:658] Replica received learned notice for position 2 I0224 23:24:43.326706 30609 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 475495ns I0224 23:24:43.326756 30609 leveldb.cpp:401] Deleting ~1 keys from leveldb took 28992ns I0224 23:24:43.326772 30609 replica.cpp:679] Persisted action at 2 I0224 23:24:43.326788 30609 replica.cpp:664] Replica learned 3 action at position 2 I0224 23:24:43.336171 30617 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.336381 30617 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.338994 30605 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.339126 30605 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.341542 30603 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.341717 30603 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.345664 30611 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.345798 30611 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.348458 30603 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.348601 30603 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.351164 30611 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.351310 30611 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.353799 30614 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.353957 30614 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.356654 30603 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.356806 30603 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.359443 30604 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.359570 30604 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.362413 30615 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.362543 30615 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.365231 30613 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.365386 30613 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.368157 30614 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.368327 30614 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.371148 30606 process.cpp:2770] Handling HTTP event for process 'master' with path: '/master/observe' I0224 23:24:43.371325 30606 http.cpp:281] HTTP request for '/master/observe' I0224 23:24:43.373327 30589 master.cpp:787] Master terminating I0224 23:24:43.374944 30589 process.cpp:2117] Dropped / Lost event for PID: [email protected]:38391 I0224 23:24:43.375062 30589 process.cpp:2117] Dropped / Lost event for PID: [email protected]:38391 [ OK ] HealthTest.ObserveEndpoint (83 ms) [----------] 1 test from HealthTest (83 ms total) [----------] 6 tests from FilesTest [ RUN ] FilesTest.DownloadTest Using temporary directory '/tmp/FilesTest_DownloadTest_tzzpOP' I0224 23:24:43.379614 30604 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/download.json' I0224 23:24:43.379969 30607 process.cpp:1108] Sending file at '/tmp/FilesTest_DownloadTest_tzzpOP/binary' with length 17 I0224 23:24:43.382921 30617 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/download.json' I0224 23:24:43.383385 30609 process.cpp:1108] Sending file at '/tmp/FilesTest_DownloadTest_tzzpOP/black.gif' with length 42 [ OK ] FilesTest.DownloadTest (8 ms) [ RUN ] FilesTest.ReadTest Using temporary directory '/tmp/FilesTest_ReadTest_2E1qPP' I0224 23:24:43.386620 30603 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.389230 30615 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.392185 30616 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.395766 30617 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.398946 30617 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.401861 30606 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' [ OK ] FilesTest.ReadTest (18 ms) [ RUN ] FilesTest.ResolveTest Using temporary directory '/tmp/FilesTest_ResolveTest_DDDqTP' I0224 23:24:43.406972 30610 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.409782 30614 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.412672 30604 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.415770 30605 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.418717 30606 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.421890 30606 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.425068 30618 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.427817 30618 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.430344 30617 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' I0224 23:24:43.432948 30611 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/read.json' [ OK ] FilesTest.ResolveTest (31 ms) [ RUN ] FilesTest.AttachTest Using temporary directory '/tmp/FilesTest_AttachTest_Yfnv2P' [ OK ] FilesTest.AttachTest (3 ms) [ RUN ] FilesTest.DetachTest Using temporary directory '/tmp/FilesTest_DetachTest_3gS3bQ' [ OK ] FilesTest.DetachTest (1 ms) [ RUN ] FilesTest.BrowseTest Using temporary directory '/tmp/FilesTest_BrowseTest_yqFJlQ' I0224 23:24:43.441184 30617 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/browse.json' I0224 23:24:43.444715 30613 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/browse.json' I0224 23:24:43.448369 30617 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/browse.json' I0224 23:24:43.451839 30606 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/browse.json' I0224 23:24:43.454727 30611 process.cpp:2770] Handling HTTP event for process 'files' with path: '/files/browse.json' [ OK ] FilesTest.BrowseTest (18 ms) [----------] 6 tests from FilesTest (79 ms total) [----------] 1 test from AnonymousTest [ RUN ] AnonymousTest.Running Using temporary directory '/tmp/AnonymousTest_Running_x2wkyQ' I0224 23:24:43.456720 30589 test_anonymous_module.cpp:39] Anonymous module constructor I0224 23:24:43.456763 30589 test_anonymous_module.cpp:48] Anonymous module destructor [ OK ] AnonymousTest.Running (0 ms) [----------] 1 test from AnonymousTest (0 ms total) [----------] 1 test from ResourcesOperationTest [ RUN ] ResourcesOperationTest.CreatePersistentVolume [ OK ] ResourcesOperationTest.CreatePersistentVolume (1 ms) [----------] 1 test from ResourcesOperationTest (1 ms total) [----------] 2 tests from BasicMasterContenderDetectorTest [ RUN ] BasicMasterContenderDetectorTest.Detector I0224 23:24:43.607965 30619 process.cpp:2117] Dropped / Lost event for PID: hierarchical-allocator(254)@67.195.81.187:38391 [ OK ] BasicMasterContenderDetectorTest.Detector (173 ms) [ RUN ] BasicMasterContenderDetectorTest.Contender I0224 23:24:43.668346 30619 process.cpp:2117] Dropped / Lost event for PID: [email protected]:38391 [ OK ] BasicMasterContenderDetectorTest.Contender (176 ms) [----------] 2 tests from BasicMasterContenderDetectorTest (349 ms total) [----------] Global test environment tear-down [==========] 495 tests from 78 test cases ran. (153043 ms total) [ PASSED ] 494 tests. [ FAILED ] 1 test, listed below: [ FAILED ] MasterAllocatorTest/0.FrameworkReregistersFirst, where TypeParam = mesos::internal::master::allocator::MesosAllocator<mesos::internal::master::allocator::HierarchicalAllocatorProcess<mesos::internal::master::allocator::DRFSorter, mesos::internal::master::allocator::DRFSorter> > 1 FAILED TEST YOU HAVE 8 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 Recording test results
