See
<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1888/>
------------------------------------------
[...truncated 16359 lines...]
I0213 21:40:43.450346 5380 master.cpp:818] Received registration request from
scheduler(100)@140.211.11.27:33382
I0213 21:40:43.450381 5380 master.cpp:836] Registering framework
2014-02-13-21:40:43-453759884-33382-5335-0000 at
scheduler(100)@140.211.11.27:33382
I0213 21:40:43.450511 5384 sched.cpp:391] Framework registered with
2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.450539 5377 hierarchical_allocator_process.hpp:332] Added
framework 2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.450541 5384 sched.cpp:405] Scheduler::registered took 9660ns
I0213 21:40:43.450599 5377 hierarchical_allocator_process.hpp:752] Offering
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-13-21:40:43-453759884-33382-5335-0 to framework
2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.450726 5377 hierarchical_allocator_process.hpp:688] Performed
allocation for 1 slaves in 157142ns
I0213 21:40:43.450829 5369 master.hpp:439] Adding offer
2014-02-13-21:40:43-453759884-33382-5335-0 with resources cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-13-21:40:43-453759884-33382-5335-0 (hemera.apache.org)
I0213 21:40:43.450911 5369 master.cpp:2278] Sending 1 offers to framework
2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.451055 5369 sched.cpp:525] Scheduler::resourceOffers took
23431ns
I0213 21:40:43.451537 5364 sched.cpp:898] Attempting to launch task 2 with an
unknown offer 2014-02-13-21:40:43-453759884-33382-5335-0
I0213 21:40:43.451700 5371 master.hpp:449] Removing offer
2014-02-13-21:40:43-453759884-33382-5335-0 with resources cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-13-21:40:43-453759884-33382-5335-0 (hemera.apache.org)
I0213 21:40:43.451789 5371 master.cpp:1574] Processing reply for offers: [
2014-02-13-21:40:43-453759884-33382-5335-0 ] on slave
2014-02-13-21:40:43-453759884-33382-5335-0 (hemera.apache.org) for framework
2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.451858 5371 master.hpp:411] Adding task 1 with resources
cpus(*):1; mem(*):512 on slave 2014-02-13-21:40:43-453759884-33382-5335-0
(hemera.apache.org)
I0213 21:40:43.451890 5371 master.cpp:2447] Launching task 1 of framework
2014-02-13-21:40:43-453759884-33382-5335-0000 with resources cpus(*):1;
mem(*):512 on slave 2014-02-13-21:40:43-453759884-33382-5335-0
(hemera.apache.org)
I0213 21:40:43.452023 5384 slave.cpp:625] Got assigned task 1 for framework
2014-02-13-21:40:43-453759884-33382-5335-0000
W0213 21:40:43.452057 5371 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-13-21:40:43-453759884-33382-5335-0000"
}
------------------------------------------------------------
Task's ExecutorInfo:
executor_id {
value: "default"
}
command {
value: "exit 2"
}
framework_id {
value: "2014-02-13-21:40:43-453759884-33382-5335-0000"
}
------------------------------------------------------------
I0213 21:40:43.452227 5384 slave.cpp:734] Launching task 1 for framework
2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.454368 5371 master.cpp:1618] Sending status update TASK_LOST
(UUID: 77daa968-409f-4f47-b3a3-0afe04600a6c) for task 2 of framework
2014-02-13-21:40:43-453759884-33382-5335-0000 for invalid task
I0213 21:40:43.454491 5366 sched.cpp:616] Scheduler::statusUpdate took 17717ns
I0213 21:40:43.454522 5371 hierarchical_allocator_process.hpp:547] Framework
2014-02-13-21:40:43-453759884-33382-5335-0000 left cpus(*):1; mem(*):512;
disk(*):1024; ports(*):[31000-32000] unused on slave
2014-02-13-21:40:43-453759884-33382-5335-0
I0213 21:40:43.454617 5371 hierarchical_allocator_process.hpp:590] Framework
2014-02-13-21:40:43-453759884-33382-5335-0000 filtered slave
2014-02-13-21:40:43-453759884-33382-5335-0 for 5secs
I0213 21:40:43.456643 5384 exec.cpp:131] Version: 0.18.0
I0213 21:40:43.456753 5384 slave.cpp:844] Queuing task '1' for executor
default of framework '2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.456765 5364 exec.cpp:181] Executor started at:
executor(37)@140.211.11.27:33382 with pid 5335
I0213 21:40:43.456800 5384 slave.cpp:358] Successfully attached file
'/tmp/MultipleExecutorsTest_TasksExecutorInfoDiffers_rTBAg4/slaves/2014-02-13-21:40:43-453759884-33382-5335-0/frameworks/2014-02-13-21:40:43-453759884-33382-5335-0000/executors/default/runs/cb39691b-8b88-48de-a16a-d94283b87858'
I0213 21:40:43.456825 5384 slave.cpp:1989] Monitoring executor 'default' of
framework '2014-02-13-21:40:43-453759884-33382-5335-0000' in container
'cb39691b-8b88-48de-a16a-d94283b87858'
I0213 21:40:43.457082 5384 slave.cpp:1316] Got registration for executor
'default' of framework 2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.457198 5384 slave.cpp:1434] Flushing queued task 1 for executor
'default' of framework 2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.457238 5385 exec.cpp:205] Executor registered on slave
2014-02-13-21:40:43-453759884-33382-5335-0
I0213 21:40:43.458832 5385 exec.cpp:217] Executor::registered took 18495ns
I0213 21:40:43.458896 5385 exec.cpp:292] Executor asked to run task '1'
I0213 21:40:43.458933 5385 exec.cpp:301] Executor::launchTask took 25592ns
I0213 21:40:43.459110 5335 master.cpp:587] Master terminating
I0213 21:40:43.459148 5385 sched.cpp:727] Stopping framework
'2014-02-13-21:40:43-453759884-33382-5335-0000'
I0213 21:40:43.459403 5365 slave.cpp:1844] [email protected]:33382 exited
I0213 21:40:43.459421 5335 master.cpp:247] Shutting down master
W0213 21:40:43.459422 5365 slave.cpp:1847] Master disconnected! Waiting for a
new master to be elected
I0213 21:40:43.459491 5335 master.hpp:429] Removing task 1 with resources
cpus(*):1; mem(*):512 on slave 2014-02-13-21:40:43-453759884-33382-5335-0
(hemera.apache.org)
I0213 21:40:43.459651 5335 master.cpp:290] Removing slave
2014-02-13-21:40:43-453759884-33382-5335-0 (hemera.apache.org)
I0213 21:40:43.459697 5376 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-13-21:40:43-453759884-33382-5335-0
from framework 2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.504539 5335 slave.cpp:287] Slave terminating
I0213 21:40:43.504566 5335 slave.cpp:1027] Asked to shut down framework
2014-02-13-21:40:43-453759884-33382-5335-0000 by @0.0.0.0:0
I0213 21:40:43.504580 5335 slave.cpp:1052] Shutting down framework
2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.504591 5335 slave.cpp:2332] Shutting down executor 'default' of
framework 2014-02-13-21:40:43-453759884-33382-5335-0000
I0213 21:40:43.504693 5365 exec.cpp:378] Executor asked to shutdown
I0213 21:40:43.504763 5365 exec.cpp:393] Executor::shutdown took 9563ns
[ OK ] MultipleExecutorsTest.TasksExecutorInfoDiffers (67 ms)
[----------] 1 test from MultipleExecutorsTest (67 ms total)
[----------] 34 tests from ResourcesTest
[ RUN ] ResourcesTest.Parsing
[ OK ] ResourcesTest.Parsing (0 ms)
[ RUN ] ResourcesTest.ParsingWithRoles
[ OK ] ResourcesTest.ParsingWithRoles (1 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 (0 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 (1 ms)
[----------] 34 tests from ResourcesTest (3 ms total)
[----------] 3 tests from SASL
[ RUN ] SASL.success
I0213 21:40:43.508795 5376 authenticatee.hpp:124] Creating new client SASL
connection
I0213 21:40:43.509475 5373 authenticator.hpp:140] Creating new server SASL
connection
I0213 21:40:43.509670 5361 authenticatee.hpp:212] Received SASL authentication
mechanisms: CRAM-MD5
I0213 21:40:43.509697 5361 authenticatee.hpp:238] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0213 21:40:43.509737 5361 authenticator.hpp:243] Received SASL authentication
start
I0213 21:40:43.509874 5361 authenticator.hpp:325] Authentication requires more
steps
I0213 21:40:43.509991 5385 authenticatee.hpp:258] Received SASL authentication
step
I0213 21:40:43.510190 5383 authenticator.hpp:271] Received SASL authentication
step
I0213 21:40:43.510224 5383 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0213 21:40:43.510236 5383 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0213 21:40:43.510252 5383 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0213 21:40:43.510267 5383 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0213 21:40:43.510277 5383 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0213 21:40:43.510288 5383 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0213 21:40:43.510305 5383 authenticator.hpp:317] Authentication success
I0213 21:40:43.510921 5380 authenticatee.hpp:298] Authentication success
[ OK ] SASL.success (4 ms)
[ RUN ] SASL.failed1
I0213 21:40:43.512439 5383 authenticatee.hpp:124] Creating new client SASL
connection
I0213 21:40:43.513126 5385 authenticator.hpp:140] Creating new server SASL
connection
I0213 21:40:43.513469 5369 authenticatee.hpp:212] Received SASL authentication
mechanisms: CRAM-MD5
I0213 21:40:43.513491 5369 authenticatee.hpp:238] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0213 21:40:43.513595 5375 authenticator.hpp:243] Received SASL authentication
start
I0213 21:40:43.513707 5375 authenticator.hpp:325] Authentication requires more
steps
I0213 21:40:43.513836 5369 authenticatee.hpp:258] Received SASL authentication
step
I0213 21:40:43.513919 5378 authenticator.hpp:271] Received SASL authentication
step
I0213 21:40:43.513950 5378 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0213 21:40:43.513962 5378 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0213 21:40:43.513978 5378 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0213 21:40:43.513993 5378 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0213 21:40:43.514003 5378 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0213 21:40:43.514010 5378 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
W0213 21:40:43.514044 5378 authenticator.hpp:331] Authentication failure:
authentication failure
[ OK ] SASL.failed1 (2 ms)
[ RUN ] SASL.failed2
I0213 21:40:43.515817 5363 authenticatee.hpp:124] Creating new client SASL
connection
I0213 21:40:43.516181 5366 authenticator.hpp:140] Creating new server SASL
connection
I0213 21:40:43.516579 5384 authenticatee.hpp:212] Received SASL authentication
mechanisms: CRAM-MD5
I0213 21:40:43.516604 5384 authenticatee.hpp:238] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0213 21:40:43.516651 5384 authenticator.hpp:243] Received SASL authentication
start
I0213 21:40:43.516692 5384 authenticator.hpp:325] Authentication requires more
steps
I0213 21:40:43.516726 5384 authenticatee.hpp:258] Received SASL authentication
step
I0213 21:40:43.516870 5363 authenticator.hpp:271] Received SASL authentication
step
I0213 21:40:43.516896 5363 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0213 21:40:43.516904 5363 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0213 21:40:43.516912 5363 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0213 21:40:43.516921 5363 auxprop.cpp:81] Request to lookup properties for
user: 'benh' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0213 21:40:43.516927 5363 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0213 21:40:43.516933 5363 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
W0213 21:40:43.516942 5363 authenticator.hpp:331] Authentication failure: user
not found
[ OK ] SASL.failed2 (3 ms)
[----------] 3 tests from SASL (10 ms total)
[----------] 2 tests from SlaveStateTest
[ RUN ] SlaveStateTest.CheckpointProtobuf
Using temporary directory '/tmp/SlaveStateTest_CheckpointProtobuf_xnOFQo'
[ OK ] SlaveStateTest.CheckpointProtobuf (0 ms)
[ RUN ] SlaveStateTest.CheckpointString
Using temporary directory '/tmp/SlaveStateTest_CheckpointString_sruOqJ'
[ OK ] SlaveStateTest.CheckpointString (0 ms)
[----------] 2 tests from SlaveStateTest (1 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.*).
-------------------------------------------------------------
F0213 21:40:43.519878 5335 mesos.cpp:362] CHECK_SOME(cgroups):
'/tmp/mesos_test_cgroup/freezer' is not a valid hierarchy
*** Check failure stack trace: ***
@ 0x2b354f62c7ad google::LogMessage::Fail()
@ 0x2b354f62e8af google::LogMessage::SendToLog()
@ 0x2b354f62c39c google::LogMessage::Flush()
@ 0x2b354f62f14d google::LogMessageFatal::~LogMessageFatal()
@ 0x4d31fd _CheckSome::~_CheckSome()
@ 0x6de9f4
mesos::internal::tests::ContainerizerTest<>::TearDown()
@ 0x809e7d
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x8020b3 testing::Test::Run()
@ 0x8021e6 testing::TestInfo::Run()
@ 0x802327 testing::TestCase::Run()
@ 0x80267e testing::internal::UnitTestImpl::RunAllTests()
@ 0x8099fd
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x80175e testing::UnitTest::Run()
@ 0x4ae64b main
@ 0x2b35509e676d (unknown)
@ 0x4b62a1 (unknown)
make[3]: *** [check-local] Aborted (core dumped)
make[3]: Leaving directory
`/x1/jenkins/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
`/x1/jenkins/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
`/x1/jenkins/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
