See
<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/1928/changes>
Changes:
[vinod] Added a test for CFS cpu limits in the cpu isolator.
------------------------------------------
[...truncated 9124 lines...]
I0228 07:46:11.592021 3630 exec.cpp:217] Executor::registered took 16074ns
I0228 07:46:11.592072 3630 exec.cpp:292] Executor asked to run task '0'
I0228 07:46:11.592099 3630 exec.cpp:301] Executor::launchTask took 17579ns
I0228 07:46:11.593041 3630 exec.cpp:524] Executor sending status update
TASK_RUNNING (UUID: 852e1547-9029-4d23-acfe-ea5bc95c40a2) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.593114 3630 slave.cpp:1650] Handling status update TASK_RUNNING
(UUID: 852e1547-9029-4d23-acfe-ea5bc95c40a2) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 from
executor(23)@67.195.138.60:57583
I0228 07:46:11.593204 3633 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: 852e1547-9029-4d23-acfe-ea5bc95c40a2) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.593216 3633 status_update_manager.cpp:499] Creating
StatusUpdate stream for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.593278 3633 status_update_manager.cpp:373] Forwarding status
update TASK_RUNNING (UUID: 852e1547-9029-4d23-acfe-ea5bc95c40a2) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000 to
master@67.195.138.60:57583
I0228 07:46:11.593390 3630 master.cpp:2040] Status update TASK_RUNNING (UUID:
852e1547-9029-4d23-acfe-ea5bc95c40a2) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 from
slave(52)@67.195.138.60:57583
I0228 07:46:11.593436 3631 slave.cpp:1770] Status update manager successfully
handled status update TASK_RUNNING (UUID: 852e1547-9029-4d23-acfe-ea5bc95c40a2)
for task 0 of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.593456 3631 slave.cpp:1776] Sending acknowledgement for status
update TASK_RUNNING (UUID: 852e1547-9029-4d23-acfe-ea5bc95c40a2) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000 to
executor(23)@67.195.138.60:57583
I0228 07:46:11.593441 3633 sched.cpp:619] Scheduler::statusUpdate took 19325ns
I0228 07:46:11.593567 3627 exec.cpp:338] Executor received status update
acknowledgement 852e1547-9029-4d23-acfe-ea5bc95c40a2 for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.593618 3627 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 852e1547-9029-4d23-acfe-ea5bc95c40a2) for task 0
of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.593710 3626 slave.cpp:1263] Status update manager successfully
handled status update acknowledgement (UUID:
852e1547-9029-4d23-acfe-ea5bc95c40a2) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.593752 3626 slave.cpp:2054] Executor 'default' of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 has exited with status 0
I0228 07:46:11.594548 3626 slave.cpp:1650] Handling status update TASK_LOST
(UUID: fa8708a3-2b0b-4706-b1d7-e132c8c86684) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 from @0.0.0.0:0
I0228 07:46:11.594568 3626 slave.cpp:3149] Terminating task 0
I0228 07:46:11.594681 3633 status_update_manager.cpp:320] Received status
update TASK_LOST (UUID: fa8708a3-2b0b-4706-b1d7-e132c8c86684) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.594694 3628 master.cpp:2113] Executor default of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 on slave
2014-02-28-07:46:11-1015726915-57583-3604-0 (janus.apache.org) has exited with
status 0
I0228 07:46:11.594710 3633 status_update_manager.cpp:373] Forwarding status
update TASK_LOST (UUID: fa8708a3-2b0b-4706-b1d7-e132c8c86684) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000 to
master@67.195.138.60:57583
I0228 07:46:11.594816 3630 slave.cpp:1770] Status update manager successfully
handled status update TASK_LOST (UUID: fa8708a3-2b0b-4706-b1d7-e132c8c86684)
for task 0 of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.594820 3632 master.cpp:2040] Status update TASK_LOST (UUID:
fa8708a3-2b0b-4706-b1d7-e132c8c86684) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 from
slave(52)@67.195.138.60:57583
I0228 07:46:11.594858 3632 master.hpp:441] Removing task 0 with resources
cpus(*):2; mem(*):1024 on slave 2014-02-28-07:46:11-1015726915-57583-3604-0
(janus.apache.org)
I0228 07:46:11.594872 3633 sched.cpp:619] Scheduler::statusUpdate took 9359ns
I0228 07:46:11.594954 3631 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:46:11-1015726915-57583-3604-0 from framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.595018 3628 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: fa8708a3-2b0b-4706-b1d7-e132c8c86684) for task 0
of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.595047 3628 status_update_manager.cpp:530] Cleaning up status
update stream for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.595130 3632 slave.cpp:1263] Status update manager successfully
handled status update acknowledgement (UUID:
fa8708a3-2b0b-4706-b1d7-e132c8c86684) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.595144 3632 slave.cpp:3172] Completing task 0
I0228 07:46:11.595150 3632 slave.cpp:2193] Cleaning up executor 'default' of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.595213 3628 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_JgoBg9/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000/executors/default/runs/e2b170ac-99a4-4f42-b301-e393eaceaaf2'
for gc 6.99999312942222days in the future
I0228 07:46:11.595235 3632 slave.cpp:2268] Cleaning up framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.595254 3628 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_JgoBg9/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000/executors/default'
for gc 6.99999312942222days in the future
I0228 07:46:11.595281 3627 status_update_manager.cpp:282] Closing status
update streams for framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.595294 3631 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_JgoBg9/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000'
for gc 6.99999312942222days in the future
I0228 07:46:11.815047 3633 slave.cpp:2518] Current usage 90.00%. Max allowed
age: 0ns
I0228 07:46:11.815114 3632 gc.cpp:167] Pruning directories with remaining
removal time 6.99999312942222days
I0228 07:46:11.815167 3632 gc.cpp:134] Deleting
/tmp/GarbageCollectorIntegrationTest_DiskUsage_JgoBg9/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000/executors/default/runs/e2b170ac-99a4-4f42-b301-e393eaceaaf2
I0228 07:46:11.815233 3632 gc.cpp:143] Deleted
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_JgoBg9/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000/executors/default/runs/e2b170ac-99a4-4f42-b301-e393eaceaaf2'
I0228 07:46:11.815266 3632 gc.cpp:134] Deleting
/tmp/GarbageCollectorIntegrationTest_DiskUsage_JgoBg9/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000/executors/default
I0228 07:46:11.815394 3632 gc.cpp:143] Deleted
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_JgoBg9/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000/executors/default'
I0228 07:46:11.815407 3632 gc.cpp:134] Deleting
/tmp/GarbageCollectorIntegrationTest_DiskUsage_JgoBg9/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.815476 3632 gc.cpp:143] Deleted
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_JgoBg9/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000'
I0228 07:46:11.835315 3628 process.cpp:3189] Handling HTTP event for process
'files' with path: '/files/browse.json'
I0228 07:46:11.835675 3634 process.cpp:1008] Socket closed while receiving
I0228 07:46:11.845571 3632 sched.cpp:730] Stopping framework
'2014-02-28-07:46:11-1015726915-57583-3604-0000'
I0228 07:46:11.845576 3604 master.cpp:527] Master terminating
I0228 07:46:11.845754 3633 slave.cpp:1851] master@67.195.138.60:57583 exited
W0228 07:46:11.845769 3633 slave.cpp:1854] Master disconnected! Waiting for a
new master to be elected
I0228 07:46:11.846115 3627 slave.cpp:294] Slave terminating
[ OK ] GarbageCollectorIntegrationTest.DiskUsage (351 ms)
[ RUN ] GarbageCollectorIntegrationTest.Unschedule
I0228 07:46:11.847152 3604 master.cpp:252] Master ID:
2014-02-28-07:46:11-1015726915-57583-3604 Hostname: janus.apache.org
I0228 07:46:11.847218 3628 master.cpp:262] Master started on
67.195.138.60:57583
I0228 07:46:11.847229 3628 master.cpp:265] Master only allowing authenticated
frameworks to register!
I0228 07:46:11.847455 3627 master.cpp:96] No whitelist given. Advertising
offers for all slaves
I0228 07:46:11.847467 3632 hierarchical_allocator_process.hpp:302]
Initializing hierarchical allocator process with master :
master@67.195.138.60:57583
I0228 07:46:11.847899 3628 master.cpp:770] The newly elected leader is
master@67.195.138.60:57583 with id 2014-02-28-07:46:11-1015726915-57583-3604
I0228 07:46:11.847918 3628 master.cpp:780] Elected as the leading master!
I0228 07:46:11.849596 3626 slave.cpp:119] Slave started on
53)@67.195.138.60:57583
I0228 07:46:11.849802 3626 slave.cpp:129] Slave resources: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0228 07:46:11.849863 3626 slave.cpp:157] Slave hostname: janus.apache.org
I0228 07:46:11.849874 3626 slave.cpp:158] Slave checkpoint: false
I0228 07:46:11.850353 3633 state.cpp:33] Recovering state from
'/tmp/GarbageCollectorIntegrationTest_Unschedule_9UeuPN/meta'
I0228 07:46:11.850464 3633 status_update_manager.cpp:193] Recovering status
update manager
I0228 07:46:11.850620 3632 slave.cpp:2677] Finished recovery
I0228 07:46:11.867764 3632 slave.cpp:404] New master detected at
master@67.195.138.60:57583
I0228 07:46:11.867822 3632 slave.cpp:429] Detecting new master
I0228 07:46:11.867879 3631 status_update_manager.cpp:167] New master detected
at master@67.195.138.60:57583
I0228 07:46:11.867889 3628 master.cpp:1857] Attempting to register slave on
janus.apache.org at slave(53)@67.195.138.60:57583
I0228 07:46:11.867904 3628 master.cpp:2840] Adding slave
2014-02-28-07:46:11-1015726915-57583-3604-0 at janus.apache.org with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0228 07:46:11.867993 3632 slave.cpp:447] Registered with master
master@67.195.138.60:57583; given slave ID
2014-02-28-07:46:11-1015726915-57583-3604-0
I0228 07:46:11.868175 3631 hierarchical_allocator_process.hpp:445] Added slave
2014-02-28-07:46:11-1015726915-57583-3604-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:46:11.868218 3631 hierarchical_allocator_process.hpp:708] Performed
allocation for slave 2014-02-28-07:46:11-1015726915-57583-3604-0 in 8231ns
I0228 07:46:11.869209 3604 sched.cpp:121] Version: 0.19.0
I0228 07:46:11.869385 3628 sched.cpp:217] New master detected at
master@67.195.138.60:57583
I0228 07:46:11.869403 3628 sched.cpp:267] Authenticating with master
master@67.195.138.60:57583
I0228 07:46:11.869498 3628 authenticatee.hpp:128] Creating new client SASL
connection
I0228 07:46:11.869635 3628 master.cpp:2353] Authenticating framework at
scheduler(70)@67.195.138.60:57583
I0228 07:46:11.869717 3628 authenticator.hpp:148] Creating new server SASL
connection
I0228 07:46:11.869810 3628 authenticatee.hpp:219] Received SASL authentication
mechanisms: CRAM-MD5
I0228 07:46:11.869829 3628 authenticatee.hpp:245] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0228 07:46:11.869855 3628 authenticator.hpp:254] Received SASL authentication
start
I0228 07:46:11.869891 3628 authenticator.hpp:342] Authentication requires more
steps
I0228 07:46:11.869918 3628 authenticatee.hpp:265] Received SASL authentication
step
I0228 07:46:11.869954 3628 authenticator.hpp:282] Received SASL authentication
step
I0228 07:46:11.869973 3628 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:46:11.869982 3628 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0228 07:46:11.869997 3628 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0228 07:46:11.870008 3628 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:46:11.870017 3628 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0228 07:46:11.870023 3628 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0228 07:46:11.870035 3628 authenticator.hpp:334] Authentication success
I0228 07:46:11.870061 3628 authenticatee.hpp:305] Authentication success
I0228 07:46:11.870082 3628 master.cpp:2393] Successfully authenticated
framework at scheduler(70)@67.195.138.60:57583
I0228 07:46:11.870144 3628 sched.cpp:341] Successfully authenticated with
master master@67.195.138.60:57583
I0228 07:46:11.870157 3628 sched.cpp:460] Sending registration request to
master@67.195.138.60:57583
I0228 07:46:11.870199 3628 master.cpp:828] Received registration request from
scheduler(70)@67.195.138.60:57583
I0228 07:46:11.870229 3628 master.cpp:846] Registering framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 at
scheduler(70)@67.195.138.60:57583
I0228 07:46:11.870283 3628 sched.cpp:391] Framework registered with
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.870308 3628 sched.cpp:405] Scheduler::registered took 14531ns
I0228 07:46:11.870336 3628 hierarchical_allocator_process.hpp:332] Added
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.870379 3628 hierarchical_allocator_process.hpp:752] Offering
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-28-07:46:11-1015726915-57583-3604-0 to framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.870568 3628 hierarchical_allocator_process.hpp:688] Performed
allocation for 1 slaves in 217712ns
I0228 07:46:11.870650 3630 master.hpp:451] Adding offer
2014-02-28-07:46:11-1015726915-57583-3604-0 with resources cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-28-07:46:11-1015726915-57583-3604-0 (janus.apache.org)
I0228 07:46:11.870700 3630 master.cpp:2308] Sending 1 offers to framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.870935 3632 sched.cpp:528] Scheduler::resourceOffers took
143210ns
I0228 07:46:11.871070 3632 master.hpp:461] Removing offer
2014-02-28-07:46:11-1015726915-57583-3604-0 with resources cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
2014-02-28-07:46:11-1015726915-57583-3604-0 (janus.apache.org)
I0228 07:46:11.871115 3632 master.cpp:1584] Processing reply for offers: [
2014-02-28-07:46:11-1015726915-57583-3604-0 ] on slave
2014-02-28-07:46:11-1015726915-57583-3604-0 (janus.apache.org) for framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.871172 3632 master.hpp:423] Adding task 0 with resources
cpus(*):2; mem(*):1024 on slave 2014-02-28-07:46:11-1015726915-57583-3604-0
(janus.apache.org)
I0228 07:46:11.871198 3632 master.cpp:2477] Launching task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 with resources cpus(*):2;
mem(*):1024 on slave 2014-02-28-07:46:11-1015726915-57583-3604-0
(janus.apache.org)
I0228 07:46:11.871299 3626 slave.cpp:632] Got assigned task 0 for framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.871341 3631 hierarchical_allocator_process.hpp:547] Framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 left disk(*):1024;
ports(*):[31000-32000] unused on slave
2014-02-28-07:46:11-1015726915-57583-3604-0
I0228 07:46:11.871464 3631 hierarchical_allocator_process.hpp:590] Framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 filtered slave
2014-02-28-07:46:11-1015726915-57583-3604-0 for 5secs
I0228 07:46:11.871492 3626 slave.cpp:741] Launching task 0 for framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.873729 3626 exec.cpp:131] Version: 0.19.0
I0228 07:46:11.873793 3628 exec.cpp:181] Executor started at:
executor(24)@67.195.138.60:57583 with pid 3604
I0228 07:46:11.873826 3626 slave.cpp:851] Queuing task '0' for executor
executor-1 of framework '2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.873864 3626 slave.cpp:365] Successfully attached file
'/tmp/GarbageCollectorIntegrationTest_Unschedule_9UeuPN/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000/executors/executor-1/runs/35ae823e-3f94-4118-a1bf-fceabd468ea7'
I0228 07:46:11.873890 3626 slave.cpp:1996] Monitoring executor 'executor-1' of
framework '2014-02-28-07:46:11-1015726915-57583-3604-0000' in container
'35ae823e-3f94-4118-a1bf-fceabd468ea7'
I0228 07:46:11.873955 3626 slave.cpp:1323] Got registration for executor
'executor-1' of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.874079 3626 slave.cpp:1441] Flushing queued task 0 for executor
'executor-1' of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.874097 3630 exec.cpp:205] Executor registered on slave
2014-02-28-07:46:11-1015726915-57583-3604-0
I0228 07:46:11.874861 3630 exec.cpp:217] Executor::registered took 9183ns
I0228 07:46:11.874912 3630 exec.cpp:292] Executor asked to run task '0'
I0228 07:46:11.874939 3630 exec.cpp:301] Executor::launchTask took 17606ns
I0228 07:46:11.875694 3630 exec.cpp:524] Executor sending status update
TASK_RUNNING (UUID: 201ca110-d7e6-4b64-a832-3ebcb9249831) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.875756 3630 slave.cpp:1650] Handling status update TASK_RUNNING
(UUID: 201ca110-d7e6-4b64-a832-3ebcb9249831) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 from
executor(24)@67.195.138.60:57583
I0228 07:46:11.875838 3632 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: 201ca110-d7e6-4b64-a832-3ebcb9249831) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.875854 3632 status_update_manager.cpp:499] Creating
StatusUpdate stream for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.875891 3632 status_update_manager.cpp:373] Forwarding status
update TASK_RUNNING (UUID: 201ca110-d7e6-4b64-a832-3ebcb9249831) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000 to
master@67.195.138.60:57583
I0228 07:46:11.875978 3630 master.cpp:2040] Status update TASK_RUNNING (UUID:
201ca110-d7e6-4b64-a832-3ebcb9249831) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 from
slave(53)@67.195.138.60:57583
I0228 07:46:11.875998 3628 slave.cpp:1770] Status update manager successfully
handled status update TASK_RUNNING (UUID: 201ca110-d7e6-4b64-a832-3ebcb9249831)
for task 0 of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.876024 3628 slave.cpp:1776] Sending acknowledgement for status
update TASK_RUNNING (UUID: 201ca110-d7e6-4b64-a832-3ebcb9249831) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000 to
executor(24)@67.195.138.60:57583
I0228 07:46:11.876112 3632 exec.cpp:338] Executor received status update
acknowledgement 201ca110-d7e6-4b64-a832-3ebcb9249831 for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.876183 3627 sched.cpp:619] Scheduler::statusUpdate took 33640ns
I0228 07:46:11.876476 3630 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 201ca110-d7e6-4b64-a832-3ebcb9249831) for task 0
of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.876559 3630 slave.cpp:2054] Executor 'executor-1' of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 has exited with status 0
I0228 07:46:11.877328 3630 slave.cpp:1650] Handling status update TASK_LOST
(UUID: 97e2468a-32a8-4615-b09b-476996b86463) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 from @0.0.0.0:0
I0228 07:46:11.877346 3630 slave.cpp:3149] Terminating task 0
I0228 07:46:11.877452 3628 status_update_manager.cpp:320] Received status
update TASK_LOST (UUID: 97e2468a-32a8-4615-b09b-476996b86463) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.877496 3630 slave.cpp:1263] Status update manager successfully
handled status update acknowledgement (UUID:
201ca110-d7e6-4b64-a832-3ebcb9249831) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.877503 3628 status_update_manager.cpp:373] Forwarding status
update TASK_LOST (UUID: 97e2468a-32a8-4615-b09b-476996b86463) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000 to
master@67.195.138.60:57583
I0228 07:46:11.877516 3631 master.cpp:2113] Executor executor-1 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 on slave
2014-02-28-07:46:11-1015726915-57583-3604-0 (janus.apache.org) has exited with
status 0
I0228 07:46:11.877653 3633 slave.cpp:1770] Status update manager successfully
handled status update TASK_LOST (UUID: 97e2468a-32a8-4615-b09b-476996b86463)
for task 0 of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.877707 3631 master.cpp:2040] Status update TASK_LOST (UUID:
97e2468a-32a8-4615-b09b-476996b86463) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 from
slave(53)@67.195.138.60:57583
I0228 07:46:11.877763 3632 sched.cpp:619] Scheduler::statusUpdate took 8910ns
I0228 07:46:11.877775 3631 master.hpp:441] Removing task 0 with resources
cpus(*):2; mem(*):1024 on slave 2014-02-28-07:46:11-1015726915-57583-3604-0
(janus.apache.org)
I0228 07:46:11.877887 3633 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: 97e2468a-32a8-4615-b09b-476996b86463) for task 0
of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.877897 3628 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:46:11-1015726915-57583-3604-0 from framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.877918 3633 status_update_manager.cpp:530] Cleaning up status
update stream for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.877990 3632 slave.cpp:1263] Status update manager successfully
handled status update acknowledgement (UUID:
97e2468a-32a8-4615-b09b-476996b86463) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.878003 3632 slave.cpp:3172] Completing task 0
I0228 07:46:11.878011 3632 slave.cpp:2193] Cleaning up executor 'executor-1'
of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.878072 3633 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_Unschedule_9UeuPN/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000/executors/executor-1/runs/35ae823e-3f94-4118-a1bf-fceabd468ea7'
for gc 6.99998985555556days in the future
I0228 07:46:11.878090 3632 slave.cpp:2268] Cleaning up framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.878115 3633 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_Unschedule_9UeuPN/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000/executors/executor-1'
for gc 6.99998985555556days in the future
I0228 07:46:11.878145 3632 status_update_manager.cpp:282] Closing status
update streams for framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:11.878159 3633 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_Unschedule_9UeuPN/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000'
for gc 6.99998985555556days in the future
I0228 07:46:12.148387 3631 hierarchical_allocator_process.hpp:752] Offering
disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave
2014-02-28-07:46:11-1015726915-57583-3604-0 to framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.148540 3631 hierarchical_allocator_process.hpp:688] Performed
allocation for 1 slaves in 203807ns
I0228 07:46:12.148599 3632 master.hpp:451] Adding offer
2014-02-28-07:46:11-1015726915-57583-3604-1 with resources disk(*):1024;
ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave
2014-02-28-07:46:11-1015726915-57583-3604-0 (janus.apache.org)
I0228 07:46:12.148648 3632 master.cpp:2308] Sending 1 offers to framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.148895 3626 sched.cpp:528] Scheduler::resourceOffers took
141846ns
I0228 07:46:12.149042 3632 master.hpp:461] Removing offer
2014-02-28-07:46:11-1015726915-57583-3604-1 with resources disk(*):1024;
ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave
2014-02-28-07:46:11-1015726915-57583-3604-0 (janus.apache.org)
I0228 07:46:12.149087 3632 master.cpp:1584] Processing reply for offers: [
2014-02-28-07:46:11-1015726915-57583-3604-1 ] on slave
2014-02-28-07:46:11-1015726915-57583-3604-0 (janus.apache.org) for framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.149142 3632 master.hpp:423] Adding task 0 with resources
cpus(*):2; mem(*):1024 on slave 2014-02-28-07:46:11-1015726915-57583-3604-0
(janus.apache.org)
I0228 07:46:12.149168 3632 master.cpp:2477] Launching task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 with resources cpus(*):2;
mem(*):1024 on slave 2014-02-28-07:46:11-1015726915-57583-3604-0
(janus.apache.org)
I0228 07:46:12.149232 3626 slave.cpp:632] Got assigned task 0 for framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.149289 3628 hierarchical_allocator_process.hpp:547] Framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 left disk(*):1024;
ports(*):[31000-32000] unused on slave
2014-02-28-07:46:11-1015726915-57583-3604-0
I0228 07:46:12.149353 3628 hierarchical_allocator_process.hpp:590] Framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 filtered slave
2014-02-28-07:46:11-1015726915-57583-3604-0 for 5secs
I0228 07:46:12.149451 3626 gc.cpp:84] Unscheduling
'/tmp/GarbageCollectorIntegrationTest_Unschedule_9UeuPN/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000'
from gc
I0228 07:46:12.149523 3629 slave.cpp:741] Launching task 0 for framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.151262 3629 exec.cpp:131] Version: 0.19.0
I0228 07:46:12.151309 3626 exec.cpp:181] Executor started at:
executor(25)@67.195.138.60:57583 with pid 3604
I0228 07:46:12.151346 3629 slave.cpp:851] Queuing task '0' for executor
executor-2 of framework '2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.151378 3629 slave.cpp:365] Successfully attached file
'/tmp/GarbageCollectorIntegrationTest_Unschedule_9UeuPN/slaves/2014-02-28-07:46:11-1015726915-57583-3604-0/frameworks/2014-02-28-07:46:11-1015726915-57583-3604-0000/executors/executor-2/runs/68c1f089-2ff9-41d5-959b-b8b3311ce246'
I0228 07:46:12.151401 3629 slave.cpp:1996] Monitoring executor 'executor-2' of
framework '2014-02-28-07:46:11-1015726915-57583-3604-0000' in container
'68c1f089-2ff9-41d5-959b-b8b3311ce246'
I0228 07:46:12.151473 3629 slave.cpp:1323] Got registration for executor
'executor-2' of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.151561 3629 slave.cpp:1441] Flushing queued task 0 for executor
'executor-2' of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.151571 3633 exec.cpp:205] Executor registered on slave
2014-02-28-07:46:11-1015726915-57583-3604-0
I0228 07:46:12.152320 3633 exec.cpp:217] Executor::registered took 8426ns
I0228 07:46:12.152362 3633 exec.cpp:292] Executor asked to run task '0'
I0228 07:46:12.152389 3633 exec.cpp:301] Executor::launchTask took 15881ns
I0228 07:46:12.153141 3633 exec.cpp:524] Executor sending status update
TASK_RUNNING (UUID: e323b665-1e30-484c-8303-fe6a57aee86d) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.153198 3633 slave.cpp:1650] Handling status update TASK_RUNNING
(UUID: e323b665-1e30-484c-8303-fe6a57aee86d) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 from
executor(25)@67.195.138.60:57583
I0228 07:46:12.153267 3629 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: e323b665-1e30-484c-8303-fe6a57aee86d) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.153280 3629 status_update_manager.cpp:499] Creating
StatusUpdate stream for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.153317 3629 status_update_manager.cpp:373] Forwarding status
update TASK_RUNNING (UUID: e323b665-1e30-484c-8303-fe6a57aee86d) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000 to
master@67.195.138.60:57583
I0228 07:46:12.153386 3629 slave.cpp:1770] Status update manager successfully
handled status update TASK_RUNNING (UUID: e323b665-1e30-484c-8303-fe6a57aee86d)
for task 0 of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.153394 3633 master.cpp:2040] Status update TASK_RUNNING (UUID:
e323b665-1e30-484c-8303-fe6a57aee86d) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 from
slave(53)@67.195.138.60:57583
I0228 07:46:12.153398 3629 slave.cpp:1776] Sending acknowledgement for status
update TASK_RUNNING (UUID: e323b665-1e30-484c-8303-fe6a57aee86d) for task 0 of
framework 2014-02-28-07:46:11-1015726915-57583-3604-0000 to
executor(25)@67.195.138.60:57583
I0228 07:46:12.153447 3633 sched.cpp:619] Scheduler::statusUpdate took 7614ns
I0228 07:46:12.153450 3629 exec.cpp:338] Executor received status update
acknowledgement e323b665-1e30-484c-8303-fe6a57aee86d for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.153542 3629 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: e323b665-1e30-484c-8303-fe6a57aee86d) for task 0
of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.153589 3629 slave.cpp:1263] Status update manager successfully
handled status update acknowledgement (UUID:
e323b665-1e30-484c-8303-fe6a57aee86d) for task 0 of framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.168264 3604 master.cpp:527] Master terminating
I0228 07:46:12.168278 3633 sched.cpp:730] Stopping framework
'2014-02-28-07:46:11-1015726915-57583-3604-0000'
I0228 07:46:12.168324 3604 master.hpp:441] Removing task 0 with resources
cpus(*):2; mem(*):1024 on slave 2014-02-28-07:46:11-1015726915-57583-3604-0
(janus.apache.org)
I0228 07:46:12.168453 3631 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:46:11-1015726915-57583-3604-0 from framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.168550 3632 slave.cpp:1851] master@67.195.138.60:57583 exited
W0228 07:46:12.168565 3632 slave.cpp:1854] Master disconnected! Waiting for a
new master to be elected
I0228 07:46:12.168830 3604 slave.cpp:294] Slave terminating
I0228 07:46:12.168853 3604 slave.cpp:1034] Asked to shut down framework
2014-02-28-07:46:11-1015726915-57583-3604-0000 by @0.0.0.0:0
I0228 07:46:12.168864 3604 slave.cpp:1059] Shutting down framework
2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.168877 3604 slave.cpp:2339] Shutting down executor 'executor-2'
of framework 2014-02-28-07:46:11-1015726915-57583-3604-0000
I0228 07:46:12.168988 3628 exec.cpp:378] Executor asked to shutdown
I0228 07:46:12.169014 3628 exec.cpp:393] Executor::shutdown took 11507ns
[ OK ] GarbageCollectorIntegrationTest.Unschedule (323 ms)
[----------] 5 tests from GarbageCollectorIntegrationTest (1595 ms total)
[----------] 2 tests from CpuIsolatorTest/0, where TypeParam =
mesos::internal::slave::PosixCpuIsolatorProcess
[ RUN ] CpuIsolatorTest/0.UserCpuUsage
I0228 07:46:12.214560 3604 launcher.cpp:120] Forked child with pid '4029' for
container 'user_cpu_usage'
[ OK ] CpuIsolatorTest/0.UserCpuUsage (1047 ms)
[ RUN ] CpuIsolatorTest/0.SystemCpuUsage
I0228 07:46:13.228904 3604 launcher.cpp:120] Forked child with pid '4033' for
container 'system_cpu_usage'
[ OK ] CpuIsolatorTest/0.SystemCpuUsage (1000 ms)
[----------] 2 tests from CpuIsolatorTest/0 (2047 ms total)
[----------] 1 test from LimitedCpuIsolatorTest
[ RUN ] LimitedCpuIsolatorTest.CgroupsCfs
F0228 07:46:14.216894 3604 isolator_tests.cpp:315] CHECK_SOME(isolator):
Failed to create isolator: Using cgroups requires root permissions
*** Check failure stack trace: ***
@ 0x2b3f4c68548d google::LogMessage::Fail()
@ 0x2b3f4c68758f google::LogMessage::SendToLog()
@ 0x2b3f4c68507c google::LogMessage::Flush()
@ 0x2b3f4c687e2d google::LogMessageFatal::~LogMessageFatal()
@ 0x4c9eed _CheckSome::~_CheckSome()
@ 0x635469 LimitedCpuIsolatorTest_CgroupsCfs_Test::TestBody()
@ 0x80e62d
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x8068b1 testing::Test::Run()
@ 0x806996 testing::TestInfo::Run()
@ 0x806ad7 testing::TestCase::Run()
@ 0x806e2e testing::internal::UnitTestImpl::RunAllTests()
@ 0x80e1ad
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x805f0e testing::UnitTest::Run()
@ 0x4a4b6b main
@ 0x2b3f4da3e76d (unknown)
@ 0x4ad051 (unknown)
make[3]: *** [check-local] Aborted
make[3]: Leaving directory
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make: *** [check-recursive] Error 1
Build step 'Execute shell' marked build as failure
