See
<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1655/changes>
Changes:
[vinod] Added a test for CFS cpu limits in the cpu isolator.
------------------------------------------
[...truncated 10515 lines...]
I0228 07:50:04.739220 4856 exec.cpp:217] Executor::registered took 11544ns
I0228 07:50:04.739279 4856 exec.cpp:292] Executor asked to run task '0'
I0228 07:50:04.739325 4856 exec.cpp:301] Executor::launchTask took 32081ns
I0228 07:50:04.740324 4856 exec.cpp:524] Executor sending status update
TASK_RUNNING (UUID: 8b4e200e-ffdb-4027-a63a-4cf9b09d47f8) for task 0 of
framework 2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.740401 4856 slave.cpp:1650] Handling status update TASK_RUNNING
(UUID: 8b4e200e-ffdb-4027-a63a-4cf9b09d47f8) for task 0 of framework
2014-02-28-07:50:04-1015726915-48621-4746-0000 from
executor(23)@67.195.138.60:48621
I0228 07:50:04.740520 4858 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: 8b4e200e-ffdb-4027-a63a-4cf9b09d47f8) for task 0 of
framework 2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.740540 4858 status_update_manager.cpp:499] Creating
StatusUpdate stream for task 0 of framework
2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.740617 4858 status_update_manager.cpp:373] Forwarding status
update TASK_RUNNING (UUID: 8b4e200e-ffdb-4027-a63a-4cf9b09d47f8) for task 0 of
framework 2014-02-28-07:50:04-1015726915-48621-4746-0000 to
master@67.195.138.60:48621
I0228 07:50:04.740757 4856 master.cpp:2040] Status update TASK_RUNNING (UUID:
8b4e200e-ffdb-4027-a63a-4cf9b09d47f8) for task 0 of framework
2014-02-28-07:50:04-1015726915-48621-4746-0000 from
slave(52)@67.195.138.60:48621
I0228 07:50:04.740783 4860 slave.cpp:1770] Status update manager successfully
handled status update TASK_RUNNING (UUID: 8b4e200e-ffdb-4027-a63a-4cf9b09d47f8)
for task 0 of framework 2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.740803 4860 slave.cpp:1776] Sending acknowledgement for status
update TASK_RUNNING (UUID: 8b4e200e-ffdb-4027-a63a-4cf9b09d47f8) for task 0 of
framework 2014-02-28-07:50:04-1015726915-48621-4746-0000 to
executor(23)@67.195.138.60:48621
I0228 07:50:04.740865 4861 sched.cpp:619] Scheduler::statusUpdate took 32422ns
I0228 07:50:04.740869 4854 exec.cpp:338] Executor received status update
acknowledgement 8b4e200e-ffdb-4027-a63a-4cf9b09d47f8 for task 0 of framework
2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.741056 4858 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 8b4e200e-ffdb-4027-a63a-4cf9b09d47f8) for task 0
of framework 2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.741134 4861 slave.cpp:2054] Executor 'default' of framework
2014-02-28-07:50:04-1015726915-48621-4746-0000 has exited with status 0
I0228 07:50:04.742190 4861 slave.cpp:1650] Handling status update TASK_LOST
(UUID: 381c5a31-e07c-424e-8114-4aabc388f4ef) for task 0 of framework
2014-02-28-07:50:04-1015726915-48621-4746-0000 from @0.0.0.0:0
I0228 07:50:04.742215 4861 slave.cpp:3149] Terminating task 0
I0228 07:50:04.742324 4854 status_update_manager.cpp:320] Received status
update TASK_LOST (UUID: 381c5a31-e07c-424e-8114-4aabc388f4ef) for task 0 of
framework 2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.742372 4854 status_update_manager.cpp:373] Forwarding status
update TASK_LOST (UUID: 381c5a31-e07c-424e-8114-4aabc388f4ef) for task 0 of
framework 2014-02-28-07:50:04-1015726915-48621-4746-0000 to
master@67.195.138.60:48621
I0228 07:50:04.742379 4861 slave.cpp:1263] Status update manager successfully
handled status update acknowledgement (UUID:
8b4e200e-ffdb-4027-a63a-4cf9b09d47f8) for task 0 of framework
2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.742383 4855 master.cpp:2113] Executor default of framework
2014-02-28-07:50:04-1015726915-48621-4746-0000 on slave
2014-02-28-07:50:04-1015726915-48621-4746-0 (janus.apache.org) has exited with
status 0
I0228 07:50:04.742503 4854 slave.cpp:1770] Status update manager successfully
handled status update TASK_LOST (UUID: 381c5a31-e07c-424e-8114-4aabc388f4ef)
for task 0 of framework 2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.742578 4855 master.cpp:2040] Status update TASK_LOST (UUID:
381c5a31-e07c-424e-8114-4aabc388f4ef) for task 0 of framework
2014-02-28-07:50:04-1015726915-48621-4746-0000 from
slave(52)@67.195.138.60:48621
I0228 07:50:04.742636 4855 master.hpp:441] Removing task 0 with resources
cpus(*):2; mem(*):1024 on slave 2014-02-28-07:50:04-1015726915-48621-4746-0
(janus.apache.org)
I0228 07:50:04.742656 4856 sched.cpp:619] Scheduler::statusUpdate took 9621ns
I0228 07:50:04.742791 4855 hierarchical_allocator_process.hpp:637] Recovered
cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024;
ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave
2014-02-28-07:50:04-1015726915-48621-4746-0 from framework
2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.742827 4861 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 381c5a31-e07c-424e-8114-4aabc388f4ef) for task 0
of framework 2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.742871 4861 status_update_manager.cpp:530] Cleaning up status
update stream for task 0 of framework
2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.742974 4861 slave.cpp:1263] Status update manager successfully
handled status update acknowledgement (UUID:
381c5a31-e07c-424e-8114-4aabc388f4ef) for task 0 of framework
2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.742986 4861 slave.cpp:3172] Completing task 0
I0228 07:50:04.742995 4861 slave.cpp:2193] Cleaning up executor 'default' of
framework 2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.805608 4856 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_pU3URk/slaves/2014-02-28-07:50:04-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:04-1015726915-48621-4746-0000/executors/default/runs/3b9c8fe5-e942-4da9-aae2-511c9c1ef8b2'
for gc 6.99999142378667days in the future
I0228 07:50:04.805623 4861 slave.cpp:2268] Cleaning up framework
2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.805672 4855 status_update_manager.cpp:282] Closing status
update streams for framework 2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:04.805691 4856 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_pU3URk/slaves/2014-02-28-07:50:04-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:04-1015726915-48621-4746-0000/executors/default'
for gc 6.99999142378667days in the future
I0228 07:50:04.805727 4856 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_pU3URk/slaves/2014-02-28-07:50:04-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:04-1015726915-48621-4746-0000'
for gc 6.99999142378667days in the future
I0228 07:50:05.143503 4861 slave.cpp:2518] Current usage 90.00%. Max allowed
age: 0ns
I0228 07:50:05.143563 4855 gc.cpp:167] Pruning directories with remaining
removal time 6.99999142378667days
I0228 07:50:05.143604 4855 gc.cpp:134] Deleting
/tmp/GarbageCollectorIntegrationTest_DiskUsage_pU3URk/slaves/2014-02-28-07:50:04-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:04-1015726915-48621-4746-0000/executors/default/runs/3b9c8fe5-e942-4da9-aae2-511c9c1ef8b2
I0228 07:50:05.143709 4855 gc.cpp:143] Deleted
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_pU3URk/slaves/2014-02-28-07:50:04-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:04-1015726915-48621-4746-0000/executors/default/runs/3b9c8fe5-e942-4da9-aae2-511c9c1ef8b2'
I0228 07:50:05.143749 4855 gc.cpp:134] Deleting
/tmp/GarbageCollectorIntegrationTest_DiskUsage_pU3URk/slaves/2014-02-28-07:50:04-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:04-1015726915-48621-4746-0000/executors/default
I0228 07:50:05.143869 4855 gc.cpp:143] Deleted
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_pU3URk/slaves/2014-02-28-07:50:04-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:04-1015726915-48621-4746-0000/executors/default'
I0228 07:50:05.143889 4855 gc.cpp:134] Deleting
/tmp/GarbageCollectorIntegrationTest_DiskUsage_pU3URk/slaves/2014-02-28-07:50:04-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:04-1015726915-48621-4746-0000
I0228 07:50:05.143964 4855 gc.cpp:143] Deleted
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_pU3URk/slaves/2014-02-28-07:50:04-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:04-1015726915-48621-4746-0000'
I0228 07:50:05.163955 4861 process.cpp:3189] Handling HTTP event for process
'files' with path: '/files/browse.json'
I0228 07:50:05.164350 4862 process.cpp:1008] Socket closed while receiving
I0228 07:50:05.174160 4861 sched.cpp:730] Stopping framework
'2014-02-28-07:50:04-1015726915-48621-4746-0000'
I0228 07:50:05.174160 4746 master.cpp:527] Master terminating
I0228 07:50:05.174510 4857 slave.cpp:1851] master@67.195.138.60:48621 exited
W0228 07:50:05.174537 4857 slave.cpp:1854] Master disconnected! Waiting for a
new master to be elected
I0228 07:50:05.175019 4861 slave.cpp:294] Slave terminating
[ OK ] GarbageCollectorIntegrationTest.DiskUsage (448 ms)
[ RUN ] GarbageCollectorIntegrationTest.Unschedule
I0228 07:50:05.176112 4746 master.cpp:252] Master ID:
2014-02-28-07:50:05-1015726915-48621-4746 Hostname: janus.apache.org
I0228 07:50:05.176193 4857 master.cpp:262] Master started on
67.195.138.60:48621
I0228 07:50:05.176206 4857 master.cpp:265] Master only allowing authenticated
frameworks to register!
I0228 07:50:05.176555 4855 master.cpp:96] No whitelist given. Advertising
offers for all slaves
I0228 07:50:05.176633 4861 hierarchical_allocator_process.hpp:302]
Initializing hierarchical allocator process with master :
master@67.195.138.60:48621
I0228 07:50:05.176964 4857 master.cpp:770] The newly elected leader is
master@67.195.138.60:48621 with id 2014-02-28-07:50:05-1015726915-48621-4746
I0228 07:50:05.176982 4857 master.cpp:780] Elected as the leading master!
I0228 07:50:05.229645 4857 slave.cpp:119] Slave started on
53)@67.195.138.60:48621
I0228 07:50:05.229773 4857 slave.cpp:129] Slave resources: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0228 07:50:05.229856 4857 slave.cpp:157] Slave hostname: janus.apache.org
I0228 07:50:05.229863 4857 slave.cpp:158] Slave checkpoint: false
I0228 07:50:05.230262 4859 state.cpp:33] Recovering state from
'/tmp/GarbageCollectorIntegrationTest_Unschedule_mrp4Vw/meta'
I0228 07:50:05.230460 4861 status_update_manager.cpp:193] Recovering status
update manager
I0228 07:50:05.230670 4860 slave.cpp:2677] Finished recovery
I0228 07:50:05.231830 4861 slave.cpp:404] New master detected at
master@67.195.138.60:48621
I0228 07:50:05.231895 4861 slave.cpp:429] Detecting new master
I0228 07:50:05.231976 4860 status_update_manager.cpp:167] New master detected
at master@67.195.138.60:48621
I0228 07:50:05.232012 4861 master.cpp:1857] Attempting to register slave on
janus.apache.org at slave(53)@67.195.138.60:48621
I0228 07:50:05.232027 4861 master.cpp:2840] Adding slave
2014-02-28-07:50:05-1015726915-48621-4746-0 at janus.apache.org with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0228 07:50:05.232177 4858 slave.cpp:447] Registered with master
master@67.195.138.60:48621; given slave ID
2014-02-28-07:50:05-1015726915-48621-4746-0
I0228 07:50:05.232230 4860 hierarchical_allocator_process.hpp:445] Added slave
2014-02-28-07:50:05-1015726915-48621-4746-0 (janus.apache.org) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000] available)
I0228 07:50:05.232295 4860 hierarchical_allocator_process.hpp:708] Performed
allocation for slave 2014-02-28-07:50:05-1015726915-48621-4746-0 in 9714ns
I0228 07:50:05.233348 4746 sched.cpp:121] Version: 0.19.0
I0228 07:50:05.233614 4857 sched.cpp:217] New master detected at
master@67.195.138.60:48621
I0228 07:50:05.233633 4857 sched.cpp:267] Authenticating with master
master@67.195.138.60:48621
I0228 07:50:05.233698 4855 authenticatee.hpp:128] Creating new client SASL
connection
I0228 07:50:05.233877 4859 master.cpp:2353] Authenticating framework at
scheduler(70)@67.195.138.60:48621
I0228 07:50:05.233974 4855 authenticator.hpp:148] Creating new server SASL
connection
I0228 07:50:05.234096 4860 authenticatee.hpp:219] Received SASL authentication
mechanisms: CRAM-MD5
I0228 07:50:05.234122 4860 authenticatee.hpp:245] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0228 07:50:05.234169 4860 authenticator.hpp:254] Received SASL authentication
start
I0228 07:50:05.234223 4860 authenticator.hpp:342] Authentication requires more
steps
I0228 07:50:05.234251 4860 authenticatee.hpp:265] Received SASL authentication
step
I0228 07:50:05.234309 4860 authenticator.hpp:282] Received SASL authentication
step
I0228 07:50:05.234338 4860 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'janus.apache.org' server FQDN:
'janus.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0228 07:50:05.234350 4860 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0228 07:50:05.234364 4860 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0228 07:50:05.234372 4860 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'janus.apache.org' server FQDN:
'janus.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0228 07:50:05.234379 4860 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0228 07:50:05.234383 4860 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0228 07:50:05.234405 4860 authenticator.hpp:334] Authentication success
I0228 07:50:05.234459 4855 authenticatee.hpp:305] Authentication success
I0228 07:50:05.234525 4855 sched.cpp:341] Successfully authenticated with
master master@67.195.138.60:48621
I0228 07:50:05.234545 4855 sched.cpp:460] Sending registration request to
master@67.195.138.60:48621
I0228 07:50:05.234662 4856 master.cpp:2393] Successfully authenticated
framework at scheduler(70)@67.195.138.60:48621
I0228 07:50:05.234757 4856 master.cpp:828] Received registration request from
scheduler(70)@67.195.138.60:48621
I0228 07:50:05.234781 4856 master.cpp:846] Registering framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 at
scheduler(70)@67.195.138.60:48621
I0228 07:50:05.234868 4858 sched.cpp:391] Framework registered with
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.234887 4857 hierarchical_allocator_process.hpp:332] Added
framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.234905 4858 sched.cpp:405] Scheduler::registered took 22145ns
I0228 07:50:05.234952 4857 hierarchical_allocator_process.hpp:752] Offering
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-28-07:50:05-1015726915-48621-4746-0 to framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.235118 4857 hierarchical_allocator_process.hpp:688] Performed
allocation for 1 slaves in 215327ns
I0228 07:50:05.235195 4859 master.hpp:451] Adding offer
2014-02-28-07:50:05-1015726915-48621-4746-0 with resources cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-28-07:50:05-1015726915-48621-4746-0 (janus.apache.org)
I0228 07:50:05.235256 4859 master.cpp:2308] Sending 1 offers to framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.235537 4856 sched.cpp:528] Scheduler::resourceOffers took
150609ns
I0228 07:50:05.235734 4859 master.hpp:461] Removing offer
2014-02-28-07:50:05-1015726915-48621-4746-0 with resources cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-28-07:50:05-1015726915-48621-4746-0 (janus.apache.org)
I0228 07:50:05.235795 4859 master.cpp:1584] Processing reply for offers: [
2014-02-28-07:50:05-1015726915-48621-4746-0 ] on slave
2014-02-28-07:50:05-1015726915-48621-4746-0 (janus.apache.org) for framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.235887 4859 master.hpp:423] Adding task 0 with resources
cpus(*):2; mem(*):1024 on slave 2014-02-28-07:50:05-1015726915-48621-4746-0
(janus.apache.org)
I0228 07:50:05.235918 4859 master.cpp:2477] Launching task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 with resources cpus(*):2;
mem(*):1024 on slave 2014-02-28-07:50:05-1015726915-48621-4746-0
(janus.apache.org)
I0228 07:50:05.236012 4856 slave.cpp:632] Got assigned task 0 for framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.236068 4855 hierarchical_allocator_process.hpp:547] Framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 left disk(*):1024;
ports(*):[31000-32000] unused on slave
2014-02-28-07:50:05-1015726915-48621-4746-0
I0228 07:50:05.236140 4855 hierarchical_allocator_process.hpp:590] Framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 filtered slave
2014-02-28-07:50:05-1015726915-48621-4746-0 for 5secs
I0228 07:50:05.236189 4856 slave.cpp:741] Launching task 0 for framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.238026 4856 exec.cpp:131] Version: 0.19.0
I0228 07:50:05.238093 4859 exec.cpp:181] Executor started at:
executor(24)@67.195.138.60:48621 with pid 4746
I0228 07:50:05.238122 4856 slave.cpp:851] Queuing task '0' for executor
executor-1 of framework '2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.238158 4856 slave.cpp:365] Successfully attached file
'/tmp/GarbageCollectorIntegrationTest_Unschedule_mrp4Vw/slaves/2014-02-28-07:50:05-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:05-1015726915-48621-4746-0000/executors/executor-1/runs/66d322d5-44cb-4755-9b1d-a15a6225d5db'
I0228 07:50:05.238183 4856 slave.cpp:1996] Monitoring executor 'executor-1' of
framework '2014-02-28-07:50:05-1015726915-48621-4746-0000' in container
'66d322d5-44cb-4755-9b1d-a15a6225d5db'
I0228 07:50:05.238243 4856 slave.cpp:1323] Got registration for executor
'executor-1' of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.238379 4856 slave.cpp:1441] Flushing queued task 0 for executor
'executor-1' of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.238417 4858 exec.cpp:205] Executor registered on slave
2014-02-28-07:50:05-1015726915-48621-4746-0
I0228 07:50:05.239398 4858 exec.cpp:217] Executor::registered took 12276ns
I0228 07:50:05.239462 4858 exec.cpp:292] Executor asked to run task '0'
I0228 07:50:05.239496 4858 exec.cpp:301] Executor::launchTask took 21155ns
I0228 07:50:05.240458 4858 exec.cpp:524] Executor sending status update
TASK_RUNNING (UUID: 37357fd2-b82b-4cb8-85de-f6c81535be5e) for task 0 of
framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.240567 4857 slave.cpp:1650] Handling status update TASK_RUNNING
(UUID: 37357fd2-b82b-4cb8-85de-f6c81535be5e) for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 from
executor(24)@67.195.138.60:48621
I0228 07:50:05.240653 4855 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: 37357fd2-b82b-4cb8-85de-f6c81535be5e) for task 0 of
framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.240669 4855 status_update_manager.cpp:499] Creating
StatusUpdate stream for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.240726 4855 status_update_manager.cpp:373] Forwarding status
update TASK_RUNNING (UUID: 37357fd2-b82b-4cb8-85de-f6c81535be5e) for task 0 of
framework 2014-02-28-07:50:05-1015726915-48621-4746-0000 to
master@67.195.138.60:48621
I0228 07:50:05.240806 4855 slave.cpp:1770] Status update manager successfully
handled status update TASK_RUNNING (UUID: 37357fd2-b82b-4cb8-85de-f6c81535be5e)
for task 0 of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.240821 4855 slave.cpp:1776] Sending acknowledgement for status
update TASK_RUNNING (UUID: 37357fd2-b82b-4cb8-85de-f6c81535be5e) for task 0 of
framework 2014-02-28-07:50:05-1015726915-48621-4746-0000 to
executor(24)@67.195.138.60:48621
I0228 07:50:05.240828 4861 master.cpp:2040] Status update TASK_RUNNING (UUID:
37357fd2-b82b-4cb8-85de-f6c81535be5e) for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 from
slave(53)@67.195.138.60:48621
I0228 07:50:05.240886 4861 exec.cpp:338] Executor received status update
acknowledgement 37357fd2-b82b-4cb8-85de-f6c81535be5e for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.240905 4855 sched.cpp:619] Scheduler::statusUpdate took 20169ns
I0228 07:50:05.241055 4861 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 37357fd2-b82b-4cb8-85de-f6c81535be5e) for task 0
of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.241236 4855 slave.cpp:1263] Status update manager successfully
handled status update acknowledgement (UUID:
37357fd2-b82b-4cb8-85de-f6c81535be5e) for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.241394 4860 slave.cpp:2054] Executor 'executor-1' of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 has exited with status 0
I0228 07:50:05.242372 4860 slave.cpp:1650] Handling status update TASK_LOST
(UUID: 9548fc26-617f-4907-83c1-c2cf853ced90) for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 from @0.0.0.0:0
I0228 07:50:05.242429 4860 slave.cpp:3149] Terminating task 0
I0228 07:50:05.242558 4855 status_update_manager.cpp:320] Received status
update TASK_LOST (UUID: 9548fc26-617f-4907-83c1-c2cf853ced90) for task 0 of
framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.242583 4855 status_update_manager.cpp:373] Forwarding status
update TASK_LOST (UUID: 9548fc26-617f-4907-83c1-c2cf853ced90) for task 0 of
framework 2014-02-28-07:50:05-1015726915-48621-4746-0000 to
master@67.195.138.60:48621
I0228 07:50:05.242594 4860 master.cpp:2113] Executor executor-1 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 on slave
2014-02-28-07:50:05-1015726915-48621-4746-0 (janus.apache.org) has exited with
status 0
I0228 07:50:05.242679 4855 slave.cpp:1770] Status update manager successfully
handled status update TASK_LOST (UUID: 9548fc26-617f-4907-83c1-c2cf853ced90)
for task 0 of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.242697 4860 master.cpp:2040] Status update TASK_LOST (UUID:
9548fc26-617f-4907-83c1-c2cf853ced90) for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 from
slave(53)@67.195.138.60:48621
I0228 07:50:05.242737 4860 master.hpp:441] Removing task 0 with resources
cpus(*):2; mem(*):1024 on slave 2014-02-28-07:50:05-1015726915-48621-4746-0
(janus.apache.org)
I0228 07:50:05.242744 4855 sched.cpp:619] Scheduler::statusUpdate took 9495ns
I0228 07:50:05.242844 4860 hierarchical_allocator_process.hpp:637] Recovered
cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024;
ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave
2014-02-28-07:50:05-1015726915-48621-4746-0 from framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.242897 4855 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 9548fc26-617f-4907-83c1-c2cf853ced90) for task 0
of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.242923 4855 status_update_manager.cpp:530] Cleaning up status
update stream for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.242987 4860 slave.cpp:1263] Status update manager successfully
handled status update acknowledgement (UUID:
9548fc26-617f-4907-83c1-c2cf853ced90) for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.243019 4860 slave.cpp:3172] Completing task 0
I0228 07:50:05.243028 4860 slave.cpp:2193] Cleaning up executor 'executor-1'
of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.243109 4855 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_Unschedule_mrp4Vw/slaves/2014-02-28-07:50:05-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:05-1015726915-48621-4746-0000/executors/executor-1/runs/66d322d5-44cb-4755-9b1d-a15a6225d5db'
for gc 6.99999720731556days in the future
I0228 07:50:05.243131 4860 slave.cpp:2268] Cleaning up framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.243162 4855 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_Unschedule_mrp4Vw/slaves/2014-02-28-07:50:05-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:05-1015726915-48621-4746-0000/executors/executor-1'
for gc 6.99999720731556days in the future
I0228 07:50:05.243206 4860 status_update_manager.cpp:282] Closing status
update streams for framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.243221 4855 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_Unschedule_mrp4Vw/slaves/2014-02-28-07:50:05-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:05-1015726915-48621-4746-0000'
for gc 6.99999720731556days in the future
I0228 07:50:05.645196 4860 hierarchical_allocator_process.hpp:752] Offering
disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave
2014-02-28-07:50:05-1015726915-48621-4746-0 to framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.645380 4860 hierarchical_allocator_process.hpp:688] Performed
allocation for 1 slaves in 270705ns
I0228 07:50:05.645541 4856 master.hpp:451] Adding offer
2014-02-28-07:50:05-1015726915-48621-4746-1 with resources disk(*):1024;
ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave
2014-02-28-07:50:05-1015726915-48621-4746-0 (janus.apache.org)
I0228 07:50:05.645637 4856 master.cpp:2308] Sending 1 offers to framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.646023 4855 sched.cpp:528] Scheduler::resourceOffers took
178512ns
I0228 07:50:05.646221 4855 master.hpp:461] Removing offer
2014-02-28-07:50:05-1015726915-48621-4746-1 with resources disk(*):1024;
ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave
2014-02-28-07:50:05-1015726915-48621-4746-0 (janus.apache.org)
I0228 07:50:05.646277 4855 master.cpp:1584] Processing reply for offers: [
2014-02-28-07:50:05-1015726915-48621-4746-1 ] on slave
2014-02-28-07:50:05-1015726915-48621-4746-0 (janus.apache.org) for framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.646356 4855 master.hpp:423] Adding task 0 with resources
cpus(*):2; mem(*):1024 on slave 2014-02-28-07:50:05-1015726915-48621-4746-0
(janus.apache.org)
I0228 07:50:05.646384 4855 master.cpp:2477] Launching task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 with resources cpus(*):2;
mem(*):1024 on slave 2014-02-28-07:50:05-1015726915-48621-4746-0
(janus.apache.org)
I0228 07:50:05.646525 4858 slave.cpp:632] Got assigned task 0 for framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.646586 4856 hierarchical_allocator_process.hpp:547] Framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 left disk(*):1024;
ports(*):[31000-32000] unused on slave
2014-02-28-07:50:05-1015726915-48621-4746-0
I0228 07:50:05.646677 4856 hierarchical_allocator_process.hpp:590] Framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 filtered slave
2014-02-28-07:50:05-1015726915-48621-4746-0 for 5secs
I0228 07:50:05.646872 4858 gc.cpp:84] Unscheduling
'/tmp/GarbageCollectorIntegrationTest_Unschedule_mrp4Vw/slaves/2014-02-28-07:50:05-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:05-1015726915-48621-4746-0000'
from gc
I0228 07:50:05.646985 4855 slave.cpp:741] Launching task 0 for framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.648854 4855 exec.cpp:131] Version: 0.19.0
I0228 07:50:05.648939 4859 exec.cpp:181] Executor started at:
executor(25)@67.195.138.60:48621 with pid 4746
I0228 07:50:05.648963 4855 slave.cpp:851] Queuing task '0' for executor
executor-2 of framework '2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.648998 4855 slave.cpp:365] Successfully attached file
'/tmp/GarbageCollectorIntegrationTest_Unschedule_mrp4Vw/slaves/2014-02-28-07:50:05-1015726915-48621-4746-0/frameworks/2014-02-28-07:50:05-1015726915-48621-4746-0000/executors/executor-2/runs/faed896d-6871-4431-a0a3-7132740bfbbf'
I0228 07:50:05.649024 4855 slave.cpp:1996] Monitoring executor 'executor-2' of
framework '2014-02-28-07:50:05-1015726915-48621-4746-0000' in container
'faed896d-6871-4431-a0a3-7132740bfbbf'
I0228 07:50:05.649090 4855 slave.cpp:1323] Got registration for executor
'executor-2' of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.649206 4855 slave.cpp:1441] Flushing queued task 0 for executor
'executor-2' of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.649242 4859 exec.cpp:205] Executor registered on slave
2014-02-28-07:50:05-1015726915-48621-4746-0
I0228 07:50:05.650054 4859 exec.cpp:217] Executor::registered took 10464ns
I0228 07:50:05.650113 4859 exec.cpp:292] Executor asked to run task '0'
I0228 07:50:05.650151 4859 exec.cpp:301] Executor::launchTask took 25642ns
I0228 07:50:05.650921 4859 exec.cpp:524] Executor sending status update
TASK_RUNNING (UUID: 78a6e753-e5ba-466b-b618-256e3fd36e8e) for task 0 of
framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.651011 4859 slave.cpp:1650] Handling status update TASK_RUNNING
(UUID: 78a6e753-e5ba-466b-b618-256e3fd36e8e) for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 from
executor(25)@67.195.138.60:48621
I0228 07:50:05.651113 4861 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: 78a6e753-e5ba-466b-b618-256e3fd36e8e) for task 0 of
framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.651139 4861 status_update_manager.cpp:499] Creating
StatusUpdate stream for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.651209 4861 status_update_manager.cpp:373] Forwarding status
update TASK_RUNNING (UUID: 78a6e753-e5ba-466b-b618-256e3fd36e8e) for task 0 of
framework 2014-02-28-07:50:05-1015726915-48621-4746-0000 to
master@67.195.138.60:48621
I0228 07:50:05.651326 4855 slave.cpp:1770] Status update manager successfully
handled status update TASK_RUNNING (UUID: 78a6e753-e5ba-466b-b618-256e3fd36e8e)
for task 0 of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.651347 4855 slave.cpp:1776] Sending acknowledgement for status
update TASK_RUNNING (UUID: 78a6e753-e5ba-466b-b618-256e3fd36e8e) for task 0 of
framework 2014-02-28-07:50:05-1015726915-48621-4746-0000 to
executor(25)@67.195.138.60:48621
I0228 07:50:05.651355 4859 master.cpp:2040] Status update TASK_RUNNING (UUID:
78a6e753-e5ba-466b-b618-256e3fd36e8e) for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 from
slave(53)@67.195.138.60:48621
I0228 07:50:05.651425 4857 exec.cpp:338] Executor received status update
acknowledgement 78a6e753-e5ba-466b-b618-256e3fd36e8e for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.651468 4854 sched.cpp:619] Scheduler::statusUpdate took 12449ns
I0228 07:50:05.651610 4854 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 78a6e753-e5ba-466b-b618-256e3fd36e8e) for task 0
of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.651684 4854 slave.cpp:1263] Status update manager successfully
handled status update acknowledgement (UUID:
78a6e753-e5ba-466b-b618-256e3fd36e8e) for task 0 of framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.665117 4859 sched.cpp:730] Stopping framework
'2014-02-28-07:50:05-1015726915-48621-4746-0000'
I0228 07:50:05.665122 4746 master.cpp:527] Master terminating
I0228 07:50:05.665209 4746 master.hpp:441] Removing task 0 with resources
cpus(*):2; mem(*):1024 on slave 2014-02-28-07:50:05-1015726915-48621-4746-0
(janus.apache.org)
I0228 07:50:05.665336 4856 hierarchical_allocator_process.hpp:637] Recovered
cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024;
ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave
2014-02-28-07:50:05-1015726915-48621-4746-0 from framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.665444 4854 slave.cpp:1851] master@67.195.138.60:48621 exited
W0228 07:50:05.665462 4854 slave.cpp:1854] Master disconnected! Waiting for a
new master to be elected
I0228 07:50:05.665797 4856 slave.cpp:294] Slave terminating
I0228 07:50:05.665818 4856 slave.cpp:1034] Asked to shut down framework
2014-02-28-07:50:05-1015726915-48621-4746-0000 by @0.0.0.0:0
I0228 07:50:05.665827 4856 slave.cpp:1059] Shutting down framework
2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.665843 4856 slave.cpp:2339] Shutting down executor 'executor-2'
of framework 2014-02-28-07:50:05-1015726915-48621-4746-0000
I0228 07:50:05.665927 4861 exec.cpp:378] Executor asked to shutdown
I0228 07:50:05.666000 4861 exec.cpp:393] Executor::shutdown took 35716ns
[ OK ] GarbageCollectorIntegrationTest.Unschedule (491 ms)
[----------] 5 tests from GarbageCollectorIntegrationTest (2459 ms total)
[----------] 2 tests from CpuIsolatorTest/0, where TypeParam =
mesos::internal::slave::PosixCpuIsolatorProcess
[ RUN ] CpuIsolatorTest/0.UserCpuUsage
I0228 07:50:05.668663 4746 launcher.cpp:120] Forked child with pid '5889' for
container 'user_cpu_usage'
[ OK ] CpuIsolatorTest/0.UserCpuUsage (1004 ms)
[ RUN ] CpuIsolatorTest/0.SystemCpuUsage
I0228 07:50:06.671387 4746 launcher.cpp:120] Forked child with pid '5904' for
container 'system_cpu_usage'
[ OK ] CpuIsolatorTest/0.SystemCpuUsage (1000 ms)
[----------] 2 tests from CpuIsolatorTest/0 (2004 ms total)
[----------] 1 test from LimitedCpuIsolatorTest
[ RUN ] LimitedCpuIsolatorTest.CgroupsCfs
F0228 07:50:07.671116 4746 isolator_tests.cpp:315] CHECK_SOME(isolator):
Failed to create isolator: Using cgroups requires root permissions
*** Check failure stack trace: ***
@ 0x2b1fd66532fd google::LogMessage::Fail()
@ 0x2b1fd66553ff google::LogMessage::SendToLog()
@ 0x2b1fd6652eec google::LogMessage::Flush()
@ 0x2b1fd6655c9d google::LogMessageFatal::~LogMessageFatal()
@ 0x4cf64d _CheckSome::~_CheckSome()
@ 0x63b089 LimitedCpuIsolatorTest_CgroupsCfs_Test::TestBody()
@ 0x854fed
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x84d271 testing::Test::Run()
@ 0x84d356 testing::TestInfo::Run()
@ 0x84d497 testing::TestCase::Run()
@ 0x84d7ee testing::internal::UnitTestImpl::RunAllTests()
@ 0x854b6d
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x84c8ce testing::UnitTest::Run()
@ 0x4a90cb main
@ 0x2b1fd7a1876d (unknown)
@ 0x4b27b1 (unknown)
make[3]: *** [check-local] Aborted
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
Build step 'Execute shell' marked build as failure
