See
<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1889/>
------------------------------------------
[...truncated 18777 lines...]
I0212 06:03:23.300499 32739 master.cpp:818] Received registration request from
scheduler(101)@67.195.138.9:55071
I0212 06:03:23.300551 32739 master.cpp:836] Registering framework
2014-02-12-06:03:23-160088899-55071-32714-0000 at
scheduler(101)@67.195.138.9:55071
I0212 06:03:23.300624 32737 sched.cpp:391] Framework registered with
2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.300643 32739 hierarchical_allocator_process.hpp:332] Added
framework 2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.300650 32737 sched.cpp:405] Scheduler::registered took 11352ns
I0212 06:03:23.300700 32739 hierarchical_allocator_process.hpp:752] Offering
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-12-06:03:23-160088899-55071-32714-0 to framework
2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.300839 32739 hierarchical_allocator_process.hpp:688] Performed
allocation for 1 slaves in 182467ns
I0212 06:03:23.300891 32735 master.hpp:439] Adding offer
2014-02-12-06:03:23-160088899-55071-32714-0 with resources cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-12-06:03:23-160088899-55071-32714-0 (vesta.apache.org)
I0212 06:03:23.300933 32735 master.cpp:2278] Sending 1 offers to framework
2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.301035 32739 sched.cpp:525] Scheduler::resourceOffers took
21849ns
I0212 06:03:23.301368 32740 sched.cpp:898] Attempting to launch task 2 with an
unknown offer 2014-02-12-06:03:23-160088899-55071-32714-0
I0212 06:03:23.301532 32739 master.hpp:449] Removing offer
2014-02-12-06:03:23-160088899-55071-32714-0 with resources cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-12-06:03:23-160088899-55071-32714-0 (vesta.apache.org)
I0212 06:03:23.301579 32739 master.cpp:1574] Processing reply for offers: [
2014-02-12-06:03:23-160088899-55071-32714-0 ] on slave
2014-02-12-06:03:23-160088899-55071-32714-0 (vesta.apache.org) for framework
2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.301651 32739 master.hpp:411] Adding task 1 with resources
cpus(*):1; mem(*):512 on slave 2014-02-12-06:03:23-160088899-55071-32714-0
(vesta.apache.org)
I0212 06:03:23.301681 32739 master.cpp:2447] Launching task 1 of framework
2014-02-12-06:03:23-160088899-55071-32714-0000 with resources cpus(*):1;
mem(*):512 on slave 2014-02-12-06:03:23-160088899-55071-32714-0
(vesta.apache.org)
I0212 06:03:23.301766 32737 slave.cpp:625] Got assigned task 1 for framework
2014-02-12-06:03:23-160088899-55071-32714-0000
W0212 06:03:23.301853 32739 master.cpp:1608] Failed to validate task 2 : Task
has invalid ExecutorInfo (existing ExecutorInfo with same ExecutorID is not
compatible).
------------------------------------------------------------
Existing ExecutorInfo:
executor_id {
value: "default"
}
command {
value: "exit 1"
}
framework_id {
value: "2014-02-12-06:03:23-160088899-55071-32714-0000"
}
------------------------------------------------------------
Task's ExecutorInfo:
executor_id {
value: "default"
}
command {
value: "exit 2"
}
framework_id {
value: "2014-02-12-06:03:23-160088899-55071-32714-0000"
}
------------------------------------------------------------
I0212 06:03:23.301952 32737 slave.cpp:734] Launching task 1 for framework
2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.304467 32739 master.cpp:1618] Sending status update TASK_LOST
(UUID: 5f1afa5a-096b-4365-95b7-45c04856ec68) for task 2 of framework
2014-02-12-06:03:23-160088899-55071-32714-0000 for invalid task
I0212 06:03:23.304571 32735 sched.cpp:616] Scheduler::statusUpdate took 15229ns
I0212 06:03:23.304611 32736 hierarchical_allocator_process.hpp:547] Framework
2014-02-12-06:03:23-160088899-55071-32714-0000 left cpus(*):1; mem(*):512;
disk(*):1024; ports(*):[31000-32000] unused on slave
2014-02-12-06:03:23-160088899-55071-32714-0
I0212 06:03:23.304743 32736 hierarchical_allocator_process.hpp:590] Framework
2014-02-12-06:03:23-160088899-55071-32714-0000 filtered slave
2014-02-12-06:03:23-160088899-55071-32714-0 for 5secs
I0212 06:03:23.306035 32737 exec.cpp:131] Version: 0.18.0
I0212 06:03:23.306078 32740 exec.cpp:181] Executor started at:
executor(37)@67.195.138.9:55071 with pid 32714
I0212 06:03:23.306176 32737 slave.cpp:844] Queuing task '1' for executor
default of framework '2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.306220 32737 slave.cpp:358] Successfully attached file
'/tmp/MultipleExecutorsTest_TasksExecutorInfoDiffers_pu8ZoE/slaves/2014-02-12-06:03:23-160088899-55071-32714-0/frameworks/2014-02-12-06:03:23-160088899-55071-32714-0000/executors/default/runs/d9184dc4-3b1c-4ac9-8ea4-650a991860da'
I0212 06:03:23.306238 32737 slave.cpp:1989] Monitoring executor 'default' of
framework '2014-02-12-06:03:23-160088899-55071-32714-0000' in container
'd9184dc4-3b1c-4ac9-8ea4-650a991860da'
I0212 06:03:23.306290 32737 slave.cpp:1316] Got registration for executor
'default' of framework 2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.306416 32737 slave.cpp:1434] Flushing queued task 1 for executor
'default' of framework 2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.306429 32741 exec.cpp:205] Executor registered on slave
2014-02-12-06:03:23-160088899-55071-32714-0
I0212 06:03:23.307482 32741 exec.cpp:217] Executor::registered took 10219ns
I0212 06:03:23.307534 32741 exec.cpp:292] Executor asked to run task '1'
I0212 06:03:23.307590 32741 exec.cpp:301] Executor::launchTask took 42189ns
I0212 06:03:23.307802 32714 master.cpp:587] Master terminating
I0212 06:03:23.307807 32740 sched.cpp:727] Stopping framework
'2014-02-12-06:03:23-160088899-55071-32714-0000'
I0212 06:03:23.307852 32714 master.cpp:247] Shutting down master
I0212 06:03:23.307867 32736 slave.cpp:1844] [email protected]:55071 exited
W0212 06:03:23.307883 32736 slave.cpp:1847] Master disconnected! Waiting for a
new master to be elected
I0212 06:03:23.307901 32714 master.hpp:429] Removing task 1 with resources
cpus(*):1; mem(*):512 on slave 2014-02-12-06:03:23-160088899-55071-32714-0
(vesta.apache.org)
I0212 06:03:23.307981 32714 master.cpp:290] Removing slave
2014-02-12-06:03:23-160088899-55071-32714-0 (vesta.apache.org)
I0212 06:03:23.308043 32737 hierarchical_allocator_process.hpp:637] Recovered
cpus(*):1; mem(*):512 (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]) on slave 2014-02-12-06:03:23-160088899-55071-32714-0
from framework 2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.661538 32714 slave.cpp:287] Slave terminating
I0212 06:03:23.661617 32714 slave.cpp:1027] Asked to shut down framework
2014-02-12-06:03:23-160088899-55071-32714-0000 by @0.0.0.0:0
I0212 06:03:23.661638 32714 slave.cpp:1052] Shutting down framework
2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.661658 32714 slave.cpp:2332] Shutting down executor 'default' of
framework 2014-02-12-06:03:23-160088899-55071-32714-0000
I0212 06:03:23.661972 32714 exec.cpp:378] Executor asked to shutdown
I0212 06:03:23.661990 32714 exec.cpp:393] Executor::shutdown took 9us
[ OK ] MultipleExecutorsTest.TasksExecutorInfoDiffers (368 ms)
[----------] 1 test from MultipleExecutorsTest (368 ms total)
[----------] 34 tests from ResourcesTest
[ RUN ] ResourcesTest.Parsing
[ OK ] ResourcesTest.Parsing (0 ms)
[ RUN ] ResourcesTest.ParsingWithRoles
[ OK ] ResourcesTest.ParsingWithRoles (0 ms)
[ RUN ] ResourcesTest.ParseError
[ OK ] ResourcesTest.ParseError (0 ms)
[ RUN ] ResourcesTest.Resources
[ OK ] ResourcesTest.Resources (0 ms)
[ RUN ] ResourcesTest.Printing
[ OK ] ResourcesTest.Printing (0 ms)
[ RUN ] ResourcesTest.InitializedIsEmpty
[ OK ] ResourcesTest.InitializedIsEmpty (0 ms)
[ RUN ] ResourcesTest.BadResourcesNotAllocatable
[ OK ] ResourcesTest.BadResourcesNotAllocatable (0 ms)
[ RUN ] ResourcesTest.ScalarEquals
[ OK ] ResourcesTest.ScalarEquals (0 ms)
[ RUN ] ResourcesTest.ScalarSubset
[ OK ] ResourcesTest.ScalarSubset (1 ms)
[ RUN ] ResourcesTest.ScalarSubset2
[ OK ] ResourcesTest.ScalarSubset2 (0 ms)
[ RUN ] ResourcesTest.ScalarAddition
[ OK ] ResourcesTest.ScalarAddition (0 ms)
[ RUN ] ResourcesTest.ScalarAddition2
[ OK ] ResourcesTest.ScalarAddition2 (0 ms)
[ RUN ] ResourcesTest.ScalarSubtraction
[ OK ] ResourcesTest.ScalarSubtraction (0 ms)
[ RUN ] ResourcesTest.ScalarSubtraction2
[ OK ] ResourcesTest.ScalarSubtraction2 (0 ms)
[ RUN ] ResourcesTest.RangesEquals
[ OK ] ResourcesTest.RangesEquals (0 ms)
[ RUN ] ResourcesTest.RangesSubset
[ OK ] ResourcesTest.RangesSubset (0 ms)
[ RUN ] ResourcesTest.RangesAddition
[ OK ] ResourcesTest.RangesAddition (0 ms)
[ RUN ] ResourcesTest.RangesAddition2
[ OK ] ResourcesTest.RangesAddition2 (0 ms)
[ RUN ] ResourcesTest.RangesAdditon3
[ OK ] ResourcesTest.RangesAdditon3 (0 ms)
[ RUN ] ResourcesTest.RangesAddition4
[ OK ] ResourcesTest.RangesAddition4 (0 ms)
[ RUN ] ResourcesTest.RangesSubtraction
[ OK ] ResourcesTest.RangesSubtraction (0 ms)
[ RUN ] ResourcesTest.RangesSubtraction1
[ OK ] ResourcesTest.RangesSubtraction1 (0 ms)
[ RUN ] ResourcesTest.RangesSubtraction2
[ OK ] ResourcesTest.RangesSubtraction2 (0 ms)
[ RUN ] ResourcesTest.RangesSubtraction3
[ OK ] ResourcesTest.RangesSubtraction3 (0 ms)
[ RUN ] ResourcesTest.RangesSubtraction4
[ OK ] ResourcesTest.RangesSubtraction4 (0 ms)
[ RUN ] ResourcesTest.RangesSubtraction5
[ OK ] ResourcesTest.RangesSubtraction5 (0 ms)
[ RUN ] ResourcesTest.RangesSubtraction6
[ OK ] ResourcesTest.RangesSubtraction6 (0 ms)
[ RUN ] ResourcesTest.SetEquals
[ OK ] ResourcesTest.SetEquals (0 ms)
[ RUN ] ResourcesTest.SetSubset
[ OK ] ResourcesTest.SetSubset (0 ms)
[ RUN ] ResourcesTest.SetAddition
[ OK ] ResourcesTest.SetAddition (0 ms)
[ RUN ] ResourcesTest.SetSubtraction
[ OK ] ResourcesTest.SetSubtraction (0 ms)
[ RUN ] ResourcesTest.EmptyUnequal
[ OK ] ResourcesTest.EmptyUnequal (0 ms)
[ RUN ] ResourcesTest.FlattenRoles
[ OK ] ResourcesTest.FlattenRoles (0 ms)
[ RUN ] ResourcesTest.Find
[ OK ] ResourcesTest.Find (0 ms)
[----------] 34 tests from ResourcesTest (2 ms total)
[----------] 3 tests from SASL
[ RUN ] SASL.success
I0212 06:03:23.665103 32740 authenticatee.hpp:124] Creating new client SASL
connection
I0212 06:03:23.665453 32739 authenticator.hpp:140] Creating new server SASL
connection
I0212 06:03:23.665568 32736 authenticatee.hpp:212] Received SASL authentication
mechanisms: CRAM-MD5
I0212 06:03:23.665596 32736 authenticatee.hpp:238] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0212 06:03:23.665624 32736 authenticator.hpp:243] Received SASL authentication
start
I0212 06:03:23.665674 32736 authenticator.hpp:325] Authentication requires more
steps
I0212 06:03:23.665700 32736 authenticatee.hpp:258] Received SASL authentication
step
I0212 06:03:23.665740 32736 authenticator.hpp:271] Received SASL authentication
step
I0212 06:03:23.665760 32736 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0212 06:03:23.665771 32736 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0212 06:03:23.665782 32736 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0212 06:03:23.665791 32736 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0212 06:03:23.665798 32736 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0212 06:03:23.665803 32736 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0212 06:03:23.665813 32736 authenticator.hpp:317] Authentication success
I0212 06:03:23.665845 32739 authenticatee.hpp:298] Authentication success
[ OK ] SASL.success (2 ms)
[ RUN ] SASL.failed1
I0212 06:03:23.666290 32736 authenticatee.hpp:124] Creating new client SASL
connection
I0212 06:03:23.666569 32735 authenticator.hpp:140] Creating new server SASL
connection
I0212 06:03:23.666646 32736 authenticatee.hpp:212] Received SASL authentication
mechanisms: CRAM-MD5
I0212 06:03:23.666678 32736 authenticatee.hpp:238] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0212 06:03:23.666705 32736 authenticator.hpp:243] Received SASL authentication
start
I0212 06:03:23.666734 32736 authenticator.hpp:325] Authentication requires more
steps
I0212 06:03:23.666762 32736 authenticatee.hpp:258] Received SASL authentication
step
I0212 06:03:23.666796 32736 authenticator.hpp:271] Received SASL authentication
step
I0212 06:03:23.666812 32736 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0212 06:03:23.666825 32736 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0212 06:03:23.666833 32736 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0212 06:03:23.666844 32736 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0212 06:03:23.666851 32736 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0212 06:03:23.666856 32736 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
W0212 06:03:23.666872 32736 authenticator.hpp:331] Authentication failure:
authentication failure
[ OK ] SASL.failed1 (1 ms)
[ RUN ] SASL.failed2
I0212 06:03:23.667348 32739 authenticatee.hpp:124] Creating new client SASL
connection
I0212 06:03:23.667698 32738 authenticator.hpp:140] Creating new server SASL
connection
I0212 06:03:23.667814 32734 authenticatee.hpp:212] Received SASL authentication
mechanisms: CRAM-MD5
I0212 06:03:23.667831 32734 authenticatee.hpp:238] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0212 06:03:23.667861 32734 authenticator.hpp:243] Received SASL authentication
start
I0212 06:03:23.667902 32734 authenticator.hpp:325] Authentication requires more
steps
I0212 06:03:23.667932 32734 authenticatee.hpp:258] Received SASL authentication
step
I0212 06:03:23.668011 32739 authenticator.hpp:271] Received SASL authentication
step
I0212 06:03:23.668038 32739 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0212 06:03:23.668046 32739 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0212 06:03:23.668053 32739 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0212 06:03:23.668061 32739 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0212 06:03:23.668067 32739 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0212 06:03:23.668072 32739 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
W0212 06:03:23.668081 32739 authenticator.hpp:331] Authentication failure: user
not found
[ OK ] SASL.failed2 (1 ms)
[----------] 3 tests from SASL (4 ms total)
[----------] 2 tests from SlaveStateTest
[ RUN ] SlaveStateTest.CheckpointProtobuf
Using temporary directory '/tmp/SlaveStateTest_CheckpointProtobuf_7B9JDH'
[ OK ] SlaveStateTest.CheckpointProtobuf (0 ms)
[ RUN ] SlaveStateTest.CheckpointString
Using temporary directory '/tmp/SlaveStateTest_CheckpointString_sJwxSK'
[ OK ] SlaveStateTest.CheckpointString (0 ms)
[----------] 2 tests from SlaveStateTest (0 ms total)
[----------] 23 tests from SlaveRecoveryTest/0, where TypeParam =
mesos::internal::slave::MesosContainerizer
[ RUN ] SlaveRecoveryTest/0.RecoverSlaveState
../../src/tests/mesos.cpp:336: Failure
cgroups::mount(hierarchy, subsystem): Failed to mount 'freezer' at
'/tmp/mesos_test_cgroup/freezer': Operation not permitted
-------------------------------------------------------------
We cannot run any cgroups tests that require
a hierarchy with subsystem 'freezer'
because we failed to find an existing hierarchy
or create a new one (tried '/tmp/mesos_test_cgroup/freezer').
You can either remove all existing
hierarchies, or disable this test case
(i.e., --gtest_filter=-SlaveRecoveryTest/0.*).
-------------------------------------------------------------
F0212 06:03:23.686146 32714 mesos.cpp:362] CHECK_SOME(cgroups):
'/tmp/mesos_test_cgroup/freezer' is not a valid hierarchy
*** Check failure stack trace: ***
@ 0x2b53b860df1d google::LogMessage::Fail()
@ 0x2b53b861001f google::LogMessage::SendToLog()
@ 0x2b53b860db0c google::LogMessage::Flush()
@ 0x2b53b86108bd google::LogMessageFatal::~LogMessageFatal()
@ 0x4d8add _CheckSome::~_CheckSome()
@ 0x6fceb4
mesos::internal::tests::ContainerizerTest<>::TearDown()
@ 0x851fbd
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x84a1f3 testing::Test::Run()
@ 0x84a326 testing::TestInfo::Run()
@ 0x84a467 testing::TestCase::Run()
@ 0x84a7be testing::internal::UnitTestImpl::RunAllTests()
@ 0x851b3d
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x84989e testing::UnitTest::Run()
@ 0x4b2d2b main
@ 0x2b53b99d376d (unknown)
@ 0x4bbb81 (unknown)
make[3]: *** [check-local] Aborted
make[3]: Leaving directory
`/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
make[2]: *** [check-am] Error 2
make[2]: Leaving directory
`/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
make[1]: *** [check] Error 2
make[1]: Leaving directory
`/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
make: *** [check-recursive] Error 1
Build step 'Execute shell' marked build as failure
