See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2229/changes>
Changes: [idownes] Define MNT_DETACH for pid isolator. ------------------------------------------ [...truncated 69433 lines...] I1029 20:01:09.767547 27824 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1029 20:01:09.767561 27824 authenticator.hpp:381] Authentication success I1029 20:01:09.767650 27824 authenticatee.hpp:310] Authentication success I1029 20:01:09.767752 27820 master.cpp:3893] Successfully authenticated principal 'test-principal' at [email protected]:41000 I1029 20:01:09.768019 27824 sched.cpp:357] Successfully authenticated with master [email protected]:41000 I1029 20:01:09.768048 27824 sched.cpp:476] Sending registration request to [email protected]:41000 I1029 20:01:09.768321 27820 master.cpp:1362] Received registration request for framework 'framework3' at [email protected]:41000 I1029 20:01:09.768384 27820 master.cpp:1321] Authorizing framework principal 'test-principal' to receive offers for role 'role3' I1029 20:01:09.768911 27823 master.cpp:1426] Registering framework 20141029-200107-3193029443-41000-27796-0002 (framework3) at [email protected]:41000 I1029 20:01:09.769162 27817 sched.cpp:407] Framework registered with 20141029-200107-3193029443-41000-27796-0002 I1029 20:01:09.769201 27817 sched.cpp:421] Scheduler::registered took 16443ns I1029 20:01:09.769261 27825 hierarchical_allocator_process.hpp:329] Added framework 20141029-200107-3193029443-41000-27796-0002 I1029 20:01:09.769722 27825 hierarchical_allocator_process.hpp:734] Offering cpus(role3):4; mem(role3):4096; disk(role3):0; ports(role3):[31000-32000] on slave 20141029-200107-3193029443-41000-27796-S2 to framework 20141029-200107-3193029443-41000-27796-0002 I1029 20:01:09.770187 27825 hierarchical_allocator_process.hpp:659] Performed allocation for 4 slaves in 903391ns I1029 20:01:09.770460 27819 master.cpp:3795] Sending 1 offers to framework 20141029-200107-3193029443-41000-27796-0002 (framework3) at [email protected]:41000 I1029 20:01:09.770774 27816 sched.cpp:544] Scheduler::resourceOffers took 45045ns I1029 20:01:09.772645 27796 containerizer.cpp:100] Using isolation: posix/cpu,posix/mem I1029 20:01:09.776811 27820 slave.cpp:169] Slave started on 221)@67.195.81.190:41000 I1029 20:01:09.776852 27820 credentials.hpp:84] Loading credential for authentication from '/tmp/ReservationAllocatorTest_ReservedResources_amUsCX/credential' I1029 20:01:09.777021 27820 slave.cpp:276] Slave using credential for: test-principal I1029 20:01:09.777241 27820 slave.cpp:289] Slave resources: cpus(role1):1; mem(role1):512; disk(role1):0; ports(role1):[31000-32000] I1029 20:01:09.777340 27820 slave.cpp:318] Slave hostname: pietas.apache.org I1029 20:01:09.777358 27820 slave.cpp:319] Slave checkpoint: false W1029 20:01:09.777366 27820 slave.cpp:321] Disabling checkpointing is deprecated and the --checkpoint flag will be removed in a future release. Please avoid using this flag I1029 20:01:09.778183 27811 state.cpp:33] Recovering state from '/tmp/ReservationAllocatorTest_ReservedResources_amUsCX/meta' I1029 20:01:09.778393 27817 status_update_manager.cpp:197] Recovering status update manager I1029 20:01:09.778676 27815 containerizer.cpp:281] Recovering containerizer I1029 20:01:09.779857 27816 slave.cpp:3456] Finished recovery I1029 20:01:09.780598 27816 slave.cpp:602] New master detected at [email protected]:41000 I1029 20:01:09.780638 27816 slave.cpp:665] Authenticating with master [email protected]:41000 I1029 20:01:09.780674 27825 status_update_manager.cpp:171] Pausing sending status updates I1029 20:01:09.780791 27816 slave.cpp:638] Detecting new master I1029 20:01:09.780843 27818 authenticatee.hpp:133] Creating new client SASL connection I1029 20:01:09.781141 27810 master.cpp:3853] Authenticating slave(221)@67.195.81.190:41000 I1029 20:01:09.781342 27816 authenticator.hpp:161] Creating new server SASL connection I1029 20:01:09.781508 27816 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5 I1029 20:01:09.781529 27816 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5' I1029 20:01:09.781632 27824 authenticator.hpp:267] Received SASL authentication start I1029 20:01:09.781687 27824 authenticator.hpp:389] Authentication requires more steps I1029 20:01:09.781771 27824 authenticatee.hpp:270] Received SASL authentication step I1029 20:01:09.781875 27824 authenticator.hpp:295] Received SASL authentication step I1029 20:01:09.781903 27824 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pietas.apache.org' server FQDN: 'pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1029 20:01:09.781915 27824 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I1029 20:01:09.781945 27824 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1029 20:01:09.781968 27824 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'pietas.apache.org' server FQDN: 'pietas.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1029 20:01:09.781980 27824 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1029 20:01:09.781988 27824 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1029 20:01:09.782004 27824 authenticator.hpp:381] Authentication success I1029 20:01:09.782112 27824 authenticatee.hpp:310] Authentication success I1029 20:01:09.782143 27821 master.cpp:3893] Successfully authenticated principal 'test-principal' at slave(221)@67.195.81.190:41000 I1029 20:01:09.782546 27824 slave.cpp:722] Successfully authenticated with master [email protected]:41000 I1029 20:01:09.782659 27824 slave.cpp:1050] Will retry registration in 11.769712ms if necessary I1029 20:01:09.782820 27815 master.cpp:3032] Registering slave at slave(221)@67.195.81.190:41000 (pietas.apache.org) with id 20141029-200107-3193029443-41000-27796-S4 I1029 20:01:09.783329 27817 registrar.cpp:445] Applied 1 operations in 133421ns; attempting to update the 'registry' I1029 20:01:09.785845 27817 log.cpp:680] Attempting to append 1127 bytes to the log I1029 20:01:09.795310 27810 slave.cpp:1050] Will retry registration in 37.030767ms if necessary I1029 20:01:09.795497 27814 master.cpp:3020] Ignoring register slave message from slave(221)@67.195.81.190:41000 (pietas.apache.org) as admission is already in progress I1029 20:01:09.913693 27811 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 9 I1029 20:01:09.914785 27819 replica.cpp:508] Replica received write request for position 9 I1029 20:01:09.915117 27819 leveldb.cpp:343] Persisting action (1146 bytes) to leveldb took 294042ns I1029 20:01:09.915148 27819 replica.cpp:676] Persisted action at 9 I1029 20:01:09.916208 27815 replica.cpp:655] Replica received learned notice for position 9 I1029 20:01:09.916882 27815 leveldb.cpp:343] Persisting action (1148 bytes) to leveldb took 644425ns I1029 20:01:09.916908 27815 replica.cpp:676] Persisted action at 9 I1029 20:01:09.916930 27815 replica.cpp:661] Replica learned APPEND action at position 9 I1029 20:01:09.918350 27820 registrar.cpp:490] Successfully updated the 'registry' in 134.961152ms I1029 20:01:09.918740 27813 log.cpp:699] Attempting to truncate the log to 9 I1029 20:01:09.918941 27810 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 10 I1029 20:01:09.919064 27816 slave.cpp:2522] Received ping from slave-observer(201)@67.195.81.190:41000 I1029 20:01:09.919123 27811 master.cpp:3086] Registered slave 20141029-200107-3193029443-41000-27796-S4 at slave(221)@67.195.81.190:41000 (pietas.apache.org) with cpus(role1):1; mem(role1):512; disk(role1):0; ports(role1):[31000-32000] I1029 20:01:09.919225 27816 slave.cpp:756] Registered with master [email protected]:41000; given slave ID 20141029-200107-3193029443-41000-27796-S4 I1029 20:01:09.919546 27822 status_update_manager.cpp:178] Resuming sending status updates I1029 20:01:09.919711 27810 hierarchical_allocator_process.hpp:442] Added slave 20141029-200107-3193029443-41000-27796-S4 (pietas.apache.org) with cpus(role1):1; mem(role1):512; disk(role1):0; ports(role1):[31000-32000] (and cpus(role1):1; mem(role1):512; disk(role1):0; ports(role1):[31000-32000] available) I1029 20:01:09.919790 27822 replica.cpp:508] Replica received write request for position 10 I1029 20:01:09.920136 27822 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 311126ns I1029 20:01:09.920131 27810 hierarchical_allocator_process.hpp:734] Offering cpus(role1):1; mem(role1):512; disk(role1):0; ports(role1):[31000-32000] on slave 20141029-200107-3193029443-41000-27796-S4 to framework 20141029-200107-3193029443-41000-27796-0000 I1029 20:01:09.920161 27822 replica.cpp:676] Persisted action at 10 I1029 20:01:09.920377 27810 hierarchical_allocator_process.hpp:679] Performed allocation for slave 20141029-200107-3193029443-41000-27796-S4 in 583079ns I1029 20:01:09.920769 27811 replica.cpp:655] Replica received learned notice for position 10 I1029 20:01:09.920958 27817 master.cpp:3795] Sending 1 offers to framework 20141029-200107-3193029443-41000-27796-0000 (framework1) at [email protected]:41000 I1029 20:01:09.921269 27811 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 474524ns I1029 20:01:09.921293 27813 sched.cpp:544] Scheduler::resourceOffers took 49416ns I1029 20:01:09.921353 27811 leveldb.cpp:401] Deleting ~2 keys from leveldb took 54019ns I1029 20:01:09.921383 27811 replica.cpp:676] Persisted action at 10 I1029 20:01:09.921417 27811 replica.cpp:661] Replica learned TRUNCATE action at position 10 I1029 20:01:09.921733 27814 sched.cpp:745] Stopping framework '20141029-200107-3193029443-41000-27796-0002' I1029 20:01:09.921768 27815 sched.cpp:745] Stopping framework '20141029-200107-3193029443-41000-27796-0000' I1029 20:01:09.921787 27816 sched.cpp:745] Stopping framework '20141029-200107-3193029443-41000-27796-0001' I1029 20:01:09.921880 27821 master.cpp:677] Master terminating I1029 20:01:09.924136 27816 slave.cpp:2607] [email protected]:41000 exited W1029 20:01:09.924165 27816 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected I1029 20:01:09.924201 27813 slave.cpp:2607] [email protected]:41000 exited W1029 20:01:09.924247 27813 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected I1029 20:01:09.924223 27810 slave.cpp:2607] [email protected]:41000 exited W1029 20:01:09.924288 27810 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected I1029 20:01:09.924309 27819 slave.cpp:2607] [email protected]:41000 exited I1029 20:01:09.924320 27818 slave.cpp:2607] [email protected]:41000 exited W1029 20:01:09.924342 27819 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected W1029 20:01:09.924358 27818 slave.cpp:2610] Master disconnected! Waiting for a new master to be elected I1029 20:01:09.930064 27796 slave.cpp:484] Slave terminating I1029 20:01:09.932471 27796 slave.cpp:484] Slave terminating I1029 20:01:09.934528 27796 slave.cpp:484] Slave terminating I1029 20:01:09.936530 27817 slave.cpp:484] Slave terminating I1029 20:01:09.938618 27796 slave.cpp:484] Slave terminating [ OK ] ReservationAllocatorTest.ReservedResources (2451 ms) [----------] 2 tests from ReservationAllocatorTest (3094 ms total) [----------] 2 tests from AttributesTest [ RUN ] AttributesTest.Equality [ OK ] AttributesTest.Equality (0 ms) [ RUN ] AttributesTest.Parsing [ OK ] AttributesTest.Parsing (1 ms) [----------] 2 tests from AttributesTest (1 ms total) [----------] 35 tests from ResourcesTest [ RUN ] ResourcesTest.Find [ OK ] ResourcesTest.Find (1 ms) [ RUN ] ResourcesTest.SetEquals [ OK ] ResourcesTest.SetEquals (0 ms) [ RUN ] ResourcesTest.ScalarSubset2 [ OK ] ResourcesTest.ScalarSubset2 (0 ms) [ RUN ] ResourcesTest.SetAddition [ OK ] ResourcesTest.SetAddition (0 ms) [ RUN ] ResourcesTest.ScalarAddition [ OK ] ResourcesTest.ScalarAddition (0 ms) [ RUN ] ResourcesTest.RangesSubtraction [ OK ] ResourcesTest.RangesSubtraction (0 ms) [ RUN ] ResourcesTest.Ports [ OK ] ResourcesTest.Ports (1 ms) [ RUN ] ResourcesTest.ScalarAddition2 [ OK ] ResourcesTest.ScalarAddition2 (0 ms) [ RUN ] ResourcesTest.EmptyUnequal [ OK ] ResourcesTest.EmptyUnequal (0 ms) [ RUN ] ResourcesTest.Printing [ OK ] ResourcesTest.Printing (0 ms) [ RUN ] ResourcesTest.ScalarEquals [ OK ] ResourcesTest.ScalarEquals (0 ms) [ RUN ] ResourcesTest.RangesSubtraction1 [ OK ] ResourcesTest.RangesSubtraction1 (0 ms) [ RUN ] ResourcesTest.RangesAddition4 [ OK ] ResourcesTest.RangesAddition4 (1 ms) [ RUN ] ResourcesTest.RangesAddition2 [ OK ] ResourcesTest.RangesAddition2 (0 ms) [ RUN ] ResourcesTest.SetSubset [ OK ] ResourcesTest.SetSubset (0 ms) [ RUN ] ResourcesTest.RangesSubtraction2 [ OK ] ResourcesTest.RangesSubtraction2 (0 ms) [ RUN ] ResourcesTest.ScalarSubset [ OK ] ResourcesTest.ScalarSubset (0 ms) [ RUN ] ResourcesTest.RangesSubtraction3 [ OK ] ResourcesTest.RangesSubtraction3 (0 ms) [ RUN ] ResourcesTest.ScalarSubtraction2 [ OK ] ResourcesTest.ScalarSubtraction2 (0 ms) [ RUN ] ResourcesTest.FlattenRoles [ OK ] ResourcesTest.FlattenRoles (0 ms) [ RUN ] ResourcesTest.SetSubtraction [ OK ] ResourcesTest.SetSubtraction (1 ms) [ RUN ] ResourcesTest.RangesAddition [ OK ] ResourcesTest.RangesAddition (0 ms) [ RUN ] ResourcesTest.RangesSubtraction5 [ OK ] ResourcesTest.RangesSubtraction5 (0 ms) [ RUN ] ResourcesTest.Resources [ OK ] ResourcesTest.Resources (0 ms) [ RUN ] ResourcesTest.ScalarSubtraction [ OK ] ResourcesTest.ScalarSubtraction (0 ms) [ RUN ] ResourcesTest.RangesSubtraction4 [ OK ] ResourcesTest.RangesSubtraction4 (0 ms) [ RUN ] ResourcesTest.RangesSubtraction6 [ OK ] ResourcesTest.RangesSubtraction6 (0 ms) [ RUN ] ResourcesTest.BadResourcesNotAllocatable [ OK ] ResourcesTest.BadResourcesNotAllocatable (0 ms) [ RUN ] ResourcesTest.ParseError [ OK ] ResourcesTest.ParseError (0 ms) [ RUN ] ResourcesTest.ParsingWithRoles [ OK ] ResourcesTest.ParsingWithRoles (0 ms) [ RUN ] ResourcesTest.InitializedIsEmpty [ OK ] ResourcesTest.InitializedIsEmpty (0 ms) [ RUN ] ResourcesTest.RangesAdditon3 [ OK ] ResourcesTest.RangesAdditon3 (1 ms) [ RUN ] ResourcesTest.RangesSubset [ OK ] ResourcesTest.RangesSubset (0 ms) [ RUN ] ResourcesTest.RangesEquals [ OK ] ResourcesTest.RangesEquals (0 ms) [ RUN ] ResourcesTest.Parsing [ OK ] ResourcesTest.Parsing (0 ms) [----------] 35 tests from ResourcesTest (117 ms total) [----------] 3 tests from GarbageCollectorTest [ RUN ] GarbageCollectorTest.Unschedule Using temporary directory '/tmp/GarbageCollectorTest_Unschedule_AWxnXw' I1029 20:01:10.063221 27812 gc.cpp:84] Unscheduling 'bogus' from gc I1029 20:01:10.063886 27812 gc.cpp:56] Scheduling 'file1' for gc 10secs in the future I1029 20:01:10.064044 27812 gc.cpp:56] Scheduling 'file2' for gc 10secs in the future I1029 20:01:10.064129 27812 gc.cpp:56] Scheduling 'file3' for gc 10secs in the future I1029 20:01:10.064209 27812 gc.cpp:84] Unscheduling 'file2' from gc I1029 20:01:10.074157 27822 gc.cpp:84] Unscheduling 'file3' from gc I1029 20:01:10.084421 27823 gc.cpp:84] Unscheduling 'file1' from gc I1029 20:01:10.096211 27821 gc.cpp:155] Ignoring gc event at 0ns as the paths were already removed, or were unscheduled [ OK ] GarbageCollectorTest.Unschedule (73 ms) [ RUN ] GarbageCollectorTest.Prune Using temporary directory '/tmp/GarbageCollectorTest_Prune_QII2t6' I1029 20:01:10.135681 27818 gc.cpp:56] Scheduling 'file1' for gc 10secs in the future I1029 20:01:10.135833 27818 gc.cpp:56] Scheduling 'file2' for gc 10secs in the future I1029 20:01:10.135920 27818 gc.cpp:56] Scheduling 'file3' for gc 15secs in the future I1029 20:01:10.135999 27818 gc.cpp:56] Scheduling 'file4' for gc 15secs in the future I1029 20:01:10.136077 27818 gc.cpp:84] Unscheduling 'file3' from gc I1029 20:01:10.156009 27813 gc.cpp:167] Pruning directories with remaining removal time 10secs I1029 20:01:10.156090 27813 gc.cpp:134] Deleting file1 I1029 20:01:10.156141 27813 gc.cpp:143] Deleted 'file1' I1029 20:01:10.156167 27813 gc.cpp:134] Deleting file2 I1029 20:01:10.156199 27813 gc.cpp:143] Deleted 'file2' I1029 20:01:10.176352 27816 gc.cpp:167] Pruning directories with remaining removal time 15secs I1029 20:01:10.176508 27816 gc.cpp:134] Deleting file4 I1029 20:01:10.176563 27816 gc.cpp:143] Deleted 'file4' [ OK ] GarbageCollectorTest.Prune (51 ms) [ RUN ] GarbageCollectorTest.Schedule Using temporary directory '/tmp/GarbageCollectorTest_Schedule_yE838F' I1029 20:01:10.187319 27816 gc.cpp:56] Scheduling 'file1' for gc 10secs in the future I1029 20:01:10.187497 27816 gc.cpp:56] Scheduling 'file2' for gc 10secs in the future I1029 20:01:10.187609 27816 gc.cpp:56] Scheduling 'file3' for gc 15secs in the future I1029 20:01:10.228978 27815 gc.cpp:134] Deleting file1 I1029 20:01:10.229038 27815 gc.cpp:143] Deleted 'file1' I1029 20:01:10.229069 27815 gc.cpp:134] Deleting file2 I1029 20:01:10.229109 27815 gc.cpp:143] Deleted 'file2' I1029 20:01:10.258406 27823 gc.cpp:134] Deleting file3 I1029 20:01:10.258491 27823 gc.cpp:143] Deleted 'file3' [ OK ] GarbageCollectorTest.Schedule (92 ms) [----------] 3 tests from GarbageCollectorTest (216 ms total) [----------] Global test environment tear-down [==========] 449 tests from 70 test cases ran. (283265 ms total) [ PASSED ] 448 tests. [ FAILED ] 1 test, listed below: [ FAILED ] HealthCheckTest.GracePeriod 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
