mesos-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #2229
Date Wed, 29 Oct 2014 20:01:44 GMT
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 scheduler-433d5fe0-5dd8-4eb7-96a1-75a171494d2f@67.195.81.190:41000
I1029 20:01:09.768019 27824 sched.cpp:357] Successfully authenticated with master master@67.195.81.190:41000
I1029 20:01:09.768048 27824 sched.cpp:476] Sending registration request to master@67.195.81.190:41000
I1029 20:01:09.768321 27820 master.cpp:1362] Received registration request for framework 'framework3'
at scheduler-433d5fe0-5dd8-4eb7-96a1-75a171494d2f@67.195.81.190: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 scheduler-433d5fe0-5dd8-4eb7-96a1-75a171494d2f@67.195.81.190: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 scheduler-433d5fe0-5dd8-4eb7-96a1-75a171494d2f@67.195.81.190: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 master@67.195.81.190:41000
I1029 20:01:09.780638 27816 slave.cpp:665] Authenticating with master master@67.195.81.190: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 master@67.195.81.190: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 master@67.195.81.190: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 scheduler-bf4f0d6f-a260-4866-aa57-59024a517c4d@67.195.81.190: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] master@67.195.81.190: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] master@67.195.81.190: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] master@67.195.81.190: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] master@67.195.81.190:41000 exited
I1029 20:01:09.924320 27818 slave.cpp:2607] master@67.195.81.190: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

Mime
View raw message