See
<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1890/changes>
Changes:
[bmahler] Disable SIGPIPE on OS X upon socket creation.
------------------------------------------
[...truncated 18788 lines...]
I0213 21:18:41.219928 27395 master.cpp:818] Received registration request from
scheduler(101)@67.195.138.9:40941
I0213 21:18:41.219982 27395 master.cpp:836] Registering framework
2014-02-13-21:18:41-160088899-40941-27372-0000 at
scheduler(101)@67.195.138.9:40941
I0213 21:18:41.220068 27396 sched.cpp:391] Framework registered with
2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.220088 27395 hierarchical_allocator_process.hpp:332] Added
framework 2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.220090 27396 sched.cpp:405] Scheduler::registered took 8962ns
I0213 21:18:41.220144 27395 hierarchical_allocator_process.hpp:752] Offering
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-13-21:18:41-160088899-40941-27372-0 to framework
2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.220288 27395 hierarchical_allocator_process.hpp:688] Performed
allocation for 1 slaves in 178396ns
I0213 21:18:41.220371 27392 master.hpp:439] Adding offer
2014-02-13-21:18:41-160088899-40941-27372-0 with resources cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-13-21:18:41-160088899-40941-27372-0 (vesta.apache.org)
I0213 21:18:41.220420 27392 master.cpp:2278] Sending 1 offers to framework
2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.220568 27398 sched.cpp:525] Scheduler::resourceOffers took
24512ns
I0213 21:18:41.220954 27395 sched.cpp:898] Attempting to launch task 2 with an
unknown offer 2014-02-13-21:18:41-160088899-40941-27372-0
I0213 21:18:41.221129 27398 master.hpp:449] Removing offer
2014-02-13-21:18:41-160088899-40941-27372-0 with resources cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-13-21:18:41-160088899-40941-27372-0 (vesta.apache.org)
I0213 21:18:41.221185 27398 master.cpp:1574] Processing reply for offers: [
2014-02-13-21:18:41-160088899-40941-27372-0 ] on slave
2014-02-13-21:18:41-160088899-40941-27372-0 (vesta.apache.org) for framework
2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.221259 27398 master.hpp:411] Adding task 1 with resources
cpus(*):1; mem(*):512 on slave 2014-02-13-21:18:41-160088899-40941-27372-0
(vesta.apache.org)
I0213 21:18:41.221290 27398 master.cpp:2447] Launching task 1 of framework
2014-02-13-21:18:41-160088899-40941-27372-0000 with resources cpus(*):1;
mem(*):512 on slave 2014-02-13-21:18:41-160088899-40941-27372-0
(vesta.apache.org)
I0213 21:18:41.221364 27395 slave.cpp:625] Got assigned task 1 for framework
2014-02-13-21:18:41-160088899-40941-27372-0000
W0213 21:18:41.221458 27398 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:18:41-160088899-40941-27372-0000"
}
------------------------------------------------------------
Task's ExecutorInfo:
executor_id {
value: "default"
}
command {
value: "exit 2"
}
framework_id {
value: "2014-02-13-21:18:41-160088899-40941-27372-0000"
}
------------------------------------------------------------
I0213 21:18:41.221534 27395 slave.cpp:734] Launching task 1 for framework
2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.223247 27398 master.cpp:1618] Sending status update TASK_LOST
(UUID: 1e6c4ce1-8f5f-4251-9fc3-b1045ca84b00) for task 2 of framework
2014-02-13-21:18:41-160088899-40941-27372-0000 for invalid task
I0213 21:18:41.223338 27393 sched.cpp:616] Scheduler::statusUpdate took 13626ns
I0213 21:18:41.223399 27394 hierarchical_allocator_process.hpp:547] Framework
2014-02-13-21:18:41-160088899-40941-27372-0000 left cpus(*):1; mem(*):512;
disk(*):1024; ports(*):[31000-32000] unused on slave
2014-02-13-21:18:41-160088899-40941-27372-0
I0213 21:18:41.223510 27394 hierarchical_allocator_process.hpp:590] Framework
2014-02-13-21:18:41-160088899-40941-27372-0000 filtered slave
2014-02-13-21:18:41-160088899-40941-27372-0 for 5secs
I0213 21:18:41.224647 27395 exec.cpp:131] Version: 0.18.0
I0213 21:18:41.224733 27393 exec.cpp:181] Executor started at:
executor(37)@67.195.138.9:40941 with pid 27372
I0213 21:18:41.224788 27395 slave.cpp:844] Queuing task '1' for executor
default of framework '2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.224841 27395 slave.cpp:358] Successfully attached file
'/tmp/MultipleExecutorsTest_TasksExecutorInfoDiffers_AE3G64/slaves/2014-02-13-21:18:41-160088899-40941-27372-0/frameworks/2014-02-13-21:18:41-160088899-40941-27372-0000/executors/default/runs/16251407-1d40-42a1-aa34-47c483906ada'
I0213 21:18:41.224869 27395 slave.cpp:1989] Monitoring executor 'default' of
framework '2014-02-13-21:18:41-160088899-40941-27372-0000' in container
'16251407-1d40-42a1-aa34-47c483906ada'
I0213 21:18:41.224936 27395 slave.cpp:1316] Got registration for executor
'default' of framework 2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.225087 27395 slave.cpp:1434] Flushing queued task 1 for executor
'default' of framework 2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.225118 27394 exec.cpp:205] Executor registered on slave
2014-02-13-21:18:41-160088899-40941-27372-0
I0213 21:18:41.226194 27394 exec.cpp:217] Executor::registered took 9894ns
I0213 21:18:41.226244 27394 exec.cpp:292] Executor asked to run task '1'
I0213 21:18:41.226285 27394 exec.cpp:301] Executor::launchTask took 28605ns
I0213 21:18:41.226434 27372 master.cpp:587] Master terminating
I0213 21:18:41.226472 27372 master.cpp:247] Shutting down master
I0213 21:18:41.226486 27394 slave.cpp:1844] [email protected]:40941 exited
W0213 21:18:41.226500 27394 slave.cpp:1847] Master disconnected! Waiting for a
new master to be elected
I0213 21:18:41.226529 27397 sched.cpp:727] Stopping framework
'2014-02-13-21:18:41-160088899-40941-27372-0000'
I0213 21:18:41.226531 27372 master.hpp:429] Removing task 1 with resources
cpus(*):1; mem(*):512 on slave 2014-02-13-21:18:41-160088899-40941-27372-0
(vesta.apache.org)
I0213 21:18:41.226619 27372 master.cpp:290] Removing slave
2014-02-13-21:18:41-160088899-40941-27372-0 (vesta.apache.org)
I0213 21:18:41.226663 27393 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:18:41-160088899-40941-27372-0
from framework 2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.329572 27372 slave.cpp:287] Slave terminating
I0213 21:18:41.329640 27372 slave.cpp:1027] Asked to shut down framework
2014-02-13-21:18:41-160088899-40941-27372-0000 by @0.0.0.0:0
I0213 21:18:41.329671 27372 slave.cpp:1052] Shutting down framework
2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.329694 27372 slave.cpp:2332] Shutting down executor 'default' of
framework 2014-02-13-21:18:41-160088899-40941-27372-0000
I0213 21:18:41.329797 27395 exec.cpp:378] Executor asked to shutdown
I0213 21:18:41.329849 27395 exec.cpp:393] Executor::shutdown took 16564ns
[ OK ] MultipleExecutorsTest.TasksExecutorInfoDiffers (116 ms)
[----------] 1 test from MultipleExecutorsTest (116 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 (1 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 (1 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:18:41.333744 27398 authenticatee.hpp:124] Creating new client SASL
connection
I0213 21:18:41.334008 27398 authenticator.hpp:140] Creating new server SASL
connection
I0213 21:18:41.334120 27397 authenticatee.hpp:212] Received SASL authentication
mechanisms: CRAM-MD5
I0213 21:18:41.334149 27397 authenticatee.hpp:238] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0213 21:18:41.334178 27397 authenticator.hpp:243] Received SASL authentication
start
I0213 21:18:41.334259 27397 authenticator.hpp:325] Authentication requires more
steps
I0213 21:18:41.334293 27397 authenticatee.hpp:258] Received SASL authentication
step
I0213 21:18:41.334358 27393 authenticator.hpp:271] Received SASL authentication
step
I0213 21:18:41.334383 27393 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
I0213 21:18:41.334393 27393 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0213 21:18:41.334408 27393 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0213 21:18:41.334419 27393 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
I0213 21:18:41.334424 27393 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0213 21:18:41.334431 27393 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0213 21:18:41.334444 27393 authenticator.hpp:317] Authentication success
I0213 21:18:41.334468 27393 authenticatee.hpp:298] Authentication success
[ OK ] SASL.success (1 ms)
[ RUN ] SASL.failed1
I0213 21:18:41.334858 27393 authenticatee.hpp:124] Creating new client SASL
connection
I0213 21:18:41.335151 27391 authenticator.hpp:140] Creating new server SASL
connection
I0213 21:18:41.335252 27395 authenticatee.hpp:212] Received SASL authentication
mechanisms: CRAM-MD5
I0213 21:18:41.335268 27395 authenticatee.hpp:238] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0213 21:18:41.335300 27395 authenticator.hpp:243] Received SASL authentication
start
I0213 21:18:41.335361 27395 authenticator.hpp:325] Authentication requires more
steps
I0213 21:18:41.335392 27395 authenticatee.hpp:258] Received SASL authentication
step
I0213 21:18:41.335454 27395 authenticator.hpp:271] Received SASL authentication
step
I0213 21:18:41.335476 27395 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
I0213 21:18:41.335484 27395 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0213 21:18:41.335497 27395 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0213 21:18:41.335507 27395 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
I0213 21:18:41.335513 27395 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0213 21:18:41.335518 27395 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
W0213 21:18:41.335535 27395 authenticator.hpp:331] Authentication failure:
authentication failure
[ OK ] SASL.failed1 (1 ms)
[ RUN ] SASL.failed2
I0213 21:18:41.336061 27395 authenticatee.hpp:124] Creating new client SASL
connection
I0213 21:18:41.336312 27394 authenticator.hpp:140] Creating new server SASL
connection
I0213 21:18:41.336442 27394 authenticatee.hpp:212] Received SASL authentication
mechanisms: CRAM-MD5
I0213 21:18:41.336465 27394 authenticatee.hpp:238] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0213 21:18:41.336491 27394 authenticator.hpp:243] Received SASL authentication
start
I0213 21:18:41.336576 27394 authenticator.hpp:325] Authentication requires more
steps
I0213 21:18:41.336607 27394 authenticatee.hpp:258] Received SASL authentication
step
I0213 21:18:41.336647 27394 authenticator.hpp:271] Received SASL authentication
step
I0213 21:18:41.336673 27394 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
I0213 21:18:41.336680 27394 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0213 21:18:41.336688 27394 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0213 21:18:41.336696 27394 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
I0213 21:18:41.336702 27394 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0213 21:18:41.336707 27394 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
W0213 21:18:41.336716 27394 authenticator.hpp:331] Authentication failure: user
not found
[ OK ] SASL.failed2 (1 ms)
[----------] 3 tests from SASL (3 ms total)
[----------] 2 tests from SlaveStateTest
[ RUN ] SlaveStateTest.CheckpointProtobuf
Using temporary directory '/tmp/SlaveStateTest_CheckpointProtobuf_DMmh6Z'
[ OK ] SlaveStateTest.CheckpointProtobuf (0 ms)
[ RUN ] SlaveStateTest.CheckpointString
Using temporary directory '/tmp/SlaveStateTest_CheckpointString_oI3U5U'
[ 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.*).
-------------------------------------------------------------
F0213 21:18:41.338361 27372 mesos.cpp:362] CHECK_SOME(cgroups):
'/tmp/mesos_test_cgroup/freezer' is not a valid hierarchy
*** Check failure stack trace: ***
@ 0x2ab150bce21d google::LogMessage::Fail()
@ 0x2ab150bd031f google::LogMessage::SendToLog()
@ 0x2ab150bcde0c google::LogMessage::Flush()
@ 0x2ab150bd0bbd 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
@ 0x2ab151f9376d (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
